{"level":"warn","ts":"2026-02-26T01:35:32.81054Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T01:35:32.810647Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-26T01:35:32.81073Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T01:35:32.810748Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-26T01:35:32.810769Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-26T01:35:32.811626Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]} {"level":"info","ts":"2026-02-26T01:35:32.811755Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-26T01:35:32.81739Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.937725ms"} {"level":"info","ts":"2026-02-26T01:35:32.823872Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-02-26T01:35:32.823998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T01:35:32.824071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-02-26T01:35:32.824105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T01:35:32.824122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-02-26T01:35:32.82425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-02-26T01:35:32.829477Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T01:35:32.831404Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T01:35:32.832451Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-26T01:35:32.834434Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T01:35:32.834671Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T01:35:32.835551Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T01:35:32.835667Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T01:35:32.835737Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T01:35:32.836382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-02-26T01:35:32.837075Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-26T01:35:32.840739Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-26T01:35:32.840762Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-26T01:35:32.840929Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-26T01:35:32.841933Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T01:35:32.841979Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T01:35:33.224615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T01:35:33.224668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T01:35:33.224696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-02-26T01:35:33.22471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-02-26T01:35:33.224721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-26T01:35:33.224821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-02-26T01:35:33.22484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-26T01:35:33.226146Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T01:35:33.226376Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T01:35:33.227025Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:35:33.227198Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T01:35:33.227443Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T01:35:33.227467Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T01:35:33.231532Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-02-26T01:35:33.238585Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:35:33.238677Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:35:33.238724Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:35:33.239273Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T01:35:41.194224Z","caller":"traceutil/trace.go:171","msg":"trace[1100135754] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"116.103815ms","start":"2026-02-26T01:35:41.078098Z","end":"2026-02-26T01:35:41.194202Z","steps":["trace[1100135754] 'process raft request' (duration: 115.988022ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:35:41.550311Z","caller":"traceutil/trace.go:171","msg":"trace[1508263890] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:348; }","duration":"222.368721ms","start":"2026-02-26T01:35:41.327922Z","end":"2026-02-26T01:35:41.55029Z","steps":["trace[1508263890] 'read index received' (duration: 222.160996ms)","trace[1508263890] 'applied index is now lower than readState.Index' (duration: 206.715µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:35:41.5504Z","caller":"traceutil/trace.go:171","msg":"trace[995087379] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"256.643612ms","start":"2026-02-26T01:35:41.293711Z","end":"2026-02-26T01:35:41.550354Z","steps":["trace[995087379] 'process raft request' (duration: 256.450988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:35:41.550564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.629176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-26T01:35:41.550669Z","caller":"traceutil/trace.go:171","msg":"trace[707750853] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:341; }","duration":"222.77577ms","start":"2026-02-26T01:35:41.327877Z","end":"2026-02-26T01:35:41.550653Z","steps":["trace[707750853] 'agreement among raft nodes before linearized reading' (duration: 222.509204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:35:41.550643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.428442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-26T01:35:41.550857Z","caller":"traceutil/trace.go:171","msg":"trace[354467554] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:341; }","duration":"172.680679ms","start":"2026-02-26T01:35:41.378163Z","end":"2026-02-26T01:35:41.550844Z","steps":["trace[354467554] 'agreement among raft nodes before linearized reading' (duration: 172.428782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:35:41.550961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.244043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-26T01:35:41.551012Z","caller":"traceutil/trace.go:171","msg":"trace[1613711695] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:341; }","duration":"123.380416ms","start":"2026-02-26T01:35:41.427619Z","end":"2026-02-26T01:35:41.550999Z","steps":["trace[1613711695] 'agreement among raft nodes before linearized reading' (duration: 123.259923ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:35:56.86095Z","caller":"traceutil/trace.go:171","msg":"trace[1421074948] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"441.664901ms","start":"2026-02-26T01:35:56.419261Z","end":"2026-02-26T01:35:56.860926Z","steps":["trace[1421074948] 'process raft request' (duration: 441.513167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:35:56.861482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:35:56.419244Z","time spent":"441.781643ms","remote":"127.0.0.1:46804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:35:59.168722Z","caller":"traceutil/trace.go:171","msg":"trace[922788663] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"110.320571ms","start":"2026-02-26T01:35:59.058376Z","end":"2026-02-26T01:35:59.168697Z","steps":["trace[922788663] 'read index received' (duration: 110.106775ms)","trace[922788663] 'applied index is now lower than readState.Index' (duration: 212.946µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:35:59.168833Z","caller":"traceutil/trace.go:171","msg":"trace[930814745] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"293.113766ms","start":"2026-02-26T01:35:58.87571Z","end":"2026-02-26T01:35:59.168823Z","steps":["trace[930814745] 'process raft request' (duration: 292.86371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:35:59.169086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.7035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T01:35:59.169147Z","caller":"traceutil/trace.go:171","msg":"trace[442724621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:469; }","duration":"110.806572ms","start":"2026-02-26T01:35:59.058325Z","end":"2026-02-26T01:35:59.169132Z","steps":["trace[442724621] 'agreement among raft nodes before linearized reading' (duration: 110.72366ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:00.482602Z","caller":"traceutil/trace.go:171","msg":"trace[1456125061] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"136.787479ms","start":"2026-02-26T01:36:00.345797Z","end":"2026-02-26T01:36:00.482584Z","steps":["trace[1456125061] 'read index received' (duration: 136.43327ms)","trace[1456125061] 'applied index is now lower than readState.Index' (duration: 353.499µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:36:00.482681Z","caller":"traceutil/trace.go:171","msg":"trace[1428858913] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"471.734414ms","start":"2026-02-26T01:36:00.010904Z","end":"2026-02-26T01:36:00.482639Z","steps":["trace[1428858913] 'process raft request' (duration: 471.452537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:00.482768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.956664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:36:00.482809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:00.010878Z","time spent":"471.878967ms","remote":"127.0.0.1:46882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:36:00.482845Z","caller":"traceutil/trace.go:171","msg":"trace[688236947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:473; }","duration":"137.078656ms","start":"2026-02-26T01:36:00.345751Z","end":"2026-02-26T01:36:00.48283Z","steps":["trace[688236947] 'agreement among raft nodes before linearized reading' (duration: 136.922642ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:06.453964Z","caller":"traceutil/trace.go:171","msg":"trace[834349903] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"108.239811ms","start":"2026-02-26T01:36:06.345695Z","end":"2026-02-26T01:36:06.453935Z","steps":["trace[834349903] 'read index received' (duration: 108.076598ms)","trace[834349903] 'applied index is now lower than readState.Index' (duration: 161.233µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:36:06.454144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.424645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:36:06.454171Z","caller":"traceutil/trace.go:171","msg":"trace[1705583025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"108.508597ms","start":"2026-02-26T01:36:06.345654Z","end":"2026-02-26T01:36:06.454163Z","steps":["trace[1705583025] 'agreement among raft nodes before linearized reading' (duration: 108.409354ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:11.512935Z","caller":"traceutil/trace.go:171","msg":"trace[1461755017] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"222.827241ms","start":"2026-02-26T01:36:11.290075Z","end":"2026-02-26T01:36:11.512902Z","steps":["trace[1461755017] 'process raft request' (duration: 222.641966ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:11.536677Z","caller":"traceutil/trace.go:171","msg":"trace[2035973288] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:548; }","duration":"190.474385ms","start":"2026-02-26T01:36:11.346179Z","end":"2026-02-26T01:36:11.536653Z","steps":["trace[2035973288] 'read index received' (duration: 166.620676ms)","trace[2035973288] 'applied index is now lower than readState.Index' (duration: 23.852579ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:36:11.536791Z","caller":"traceutil/trace.go:171","msg":"trace[1773085592] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"246.044384ms","start":"2026-02-26T01:36:11.290678Z","end":"2026-02-26T01:36:11.536722Z","steps":["trace[1773085592] 'process raft request' (duration: 245.803028ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:11.536956Z","caller":"traceutil/trace.go:171","msg":"trace[1508080407] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"244.617171ms","start":"2026-02-26T01:36:11.292229Z","end":"2026-02-26T01:36:11.536847Z","steps":["trace[1508080407] 'process raft request' (duration: 244.385116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:11.537187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.913924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t7bhk\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-02-26T01:36:11.537043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.844313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:36:11.537273Z","caller":"traceutil/trace.go:171","msg":"trace[168263720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"191.096149ms","start":"2026-02-26T01:36:11.34615Z","end":"2026-02-26T01:36:11.537246Z","steps":["trace[168263720] 'agreement among raft nodes before linearized reading' (duration: 190.833583ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:11.537249Z","caller":"traceutil/trace.go:171","msg":"trace[218508590] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t7bhk; range_end:; response_count:1; response_revision:537; }","duration":"167.004386ms","start":"2026-02-26T01:36:11.37023Z","end":"2026-02-26T01:36:11.537235Z","steps":["trace[218508590] 'agreement among raft nodes before linearized reading' (duration: 166.768551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:12.651088Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.100047055s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T01:36:12.651635Z","caller":"traceutil/trace.go:171","msg":"trace[206758524] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.10066895s","start":"2026-02-26T01:36:11.550914Z","end":"2026-02-26T01:36:12.651583Z","steps":["trace[206758524] 'process raft request' (duration: 1.100480995s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:12.651778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:11.550888Z","time spent":"1.100810553s","remote":"127.0.0.1:46980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:36:13.846289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088705392172177,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T01:36:14.262541Z","caller":"traceutil/trace.go:171","msg":"trace[1736778873] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"967.9816ms","start":"2026-02-26T01:36:13.294536Z","end":"2026-02-26T01:36:14.262517Z","steps":["trace[1736778873] 'process raft request' (duration: 967.809566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:14.262702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:13.294519Z","time spent":"968.098183ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:36:14.263167Z","caller":"traceutil/trace.go:171","msg":"trace[960102818] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:554; }","duration":"917.19355ms","start":"2026-02-26T01:36:13.345958Z","end":"2026-02-26T01:36:14.263152Z","steps":["trace[960102818] 'read index received' (duration: 917.18832ms)","trace[960102818] 'applied index is now lower than readState.Index' (duration: 4.16µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:36:14.263408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.022051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T01:36:14.263449Z","caller":"traceutil/trace.go:171","msg":"trace[2046113250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"747.096733ms","start":"2026-02-26T01:36:13.516341Z","end":"2026-02-26T01:36:14.263438Z","steps":["trace[2046113250] 'agreement among raft nodes before linearized reading' (duration: 746.943379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:14.263481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:13.516326Z","time spent":"747.147803ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T01:36:14.263715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.036448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:36:14.263751Z","caller":"traceutil/trace.go:171","msg":"trace[1470164109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:539; }","duration":"723.09249ms","start":"2026-02-26T01:36:13.540649Z","end":"2026-02-26T01:36:14.263742Z","steps":["trace[1470164109] 'agreement among raft nodes before linearized reading' (duration: 723.003908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:14.263777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:13.540638Z","time spent":"723.13311ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T01:36:14.263595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.469326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:36:14.264009Z","caller":"traceutil/trace.go:171","msg":"trace[355344028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"918.08009ms","start":"2026-02-26T01:36:13.345903Z","end":"2026-02-26T01:36:14.263983Z","steps":["trace[355344028] 'agreement among raft nodes before linearized reading' (duration: 917.287282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:14.264244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:13.345882Z","time spent":"918.333087ms","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:36:14.264244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.724804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T01:36:14.265007Z","caller":"traceutil/trace.go:171","msg":"trace[1403426556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"724.514432ms","start":"2026-02-26T01:36:13.540465Z","end":"2026-02-26T01:36:14.264979Z","steps":["trace[1403426556] 'agreement among raft nodes before linearized reading' (duration: 723.331225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:14.265266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:13.540448Z","time spent":"724.791599ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T01:36:14.569471Z","caller":"traceutil/trace.go:171","msg":"trace[1150550159] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"301.755523ms","start":"2026-02-26T01:36:14.267693Z","end":"2026-02-26T01:36:14.569449Z","steps":["trace[1150550159] 'process raft request' (duration: 301.60796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:14.569623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:14.267675Z","time spent":"301.871786ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:36:14.570433Z","caller":"traceutil/trace.go:171","msg":"trace[1530362612] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:554; }","duration":"223.54722ms","start":"2026-02-26T01:36:14.346872Z","end":"2026-02-26T01:36:14.570419Z","steps":["trace[1530362612] 'read index received' (duration: 222.448854ms)","trace[1530362612] 'applied index is now lower than readState.Index' (duration: 1.097506ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:36:14.570557Z","caller":"traceutil/trace.go:171","msg":"trace[778712215] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"298.949227ms","start":"2026-02-26T01:36:14.271583Z","end":"2026-02-26T01:36:14.570532Z","steps":["trace[778712215] 'process raft request' (duration: 298.720361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:14.57062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.651533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:36:14.570759Z","caller":"traceutil/trace.go:171","msg":"trace[972500939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"223.922959ms","start":"2026-02-26T01:36:14.346823Z","end":"2026-02-26T01:36:14.570746Z","steps":["trace[972500939] 'agreement among raft nodes before linearized reading' (duration: 223.667803ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:14.571133Z","caller":"traceutil/trace.go:171","msg":"trace[633461641] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"302.359546ms","start":"2026-02-26T01:36:14.268Z","end":"2026-02-26T01:36:14.570359Z","steps":["trace[633461641] 'process raft request' (duration: 302.1059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:14.571257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:14.267981Z","time spent":"303.221387ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:36:18.536662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.373673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t7bhk\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-02-26T01:36:18.536765Z","caller":"traceutil/trace.go:171","msg":"trace[379552044] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t7bhk; range_end:; response_count:1; response_revision:561; }","duration":"129.531746ms","start":"2026-02-26T01:36:18.407213Z","end":"2026-02-26T01:36:18.536745Z","steps":["trace[379552044] 'range keys from in-memory index tree' (duration: 129.109356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:18.536876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.797645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:36:18.536999Z","caller":"traceutil/trace.go:171","msg":"trace[1184728557] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"129.569407ms","start":"2026-02-26T01:36:18.407412Z","end":"2026-02-26T01:36:18.536981Z","steps":["trace[1184728557] 'compare' (duration: 121.646631ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:18.716964Z","caller":"traceutil/trace.go:171","msg":"trace[4651987] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"131.957663ms","start":"2026-02-26T01:36:18.584983Z","end":"2026-02-26T01:36:18.716941Z","steps":["trace[4651987] 'read index received' (duration: 131.792829ms)","trace[4651987] 'applied index is now lower than readState.Index' (duration: 163.814µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:36:18.717203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.4054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-26T01:36:18.717222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.225279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:36:18.717264Z","caller":"traceutil/trace.go:171","msg":"trace[908897529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"131.514303ms","start":"2026-02-26T01:36:18.585729Z","end":"2026-02-26T01:36:18.717244Z","steps":["trace[908897529] 'agreement among raft nodes before linearized reading' (duration: 131.345729ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:18.717278Z","caller":"traceutil/trace.go:171","msg":"trace[53104202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:563; }","duration":"132.341512ms","start":"2026-02-26T01:36:18.584924Z","end":"2026-02-26T01:36:18.717266Z","steps":["trace[53104202] 'agreement among raft nodes before linearized reading' (duration: 132.133177ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:18.717006Z","caller":"traceutil/trace.go:171","msg":"trace[334125950] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"165.359976ms","start":"2026-02-26T01:36:18.55162Z","end":"2026-02-26T01:36:18.71698Z","steps":["trace[334125950] 'process raft request' (duration: 165.160741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:18.717487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.26953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T01:36:18.717625Z","caller":"traceutil/trace.go:171","msg":"trace[1789400906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:563; }","duration":"132.647579ms","start":"2026-02-26T01:36:18.584931Z","end":"2026-02-26T01:36:18.717579Z","steps":["trace[1789400906] 'agreement among raft nodes before linearized reading' (duration: 132.108266ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:20.62895Z","caller":"traceutil/trace.go:171","msg":"trace[288810272] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"305.953311ms","start":"2026-02-26T01:36:20.322973Z","end":"2026-02-26T01:36:20.628926Z","steps":["trace[288810272] 'read index received' (duration: 305.806688ms)","trace[288810272] 'applied index is now lower than readState.Index' (duration: 145.193µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:36:20.629071Z","caller":"traceutil/trace.go:171","msg":"trace[1147931415] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"330.418155ms","start":"2026-02-26T01:36:20.298536Z","end":"2026-02-26T01:36:20.628954Z","steps":["trace[1147931415] 'process raft request' (duration: 330.241301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:20.629203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.219297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T01:36:20.629222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:20.298513Z","time spent":"330.64219ms","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:36:20.629245Z","caller":"traceutil/trace.go:171","msg":"trace[1862727341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"306.302309ms","start":"2026-02-26T01:36:20.322932Z","end":"2026-02-26T01:36:20.629235Z","steps":["trace[1862727341] 'agreement among raft nodes before linearized reading' (duration: 306.105895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:20.62928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:20.322914Z","time spent":"306.3579ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T01:36:20.752926Z","caller":"traceutil/trace.go:171","msg":"trace[2036675280] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"119.581502ms","start":"2026-02-26T01:36:20.633324Z","end":"2026-02-26T01:36:20.752906Z","steps":["trace[2036675280] 'process raft request' (duration: 89.625711ms)","trace[2036675280] 'compare' (duration: 29.877489ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:36:22.2547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.959611ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-26T01:36:26.959403Z","caller":"traceutil/trace.go:171","msg":"trace[2136554132] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"112.269832ms","start":"2026-02-26T01:36:26.847109Z","end":"2026-02-26T01:36:26.959379Z","steps":["trace[2136554132] 'read index received' (duration: 112.161939ms)","trace[2136554132] 'applied index is now lower than readState.Index' (duration: 106.473µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:36:26.959518Z","caller":"traceutil/trace.go:171","msg":"trace[313527401] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"177.400128ms","start":"2026-02-26T01:36:26.782092Z","end":"2026-02-26T01:36:26.959492Z","steps":["trace[313527401] 'process raft request' (duration: 177.078701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:26.959697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.563938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:36:26.95976Z","caller":"traceutil/trace.go:171","msg":"trace[269815152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"112.670881ms","start":"2026-02-26T01:36:26.84707Z","end":"2026-02-26T01:36:26.959741Z","steps":["trace[269815152] 'agreement among raft nodes before linearized reading' (duration: 112.436086ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:26.964298Z","caller":"traceutil/trace.go:171","msg":"trace[1509227766] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"102.993274ms","start":"2026-02-26T01:36:26.861258Z","end":"2026-02-26T01:36:26.964251Z","steps":["trace[1509227766] 'process raft request' (duration: 102.786409ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:28.899822Z","caller":"traceutil/trace.go:171","msg":"trace[1736502650] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"430.260446ms","start":"2026-02-26T01:36:28.469536Z","end":"2026-02-26T01:36:28.899796Z","steps":["trace[1736502650] 'process raft request' (duration: 430.100122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:28.899953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:28.469504Z","time spent":"430.389669ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:36:28.902371Z","caller":"traceutil/trace.go:171","msg":"trace[1282947503] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"231.741673ms","start":"2026-02-26T01:36:28.670612Z","end":"2026-02-26T01:36:28.902354Z","steps":["trace[1282947503] 'process raft request' (duration: 231.601408ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:30.810371Z","caller":"traceutil/trace.go:171","msg":"trace[1493383639] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"102.911003ms","start":"2026-02-26T01:36:30.707405Z","end":"2026-02-26T01:36:30.810316Z","steps":["trace[1493383639] 'process raft request' (duration: 102.669948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:31.106974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.756585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:36:31.107054Z","caller":"traceutil/trace.go:171","msg":"trace[1227002917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:662; }","duration":"130.861437ms","start":"2026-02-26T01:36:30.976171Z","end":"2026-02-26T01:36:31.107032Z","steps":["trace[1227002917] 'range keys from in-memory index tree' (duration: 130.618631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:31.106974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.981049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-26T01:36:31.107117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.804073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T01:36:31.107159Z","caller":"traceutil/trace.go:171","msg":"trace[1763359296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:662; }","duration":"122.156624ms","start":"2026-02-26T01:36:30.984968Z","end":"2026-02-26T01:36:31.107125Z","steps":["trace[1763359296] 'range keys from in-memory index tree' (duration: 121.916577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:31.107155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.615631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T01:36:31.107196Z","caller":"traceutil/trace.go:171","msg":"trace[13473097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:662; }","duration":"135.933327ms","start":"2026-02-26T01:36:30.971243Z","end":"2026-02-26T01:36:31.107177Z","steps":["trace[13473097] 'range keys from in-memory index tree' (duration: 135.626769ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:31.107246Z","caller":"traceutil/trace.go:171","msg":"trace[1508024859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:662; }","duration":"121.740784ms","start":"2026-02-26T01:36:30.985486Z","end":"2026-02-26T01:36:31.107227Z","steps":["trace[1508024859] 'range keys from in-memory index tree' (duration: 121.457038ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:31.121801Z","caller":"traceutil/trace.go:171","msg":"trace[19933779] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"133.281305ms","start":"2026-02-26T01:36:30.988505Z","end":"2026-02-26T01:36:31.121786Z","steps":["trace[19933779] 'process raft request' (duration: 132.900686ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:31.999165Z","caller":"traceutil/trace.go:171","msg":"trace[316332562] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"132.018354ms","start":"2026-02-26T01:36:31.867121Z","end":"2026-02-26T01:36:31.99914Z","steps":["trace[316332562] 'read index received' (duration: 67.141894ms)","trace[316332562] 'applied index is now lower than readState.Index' (duration: 64.87549ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:36:31.999286Z","caller":"traceutil/trace.go:171","msg":"trace[2085604574] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"251.480215ms","start":"2026-02-26T01:36:31.74777Z","end":"2026-02-26T01:36:31.999251Z","steps":["trace[2085604574] 'process raft request' (duration: 186.508522ms)","trace[2085604574] 'compare' (duration: 64.220706ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:36:31.999406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.26956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-26T01:36:31.99945Z","caller":"traceutil/trace.go:171","msg":"trace[716902799] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:670; }","duration":"132.361662ms","start":"2026-02-26T01:36:31.867077Z","end":"2026-02-26T01:36:31.999439Z","steps":["trace[716902799] 'agreement among raft nodes before linearized reading' (duration: 132.199789ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:32.78663Z","caller":"traceutil/trace.go:171","msg":"trace[915539003] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"114.781831ms","start":"2026-02-26T01:36:32.671826Z","end":"2026-02-26T01:36:32.786608Z","steps":["trace[915539003] 'process raft request' (duration: 114.516515ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:34.78095Z","caller":"traceutil/trace.go:171","msg":"trace[246143191] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"241.762857ms","start":"2026-02-26T01:36:34.539163Z","end":"2026-02-26T01:36:34.780925Z","steps":["trace[246143191] 'process raft request' (duration: 241.637844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:35.736235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.557313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T01:36:35.736316Z","caller":"traceutil/trace.go:171","msg":"trace[554418652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:681; }","duration":"561.679556ms","start":"2026-02-26T01:36:35.174619Z","end":"2026-02-26T01:36:35.736299Z","steps":["trace[554418652] 'range keys from in-memory index tree' (duration: 561.41884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:35.736355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:35.174614Z","time spent":"561.731627ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T01:36:35.736379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.387414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:36:35.736465Z","caller":"traceutil/trace.go:171","msg":"trace[218644844] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:681; }","duration":"109.501567ms","start":"2026-02-26T01:36:35.626948Z","end":"2026-02-26T01:36:35.736449Z","steps":["trace[218644844] 'count revisions from in-memory index tree' (duration: 109.329273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:35.736621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.002953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T01:36:35.736723Z","caller":"traceutil/trace.go:171","msg":"trace[784953856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:681; }","duration":"562.131017ms","start":"2026-02-26T01:36:35.174565Z","end":"2026-02-26T01:36:35.736696Z","steps":["trace[784953856] 'range keys from in-memory index tree' (duration: 561.723477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:35.736778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:35.174558Z","time spent":"562.207469ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T01:36:35.736807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.112297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T01:36:35.736867Z","caller":"traceutil/trace.go:171","msg":"trace[886141049] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:681; }","duration":"565.201239ms","start":"2026-02-26T01:36:35.171655Z","end":"2026-02-26T01:36:35.736856Z","steps":["trace[886141049] 'range keys from in-memory index tree' (duration: 565.008285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:35.736895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:35.171636Z","time spent":"565.25222ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T01:36:35.737081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.943459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T01:36:35.737106Z","caller":"traceutil/trace.go:171","msg":"trace[1693473643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:681; }","duration":"563.9675ms","start":"2026-02-26T01:36:35.173131Z","end":"2026-02-26T01:36:35.737098Z","steps":["trace[1693473643] 'range keys from in-memory index tree' (duration: 563.852017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:35.737131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:35.173099Z","time spent":"564.025861ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T01:36:35.736663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.749949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:36:35.73729Z","caller":"traceutil/trace.go:171","msg":"trace[1151835036] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:681; }","duration":"664.407554ms","start":"2026-02-26T01:36:35.072864Z","end":"2026-02-26T01:36:35.737271Z","steps":["trace[1151835036] 'count revisions from in-memory index tree' (duration: 663.655077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:35.737402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:35.072836Z","time spent":"664.542337ms","remote":"127.0.0.1:47016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:36:36.598432Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088705392172479,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:36:37.098792Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088705392172479,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:36:37.598948Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088705392172479,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:36:37.886765Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.910291998s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T01:36:37.887205Z","caller":"traceutil/trace.go:171","msg":"trace[1610389971] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"1.910838921s","start":"2026-02-26T01:36:35.976347Z","end":"2026-02-26T01:36:37.887186Z","steps":["trace[1610389971] 'process raft request' (duration: 1.910695447s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:37.887308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:35.976321Z","time spent":"1.910924433s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:36:37.893349Z","caller":"traceutil/trace.go:171","msg":"trace[1273802883] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:705; }","duration":"1.795393395s","start":"2026-02-26T01:36:36.097941Z","end":"2026-02-26T01:36:37.893335Z","steps":["trace[1273802883] 'read index received' (duration: 1.789059536s)","trace[1273802883] 'applied index is now lower than readState.Index' (duration: 6.333149ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:36:37.893419Z","caller":"traceutil/trace.go:171","msg":"trace[1311388115] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"1.419630888s","start":"2026-02-26T01:36:36.473746Z","end":"2026-02-26T01:36:37.893377Z","steps":["trace[1311388115] 'process raft request' (duration: 1.419522655s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:37.893544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.7955974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-26T01:36:37.893556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:36.473728Z","time spent":"1.41976396s","remote":"127.0.0.1:50462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1238,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:36:37.893578Z","caller":"traceutil/trace.go:171","msg":"trace[1746449236] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:687; }","duration":"1.795667781s","start":"2026-02-26T01:36:36.097902Z","end":"2026-02-26T01:36:37.893569Z","steps":["trace[1746449236] 'agreement among raft nodes before linearized reading' (duration: 1.795503867s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:37.893608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:36.097885Z","time spent":"1.795717893s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T01:36:37.893614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.554343535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T01:36:37.893668Z","caller":"traceutil/trace.go:171","msg":"trace[901248812] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:687; }","duration":"1.554405026s","start":"2026-02-26T01:36:36.339249Z","end":"2026-02-26T01:36:37.893654Z","steps":["trace[901248812] 'agreement among raft nodes before linearized reading' (duration: 1.554238712s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:37.893705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:36.339206Z","time spent":"1.554493958s","remote":"127.0.0.1:46804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.209\" "} {"level":"warn","ts":"2026-02-26T01:36:37.893876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.094143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T01:36:37.893905Z","caller":"traceutil/trace.go:171","msg":"trace[99440205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:687; }","duration":"102.142564ms","start":"2026-02-26T01:36:37.791755Z","end":"2026-02-26T01:36:37.893898Z","steps":["trace[99440205] 'agreement among raft nodes before linearized reading' (duration: 102.060502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:37.893936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.511522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T01:36:37.893974Z","caller":"traceutil/trace.go:171","msg":"trace[2137243251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"102.586424ms","start":"2026-02-26T01:36:37.79138Z","end":"2026-02-26T01:36:37.893966Z","steps":["trace[2137243251] 'agreement among raft nodes before linearized reading' (duration: 102.504052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:37.894031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011183903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t7bhk\" ","response":"range_response_count:1 size:18713"} {"level":"info","ts":"2026-02-26T01:36:37.894096Z","caller":"traceutil/trace.go:171","msg":"trace[1914165584] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t7bhk; range_end:; response_count:1; response_revision:687; }","duration":"1.011275405s","start":"2026-02-26T01:36:36.882813Z","end":"2026-02-26T01:36:37.894088Z","steps":["trace[1914165584] 'agreement among raft nodes before linearized reading' (duration: 1.011172773s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:37.89409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.146153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-26T01:36:37.894118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:36.882797Z","time spent":"1.011315796s","remote":"127.0.0.1:46980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18735,"request content":"key:\"/registry/pods/kube-system/cilium-t7bhk\" "} {"level":"info","ts":"2026-02-26T01:36:37.894129Z","caller":"traceutil/trace.go:171","msg":"trace[1889168353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:687; }","duration":"125.218245ms","start":"2026-02-26T01:36:37.768899Z","end":"2026-02-26T01:36:37.894117Z","steps":["trace[1889168353] 'agreement among raft nodes before linearized reading' (duration: 125.063281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:37.894145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.683806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T01:36:37.894167Z","caller":"traceutil/trace.go:171","msg":"trace[1017071721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:687; }","duration":"102.720907ms","start":"2026-02-26T01:36:37.79144Z","end":"2026-02-26T01:36:37.894161Z","steps":["trace[1017071721] 'agreement among raft nodes before linearized reading' (duration: 102.658575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:37.893937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.252873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:36:37.894269Z","caller":"traceutil/trace.go:171","msg":"trace[1549762767] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:687; }","duration":"979.622122ms","start":"2026-02-26T01:36:36.91464Z","end":"2026-02-26T01:36:37.894263Z","steps":["trace[1549762767] 'agreement among raft nodes before linearized reading' (duration: 979.228223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:37.89429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.547877912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:36:37.894322Z","caller":"traceutil/trace.go:171","msg":"trace[680052902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"1.547918323s","start":"2026-02-26T01:36:36.346395Z","end":"2026-02-26T01:36:37.894313Z","steps":["trace[680052902] 'agreement among raft nodes before linearized reading' (duration: 1.547870562s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:37.894359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:36.346389Z","time spent":"1.547961394s","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:36:37.894296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:36.914627Z","time spent":"979.659743ms","remote":"127.0.0.1:47008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-02-26T01:36:48.21346Z","caller":"traceutil/trace.go:171","msg":"trace[2114435448] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"248.846984ms","start":"2026-02-26T01:36:47.964586Z","end":"2026-02-26T01:36:48.213433Z","steps":["trace[2114435448] 'read index received' (duration: 248.566067ms)","trace[2114435448] 'applied index is now lower than readState.Index' (duration: 279.877µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:36:48.213503Z","caller":"traceutil/trace.go:171","msg":"trace[2132140759] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"316.397826ms","start":"2026-02-26T01:36:47.897077Z","end":"2026-02-26T01:36:48.213475Z","steps":["trace[2132140759] 'process raft request' (duration: 316.122909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:48.213622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.086629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-26T01:36:48.213646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:47.897055Z","time spent":"316.527579ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:36:48.213668Z","caller":"traceutil/trace.go:171","msg":"trace[1738259984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"249.152751ms","start":"2026-02-26T01:36:47.96449Z","end":"2026-02-26T01:36:48.213643Z","steps":["trace[1738259984] 'agreement among raft nodes before linearized reading' (duration: 249.042638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:48.213674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.259506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:36:48.213725Z","caller":"traceutil/trace.go:171","msg":"trace[345045957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:763; }","duration":"247.351648ms","start":"2026-02-26T01:36:47.966361Z","end":"2026-02-26T01:36:48.213713Z","steps":["trace[345045957] 'agreement among raft nodes before linearized reading' (duration: 247.217075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:48.213724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.659575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-26T01:36:48.213766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.420993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-26T01:36:48.213807Z","caller":"traceutil/trace.go:171","msg":"trace[552010317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"245.479904ms","start":"2026-02-26T01:36:47.968319Z","end":"2026-02-26T01:36:48.213799Z","steps":["trace[552010317] 'agreement among raft nodes before linearized reading' (duration: 245.387042ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:48.213806Z","caller":"traceutil/trace.go:171","msg":"trace[2007545735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"215.776668ms","start":"2026-02-26T01:36:47.998009Z","end":"2026-02-26T01:36:48.213786Z","steps":["trace[2007545735] 'agreement among raft nodes before linearized reading' (duration: 215.586244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:48.213797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.737276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T01:36:48.213868Z","caller":"traceutil/trace.go:171","msg":"trace[968281440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"215.824818ms","start":"2026-02-26T01:36:47.998027Z","end":"2026-02-26T01:36:48.213852Z","steps":["trace[968281440] 'agreement among raft nodes before linearized reading' (duration: 215.608543ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:48.402491Z","caller":"traceutil/trace.go:171","msg":"trace[1312491232] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"185.323634ms","start":"2026-02-26T01:36:48.217146Z","end":"2026-02-26T01:36:48.40247Z","steps":["trace[1312491232] 'process raft request' (duration: 153.763604ms)","trace[1312491232] 'compare' (duration: 31.464537ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:36:48.453047Z","caller":"traceutil/trace.go:171","msg":"trace[368202043] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"235.660264ms","start":"2026-02-26T01:36:48.217367Z","end":"2026-02-26T01:36:48.453027Z","steps":["trace[368202043] 'process raft request' (duration: 235.603262ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:48.453108Z","caller":"traceutil/trace.go:171","msg":"trace[1438503165] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"235.700004ms","start":"2026-02-26T01:36:48.217362Z","end":"2026-02-26T01:36:48.453062Z","steps":["trace[1438503165] 'process raft request' (duration: 235.50635ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:48.453317Z","caller":"traceutil/trace.go:171","msg":"trace[762826251] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:787; }","duration":"106.927528ms","start":"2026-02-26T01:36:48.346361Z","end":"2026-02-26T01:36:48.453288Z","steps":["trace[762826251] 'read index received' (duration: 24.557426ms)","trace[762826251] 'applied index is now lower than readState.Index' (duration: 82.368862ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:36:48.453355Z","caller":"traceutil/trace.go:171","msg":"trace[1366715728] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"233.670078ms","start":"2026-02-26T01:36:48.219674Z","end":"2026-02-26T01:36:48.453344Z","steps":["trace[1366715728] 'process raft request' (duration: 233.510964ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:48.453365Z","caller":"traceutil/trace.go:171","msg":"trace[1488457634] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"235.348347ms","start":"2026-02-26T01:36:48.218001Z","end":"2026-02-26T01:36:48.45335Z","steps":["trace[1488457634] 'process raft request' (duration: 235.000378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:48.453453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.080411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:36:48.453499Z","caller":"traceutil/trace.go:171","msg":"trace[1865919808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"107.168093ms","start":"2026-02-26T01:36:48.346317Z","end":"2026-02-26T01:36:48.453485Z","steps":["trace[1865919808] 'agreement among raft nodes before linearized reading' (duration: 107.068351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:50.9572Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088705392172656,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T01:36:51.180981Z","caller":"traceutil/trace.go:171","msg":"trace[1572542042] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"723.96487ms","start":"2026-02-26T01:36:50.456992Z","end":"2026-02-26T01:36:51.180957Z","steps":["trace[1572542042] 'read index received' (duration: 723.781276ms)","trace[1572542042] 'applied index is now lower than readState.Index' (duration: 182.554µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:36:51.18117Z","caller":"traceutil/trace.go:171","msg":"trace[1479404652] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"770.466461ms","start":"2026-02-26T01:36:50.410635Z","end":"2026-02-26T01:36:51.181101Z","steps":["trace[1479404652] 'process raft request' (duration: 770.176794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:51.181276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.856027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-26T01:36:51.181271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.259417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T01:36:51.181316Z","caller":"traceutil/trace.go:171","msg":"trace[1922238334] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:770; }","duration":"723.953969ms","start":"2026-02-26T01:36:50.457351Z","end":"2026-02-26T01:36:51.181305Z","steps":["trace[1922238334] 'agreement among raft nodes before linearized reading' (duration: 723.803926ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:51.181342Z","caller":"traceutil/trace.go:171","msg":"trace[145070842] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:770; }","duration":"724.40723ms","start":"2026-02-26T01:36:50.456918Z","end":"2026-02-26T01:36:51.181326Z","steps":["trace[145070842] 'agreement among raft nodes before linearized reading' (duration: 724.194285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:51.181356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:50.457331Z","time spent":"724.017261ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T01:36:51.181382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:50.456887Z","time spent":"724.488682ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T01:36:51.181476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:50.410624Z","time spent":"770.689035ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:36:51.18152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.021021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T01:36:51.181523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.462368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:36:51.181544Z","caller":"traceutil/trace.go:171","msg":"trace[1445719974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:770; }","duration":"724.053752ms","start":"2026-02-26T01:36:50.457483Z","end":"2026-02-26T01:36:51.181537Z","steps":["trace[1445719974] 'agreement among raft nodes before linearized reading' (duration: 723.98532ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:51.181554Z","caller":"traceutil/trace.go:171","msg":"trace[770945419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:770; }","duration":"723.501259ms","start":"2026-02-26T01:36:50.458042Z","end":"2026-02-26T01:36:51.181543Z","steps":["trace[770945419] 'agreement among raft nodes before linearized reading' (duration: 723.423377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:51.181568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:50.457479Z","time spent":"724.084992ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T01:36:51.181583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:50.458036Z","time spent":"723.54007ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T01:36:51.316869Z","caller":"traceutil/trace.go:171","msg":"trace[638696797] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"131.227017ms","start":"2026-02-26T01:36:51.185605Z","end":"2026-02-26T01:36:51.316832Z","steps":["trace[638696797] 'process raft request' (duration: 56.672038ms)","trace[638696797] 'compare' (duration: 74.396455ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:36:51.52251Z","caller":"traceutil/trace.go:171","msg":"trace[1200615199] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"335.629448ms","start":"2026-02-26T01:36:51.186861Z","end":"2026-02-26T01:36:51.52249Z","steps":["trace[1200615199] 'process raft request' (duration: 335.550286ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:51.522516Z","caller":"traceutil/trace.go:171","msg":"trace[1209207612] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"335.719709ms","start":"2026-02-26T01:36:51.186758Z","end":"2026-02-26T01:36:51.522478Z","steps":["trace[1209207612] 'process raft request' (duration: 324.270741ms)","trace[1209207612] 'compare' (duration: 11.278964ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:36:51.52266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:51.186844Z","time spent":"335.73177ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:36:51.522662Z","caller":"traceutil/trace.go:171","msg":"trace[1624094644] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:795; }","duration":"175.547524ms","start":"2026-02-26T01:36:51.347093Z","end":"2026-02-26T01:36:51.522641Z","steps":["trace[1624094644] 'read index received' (duration: 163.907401ms)","trace[1624094644] 'applied index is now lower than readState.Index' (duration: 11.638493ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:36:51.522699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:51.186735Z","time spent":"335.861663ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:36:51.522763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.656627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:36:51.522794Z","caller":"traceutil/trace.go:171","msg":"trace[74281930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:774; }","duration":"175.743118ms","start":"2026-02-26T01:36:51.347041Z","end":"2026-02-26T01:36:51.522784Z","steps":["trace[74281930] 'agreement among raft nodes before linearized reading' (duration: 175.666827ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:51.522844Z","caller":"traceutil/trace.go:171","msg":"trace[232164366] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"335.201636ms","start":"2026-02-26T01:36:51.187569Z","end":"2026-02-26T01:36:51.522771Z","steps":["trace[232164366] 'process raft request' (duration: 334.870098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:51.523104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:51.187551Z","time spent":"335.400621ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:36:59.421137Z","caller":"traceutil/trace.go:171","msg":"trace[803676343] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"207.214388ms","start":"2026-02-26T01:36:59.213889Z","end":"2026-02-26T01:36:59.421103Z","steps":["trace[803676343] 'process raft request' (duration: 205.803764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:00.057578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.006471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:37:00.057758Z","caller":"traceutil/trace.go:171","msg":"trace[1906216498] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"507.366593ms","start":"2026-02-26T01:36:59.550369Z","end":"2026-02-26T01:37:00.057736Z","steps":["trace[1906216498] 'read index received' (duration: 40.691µs)","trace[1906216498] 'applied index is now lower than readState.Index' (duration: 507.324202ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:37:00.057794Z","caller":"traceutil/trace.go:171","msg":"trace[323400725] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"630.201852ms","start":"2026-02-26T01:36:59.427557Z","end":"2026-02-26T01:37:00.057759Z","steps":["trace[323400725] 'process raft request' (duration: 33.895014ms)","trace[323400725] 'compare' (duration: 595.55241ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:00.057982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.610509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:37:00.058044Z","caller":"traceutil/trace.go:171","msg":"trace[512127209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"507.708421ms","start":"2026-02-26T01:36:59.550318Z","end":"2026-02-26T01:37:00.058027Z","steps":["trace[512127209] 'agreement among raft nodes before linearized reading' (duration: 507.506776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:00.05809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:59.550299Z","time spent":"507.779402ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T01:37:00.058201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:59.427535Z","time spent":"630.353776ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:00.058762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.623044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-26T01:37:00.058816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.273769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:37:00.058843Z","caller":"traceutil/trace.go:171","msg":"trace[1707781202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"494.703455ms","start":"2026-02-26T01:36:59.564113Z","end":"2026-02-26T01:37:00.058816Z","steps":["trace[1707781202] 'agreement among raft nodes before linearized reading' (duration: 494.533131ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:00.058858Z","caller":"traceutil/trace.go:171","msg":"trace[488407552] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"260.440195ms","start":"2026-02-26T01:36:59.798399Z","end":"2026-02-26T01:37:00.058839Z","steps":["trace[488407552] 'process raft request' (duration: 260.165118ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:00.058864Z","caller":"traceutil/trace.go:171","msg":"trace[1120735639] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:799; }","duration":"431.3218ms","start":"2026-02-26T01:36:59.627527Z","end":"2026-02-26T01:37:00.058848Z","steps":["trace[1120735639] 'agreement among raft nodes before linearized reading' (duration: 431.219858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:00.05889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:59.564099Z","time spent":"494.778977ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T01:37:00.059146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.921151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T01:37:00.059381Z","caller":"traceutil/trace.go:171","msg":"trace[1179070418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"495.177387ms","start":"2026-02-26T01:36:59.564188Z","end":"2026-02-26T01:37:00.059365Z","steps":["trace[1179070418] 'agreement among raft nodes before linearized reading' (duration: 494.827048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:00.059428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:59.564178Z","time spent":"495.238939ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T01:37:00.059723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:59.627511Z","time spent":"431.381952ms","remote":"127.0.0.1:47146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-26T01:37:00.373256Z","caller":"traceutil/trace.go:171","msg":"trace[1757680902] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"310.336815ms","start":"2026-02-26T01:37:00.062895Z","end":"2026-02-26T01:37:00.373232Z","steps":["trace[1757680902] 'process raft request' (duration: 304.371444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:00.373405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:00.062881Z","time spent":"310.450527ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:37:00.398466Z","caller":"traceutil/trace.go:171","msg":"trace[2044778538] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:826; }","duration":"145.021629ms","start":"2026-02-26T01:37:00.253426Z","end":"2026-02-26T01:37:00.398448Z","steps":["trace[2044778538] 'read index received' (duration: 113.872208ms)","trace[2044778538] 'applied index is now lower than readState.Index' (duration: 31.148201ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:00.398583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.147642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:00.39861Z","caller":"traceutil/trace.go:171","msg":"trace[1503475035] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:802; }","duration":"145.232425ms","start":"2026-02-26T01:37:00.25337Z","end":"2026-02-26T01:37:00.398602Z","steps":["trace[1503475035] 'agreement among raft nodes before linearized reading' (duration: 145.146453ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:00.398607Z","caller":"traceutil/trace.go:171","msg":"trace[1122004509] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"335.345331ms","start":"2026-02-26T01:37:00.063227Z","end":"2026-02-26T01:37:00.398572Z","steps":["trace[1122004509] 'process raft request' (duration: 335.090515ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:00.398786Z","caller":"traceutil/trace.go:171","msg":"trace[763603352] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"335.444324ms","start":"2026-02-26T01:37:00.063334Z","end":"2026-02-26T01:37:00.398779Z","steps":["trace[763603352] 'process raft request' (duration: 335.075265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:00.398784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:00.063211Z","time spent":"335.485784ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:00.398858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:00.063328Z","time spent":"335.478174ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:37:02.58956Z","caller":"traceutil/trace.go:171","msg":"trace[2096442641] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"208.674812ms","start":"2026-02-26T01:37:02.380859Z","end":"2026-02-26T01:37:02.589533Z","steps":["trace[2096442641] 'process raft request' (duration: 208.455627ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:02.589468Z","caller":"traceutil/trace.go:171","msg":"trace[1865958747] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"187.007484ms","start":"2026-02-26T01:37:02.402444Z","end":"2026-02-26T01:37:02.589451Z","steps":["trace[1865958747] 'read index received' (duration: 186.86465ms)","trace[1865958747] 'applied index is now lower than readState.Index' (duration: 142.194µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:02.589659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.209638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:37:02.589695Z","caller":"traceutil/trace.go:171","msg":"trace[531498498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"187.29086ms","start":"2026-02-26T01:37:02.402396Z","end":"2026-02-26T01:37:02.589687Z","steps":["trace[531498498] 'agreement among raft nodes before linearized reading' (duration: 187.164967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:02.589785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.099055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T01:37:02.589802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.090817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:02.589804Z","caller":"traceutil/trace.go:171","msg":"trace[1860067862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"187.150287ms","start":"2026-02-26T01:37:02.402647Z","end":"2026-02-26T01:37:02.589797Z","steps":["trace[1860067862] 'agreement among raft nodes before linearized reading' (duration: 187.093235ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:02.589816Z","caller":"traceutil/trace.go:171","msg":"trace[1797672133] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:805; }","duration":"138.141358ms","start":"2026-02-26T01:37:02.45167Z","end":"2026-02-26T01:37:02.589811Z","steps":["trace[1797672133] 'agreement among raft nodes before linearized reading' (duration: 138.110407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:03.256362Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088705392172751,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T01:37:03.342979Z","caller":"traceutil/trace.go:171","msg":"trace[1735543362] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"747.538543ms","start":"2026-02-26T01:37:02.595382Z","end":"2026-02-26T01:37:03.342921Z","steps":["trace[1735543362] 'process raft request' (duration: 747.334778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:03.343295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:02.595363Z","time spent":"747.83235ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:37:03.468943Z","caller":"traceutil/trace.go:171","msg":"trace[952886575] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:833; }","duration":"713.157137ms","start":"2026-02-26T01:37:02.755756Z","end":"2026-02-26T01:37:03.468913Z","steps":["trace[952886575] 'read index received' (duration: 587.00955ms)","trace[952886575] 'applied index is now lower than readState.Index' (duration: 126.146407ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:37:03.469042Z","caller":"traceutil/trace.go:171","msg":"trace[477660704] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"873.430513ms","start":"2026-02-26T01:37:02.595581Z","end":"2026-02-26T01:37:03.469011Z","steps":["trace[477660704] 'process raft request' (duration: 806.550755ms)","trace[477660704] 'compare' (duration: 66.624002ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:03.469158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.396172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:37:03.469214Z","caller":"traceutil/trace.go:171","msg":"trace[1914809145] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:807; }","duration":"713.496125ms","start":"2026-02-26T01:37:02.755706Z","end":"2026-02-26T01:37:03.469202Z","steps":["trace[1914809145] 'agreement among raft nodes before linearized reading' (duration: 713.359512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:03.469205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:02.595561Z","time spent":"873.565957ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:03.469311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:02.755681Z","time spent":"713.564347ms","remote":"127.0.0.1:47290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-02-26T01:37:10.74646Z","caller":"traceutil/trace.go:171","msg":"trace[486315667] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"107.91956ms","start":"2026-02-26T01:37:10.638518Z","end":"2026-02-26T01:37:10.746438Z","steps":["trace[486315667] 'process raft request' (duration: 107.778926ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:11.728104Z","caller":"traceutil/trace.go:171","msg":"trace[1813630508] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"107.620704ms","start":"2026-02-26T01:37:11.620458Z","end":"2026-02-26T01:37:11.728079Z","steps":["trace[1813630508] 'process raft request' (duration: 96.045462ms)","trace[1813630508] 'compare' (duration: 11.455199ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:37:11.768848Z","caller":"traceutil/trace.go:171","msg":"trace[1743175782] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"130.980889ms","start":"2026-02-26T01:37:11.637845Z","end":"2026-02-26T01:37:11.768826Z","steps":["trace[1743175782] 'process raft request' (duration: 130.943259ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:11.768871Z","caller":"traceutil/trace.go:171","msg":"trace[1802409145] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"131.280267ms","start":"2026-02-26T01:37:11.637575Z","end":"2026-02-26T01:37:11.768855Z","steps":["trace[1802409145] 'process raft request' (duration: 131.100063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:14.844114Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088705392172927,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T01:37:14.998835Z","caller":"traceutil/trace.go:171","msg":"trace[554027725] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:919; }","duration":"655.34684ms","start":"2026-02-26T01:37:14.343468Z","end":"2026-02-26T01:37:14.998815Z","steps":["trace[554027725] 'read index received' (duration: 655.167826ms)","trace[554027725] 'applied index is now lower than readState.Index' (duration: 178.244µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:37:14.998919Z","caller":"traceutil/trace.go:171","msg":"trace[1583748113] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"745.267289ms","start":"2026-02-26T01:37:14.253645Z","end":"2026-02-26T01:37:14.998912Z","steps":["trace[1583748113] 'process raft request' (duration: 745.054244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:14.998994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:14.253629Z","time spent":"745.308461ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:14.999101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.632308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:14.999123Z","caller":"traceutil/trace.go:171","msg":"trace[1308665551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:889; }","duration":"655.682669ms","start":"2026-02-26T01:37:14.343434Z","end":"2026-02-26T01:37:14.999117Z","steps":["trace[1308665551] 'agreement among raft nodes before linearized reading' (duration: 655.646159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:14.999143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:14.343411Z","time spent":"655.72832ms","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:37:14.999294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.632303ms","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":"info","ts":"2026-02-26T01:37:14.999316Z","caller":"traceutil/trace.go:171","msg":"trace[1738405054] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:889; }","duration":"354.692645ms","start":"2026-02-26T01:37:14.644618Z","end":"2026-02-26T01:37:14.999311Z","steps":["trace[1738405054] 'agreement among raft nodes before linearized reading' (duration: 354.658264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:14.999333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:14.644597Z","time spent":"354.731345ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:37:14.999432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.46998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:37:14.999448Z","caller":"traceutil/trace.go:171","msg":"trace[1941977994] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:889; }","duration":"209.507481ms","start":"2026-02-26T01:37:14.789936Z","end":"2026-02-26T01:37:14.999443Z","steps":["trace[1941977994] 'agreement among raft nodes before linearized reading' (duration: 209.47686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:14.999557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.342351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:37:14.99962Z","caller":"traceutil/trace.go:171","msg":"trace[1780376588] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:889; }","duration":"239.409893ms","start":"2026-02-26T01:37:14.760192Z","end":"2026-02-26T01:37:14.999602Z","steps":["trace[1780376588] 'agreement among raft nodes before linearized reading' (duration: 239.245319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:17.509488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088705392172952,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:37:18.009897Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088705392172952,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:37:18.285195Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.277593739s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T01:37:18.285468Z","caller":"traceutil/trace.go:171","msg":"trace[1991963869] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"1.276946984s","start":"2026-02-26T01:37:17.008504Z","end":"2026-02-26T01:37:18.285451Z","steps":["trace[1991963869] 'read index received' (duration: 1.2767905s)","trace[1991963869] 'applied index is now lower than readState.Index' (duration: 155.934µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:37:18.285528Z","caller":"traceutil/trace.go:171","msg":"trace[960473290] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"1.277997978s","start":"2026-02-26T01:37:17.007525Z","end":"2026-02-26T01:37:18.285523Z","steps":["trace[960473290] 'process raft request' (duration: 1.277823314s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:18.285598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:17.007513Z","time spent":"1.278031378s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:18.285769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.842225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:18.285878Z","caller":"traceutil/trace.go:171","msg":"trace[1362975233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"934.986358ms","start":"2026-02-26T01:37:17.350873Z","end":"2026-02-26T01:37:18.28586Z","steps":["trace[1362975233] 'agreement among raft nodes before linearized reading' (duration: 934.840514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:18.285927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.504826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-26T01:37:18.285952Z","caller":"traceutil/trace.go:171","msg":"trace[959907007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:895; }","duration":"632.567448ms","start":"2026-02-26T01:37:17.653379Z","end":"2026-02-26T01:37:18.285946Z","steps":["trace[959907007] 'agreement among raft nodes before linearized reading' (duration: 632.486806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:18.28597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:17.653329Z","time spent":"632.63675ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T01:37:18.286008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:17.350852Z","time spent":"935.08559ms","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:37:18.286144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.739574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:37:18.286163Z","caller":"traceutil/trace.go:171","msg":"trace[1261861520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"652.828996ms","start":"2026-02-26T01:37:17.63333Z","end":"2026-02-26T01:37:18.286159Z","steps":["trace[1261861520] 'agreement among raft nodes before linearized reading' (duration: 652.784105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:18.286191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:17.633306Z","time spent":"652.868277ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T01:37:18.286334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.061231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T01:37:18.286356Z","caller":"traceutil/trace.go:171","msg":"trace[2056191171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:895; }","duration":"708.118522ms","start":"2026-02-26T01:37:17.578233Z","end":"2026-02-26T01:37:18.286351Z","steps":["trace[2056191171] 'agreement among raft nodes before linearized reading' (duration: 708.066561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:18.286369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:17.578215Z","time spent":"708.151513ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T01:37:18.286058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277565388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:37:18.286488Z","caller":"traceutil/trace.go:171","msg":"trace[401747923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:895; }","duration":"1.278024079s","start":"2026-02-26T01:37:17.008451Z","end":"2026-02-26T01:37:18.286475Z","steps":["trace[401747923] 'agreement among raft nodes before linearized reading' (duration: 1.277577759s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:18.28658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:17.00843Z","time spent":"1.278136821s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T01:37:18.568223Z","caller":"traceutil/trace.go:171","msg":"trace[595237266] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"278.834145ms","start":"2026-02-26T01:37:18.28936Z","end":"2026-02-26T01:37:18.568194Z","steps":["trace[595237266] 'process raft request' (duration: 278.636391ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:18.834988Z","caller":"traceutil/trace.go:171","msg":"trace[1177130145] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"544.007717ms","start":"2026-02-26T01:37:18.290948Z","end":"2026-02-26T01:37:18.834956Z","steps":["trace[1177130145] 'process raft request' (duration: 543.544036ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:18.835021Z","caller":"traceutil/trace.go:171","msg":"trace[1431451529] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"543.545736ms","start":"2026-02-26T01:37:18.29146Z","end":"2026-02-26T01:37:18.835005Z","steps":["trace[1431451529] 'process raft request' (duration: 543.28403ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:18.835099Z","caller":"traceutil/trace.go:171","msg":"trace[209392025] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"543.626908ms","start":"2026-02-26T01:37:18.291418Z","end":"2026-02-26T01:37:18.835045Z","steps":["trace[209392025] 'process raft request' (duration: 543.28299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:18.835176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:18.290935Z","time spent":"544.15412ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:18.835178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:18.291441Z","time spent":"543.657218ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:18.835391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:18.291388Z","time spent":"543.822062ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:37:18.835452Z","caller":"traceutil/trace.go:171","msg":"trace[282417573] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"290.729016ms","start":"2026-02-26T01:37:18.544707Z","end":"2026-02-26T01:37:18.835436Z","steps":["trace[282417573] 'process raft request' (duration: 290.214694ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:23.016352Z","caller":"traceutil/trace.go:171","msg":"trace[1844258628] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"117.280088ms","start":"2026-02-26T01:37:22.899056Z","end":"2026-02-26T01:37:23.016336Z","steps":["trace[1844258628] 'process raft request' (duration: 117.186976ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:30.789178Z","caller":"traceutil/trace.go:171","msg":"trace[2140599091] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"408.742513ms","start":"2026-02-26T01:37:30.380405Z","end":"2026-02-26T01:37:30.789148Z","steps":["trace[2140599091] 'process raft request' (duration: 408.592509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:30.789409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:30.380396Z","time spent":"408.870736ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:31.053481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.466665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:37:31.053653Z","caller":"traceutil/trace.go:171","msg":"trace[1113919552] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"616.179301ms","start":"2026-02-26T01:37:30.437458Z","end":"2026-02-26T01:37:31.053637Z","steps":["trace[1113919552] 'process raft request' (duration: 431.290165ms)","trace[1113919552] 'compare' (duration: 184.339092ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:31.053744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:30.437438Z","time spent":"616.264542ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:31.053853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.057371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-26T01:37:31.05391Z","caller":"traceutil/trace.go:171","msg":"trace[986467417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:930; }","duration":"434.149652ms","start":"2026-02-26T01:37:30.619744Z","end":"2026-02-26T01:37:31.053894Z","steps":["trace[986467417] 'agreement among raft nodes before linearized reading' (duration: 433.992178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:31.053952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:30.619724Z","time spent":"434.217364ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T01:37:31.053595Z","caller":"traceutil/trace.go:171","msg":"trace[141938736] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:962; }","duration":"433.778652ms","start":"2026-02-26T01:37:30.619797Z","end":"2026-02-26T01:37:31.053575Z","steps":["trace[141938736] 'read index received' (duration: 169.231966ms)","trace[141938736] 'applied index is now lower than readState.Index' (duration: 264.545286ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:31.054201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.316941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:37:31.055713Z","caller":"traceutil/trace.go:171","msg":"trace[155611938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:930; }","duration":"132.855237ms","start":"2026-02-26T01:37:30.922839Z","end":"2026-02-26T01:37:31.055694Z","steps":["trace[155611938] 'agreement among raft nodes before linearized reading' (duration: 131.27309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:31.054563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.195017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T01:37:31.055847Z","caller":"traceutil/trace.go:171","msg":"trace[621966560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"129.523159ms","start":"2026-02-26T01:37:30.926302Z","end":"2026-02-26T01:37:31.055825Z","steps":["trace[621966560] 'agreement among raft nodes before linearized reading' (duration: 127.927612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:32.034647Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088705392173057,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:37:32.53527Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088705392173057,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:37:32.608484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262391721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:32.608566Z","caller":"traceutil/trace.go:171","msg":"trace[1510588287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"1.262493914s","start":"2026-02-26T01:37:31.346036Z","end":"2026-02-26T01:37:32.60853Z","steps":["trace[1510588287] 'range keys from in-memory index tree' (duration: 1.262306819s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:32.608608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:31.346011Z","time spent":"1.262579115s","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:37:32.683601Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.189362326s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-26T01:37:33.120715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.948358ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-26T01:37:33.120814Z","caller":"traceutil/trace.go:171","msg":"trace[278181812] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"1.58677458s","start":"2026-02-26T01:37:31.534022Z","end":"2026-02-26T01:37:33.120797Z","steps":["trace[278181812] 'read index received' (duration: 1.149685368s)","trace[278181812] 'applied index is now lower than readState.Index' (duration: 437.087702ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:33.121083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.519891922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T01:37:33.121148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.148211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:37:33.121199Z","caller":"traceutil/trace.go:171","msg":"trace[1973245662] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:934; }","duration":"229.243824ms","start":"2026-02-26T01:37:32.891939Z","end":"2026-02-26T01:37:33.121183Z","steps":["trace[1973245662] 'agreement among raft nodes before linearized reading' (duration: 229.155552ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:33.121215Z","caller":"traceutil/trace.go:171","msg":"trace[700218409] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:934; }","duration":"1.520007884s","start":"2026-02-26T01:37:31.601161Z","end":"2026-02-26T01:37:33.121169Z","steps":["trace[700218409] 'agreement among raft nodes before linearized reading' (duration: 1.5198461s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:33.121318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:31.6011Z","time spent":"1.520188718s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:37:33.121441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587356175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:37:33.121498Z","caller":"traceutil/trace.go:171","msg":"trace[1145867447] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:934; }","duration":"1.587513079s","start":"2026-02-26T01:37:31.533972Z","end":"2026-02-26T01:37:33.121485Z","steps":["trace[1145867447] 'agreement among raft nodes before linearized reading' (duration: 1.587308644s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:33.121532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.16488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-26T01:37:33.121537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:31.533949Z","time spent":"1.58757677s","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":110,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-26T01:37:33.121573Z","caller":"traceutil/trace.go:171","msg":"trace[2114607322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:934; }","duration":"328.255122ms","start":"2026-02-26T01:37:32.793308Z","end":"2026-02-26T01:37:33.121563Z","steps":["trace[2114607322] 'agreement among raft nodes before linearized reading' (duration: 328.109668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:33.121603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:32.793289Z","time spent":"328.307732ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T01:37:33.121862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.122633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:33.121905Z","caller":"traceutil/trace.go:171","msg":"trace[556657359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"512.189565ms","start":"2026-02-26T01:37:32.609705Z","end":"2026-02-26T01:37:33.121895Z","steps":["trace[556657359] 'agreement among raft nodes before linearized reading' (duration: 512.121254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:33.121928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:32.609693Z","time spent":"512.229026ms","remote":"127.0.0.1:46792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:37:33.63069Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088705392173060,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T01:37:33.815171Z","caller":"traceutil/trace.go:171","msg":"trace[1138227385] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"689.429201ms","start":"2026-02-26T01:37:33.125725Z","end":"2026-02-26T01:37:33.815154Z","steps":["trace[1138227385] 'process raft request' (duration: 689.303468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:33.815288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:33.125689Z","time spent":"689.534673ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:34.987077Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.171960746s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T01:37:35.056689Z","caller":"traceutil/trace.go:171","msg":"trace[1704679653] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"1.926522193s","start":"2026-02-26T01:37:33.130135Z","end":"2026-02-26T01:37:35.056657Z","steps":["trace[1704679653] 'read index received' (duration: 685.18055ms)","trace[1704679653] 'applied index is now lower than readState.Index' (duration: 1.241339493s)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:37:35.056847Z","caller":"traceutil/trace.go:171","msg":"trace[19241187] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"1.930731924s","start":"2026-02-26T01:37:33.126096Z","end":"2026-02-26T01:37:35.056828Z","steps":["trace[19241187] 'process raft request' (duration: 1.861146121s)","trace[19241187] 'compare' (duration: 69.104371ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:35.056993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:33.126086Z","time spent":"1.930801386s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:35.057184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.927042986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:35.057239Z","caller":"traceutil/trace.go:171","msg":"trace[650370388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"1.927124828s","start":"2026-02-26T01:37:33.1301Z","end":"2026-02-26T01:37:35.057224Z","steps":["trace[650370388] 'agreement among raft nodes before linearized reading' (duration: 1.927040376s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:35.057289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:33.130087Z","time spent":"1.92719037s","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:37:35.057294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.92037955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-26T01:37:35.057287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.693961323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:35.057362Z","caller":"traceutil/trace.go:171","msg":"trace[133307793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:936; }","duration":"1.920467341s","start":"2026-02-26T01:37:33.136879Z","end":"2026-02-26T01:37:35.057347Z","steps":["trace[133307793] 'agreement among raft nodes before linearized reading' (duration: 1.920297077s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:35.057383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.920023061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T01:37:35.057408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:33.136872Z","time spent":"1.920525022s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T01:37:35.057378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.908415557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:35.057446Z","caller":"traceutil/trace.go:171","msg":"trace[1740600417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:936; }","duration":"1.920119333s","start":"2026-02-26T01:37:33.137311Z","end":"2026-02-26T01:37:35.05743Z","steps":["trace[1740600417] 'agreement among raft nodes before linearized reading' (duration: 1.919921419s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:35.057463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.920290467s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-26T01:37:35.057497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:33.137302Z","time spent":"1.920182764s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T01:37:35.057482Z","caller":"traceutil/trace.go:171","msg":"trace[2022021415] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:936; }","duration":"1.90854611s","start":"2026-02-26T01:37:33.148913Z","end":"2026-02-26T01:37:35.057459Z","steps":["trace[2022021415] 'agreement among raft nodes before linearized reading' (duration: 1.908403487s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:35.057553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:33.148902Z","time spent":"1.908636242s","remote":"127.0.0.1:46954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:37:35.057573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.290632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:35.057548Z","caller":"traceutil/trace.go:171","msg":"trace[378406319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:936; }","duration":"1.920384909s","start":"2026-02-26T01:37:33.137138Z","end":"2026-02-26T01:37:35.057523Z","steps":["trace[378406319] 'agreement among raft nodes before linearized reading' (duration: 1.920181175s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:35.057638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.578212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T01:37:35.057662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:33.137133Z","time spent":"1.920519582s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T01:37:35.057686Z","caller":"traceutil/trace.go:171","msg":"trace[1616087909] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:936; }","duration":"550.673084ms","start":"2026-02-26T01:37:34.507003Z","end":"2026-02-26T01:37:35.057676Z","steps":["trace[1616087909] 'agreement among raft nodes before linearized reading' (duration: 550.588632ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:35.057636Z","caller":"traceutil/trace.go:171","msg":"trace[146944172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"331.371685ms","start":"2026-02-26T01:37:34.726257Z","end":"2026-02-26T01:37:35.057628Z","steps":["trace[146944172] 'agreement among raft nodes before linearized reading' (duration: 331.252302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:35.057729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:34.506982Z","time spent":"550.737586ms","remote":"127.0.0.1:46884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-02-26T01:37:35.057472Z","caller":"traceutil/trace.go:171","msg":"trace[1620957345] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:936; }","duration":"1.694120386s","start":"2026-02-26T01:37:33.363262Z","end":"2026-02-26T01:37:35.057382Z","steps":["trace[1620957345] 'agreement among raft nodes before linearized reading' (duration: 1.693922441s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:35.057798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:34.726241Z","time spent":"331.533748ms","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:37:35.057796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:33.363245Z","time spent":"1.694538786s","remote":"127.0.0.1:50432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-02-26T01:37:36.683797Z","caller":"traceutil/trace.go:171","msg":"trace[501220331] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"165.261163ms","start":"2026-02-26T01:37:36.51851Z","end":"2026-02-26T01:37:36.683771Z","steps":["trace[501220331] 'process raft request' (duration: 135.698176ms)","trace[501220331] 'compare' (duration: 29.451895ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:37:37.76257Z","caller":"traceutil/trace.go:171","msg":"trace[863682426] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"120.279551ms","start":"2026-02-26T01:37:37.642255Z","end":"2026-02-26T01:37:37.762535Z","steps":["trace[863682426] 'process raft request' (duration: 37.922636ms)","trace[863682426] 'compare' (duration: 82.216601ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:37:39.380733Z","caller":"traceutil/trace.go:171","msg":"trace[1499166344] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"130.273045ms","start":"2026-02-26T01:37:39.250434Z","end":"2026-02-26T01:37:39.380707Z","steps":["trace[1499166344] 'read index received' (duration: 44.276384ms)","trace[1499166344] 'applied index is now lower than readState.Index' (duration: 85.995141ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:39.381027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.993269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T01:37:39.381118Z","caller":"traceutil/trace.go:171","msg":"trace[1766313528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"130.117162ms","start":"2026-02-26T01:37:39.250983Z","end":"2026-02-26T01:37:39.3811Z","steps":["trace[1766313528] 'agreement among raft nodes before linearized reading' (duration: 129.890397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:39.381053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.593885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T01:37:39.381301Z","caller":"traceutil/trace.go:171","msg":"trace[293225474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"130.86552ms","start":"2026-02-26T01:37:39.250403Z","end":"2026-02-26T01:37:39.381269Z","steps":["trace[293225474] 'agreement among raft nodes before linearized reading' (duration: 130.43883ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:39.537287Z","caller":"traceutil/trace.go:171","msg":"trace[274156274] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"116.254256ms","start":"2026-02-26T01:37:39.421011Z","end":"2026-02-26T01:37:39.537265Z","steps":["trace[274156274] 'process raft request' (duration: 113.276066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:41.050442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.007156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:41.050522Z","caller":"traceutil/trace.go:171","msg":"trace[1283910570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"704.131359ms","start":"2026-02-26T01:37:40.346371Z","end":"2026-02-26T01:37:41.050502Z","steps":["trace[1283910570] 'range keys from in-memory index tree' (duration: 703.895363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:41.050592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:40.346351Z","time spent":"704.220371ms","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T01:37:41.330793Z","caller":"traceutil/trace.go:171","msg":"trace[1320989002] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"741.158702ms","start":"2026-02-26T01:37:40.589611Z","end":"2026-02-26T01:37:41.33077Z","steps":["trace[1320989002] 'process raft request' (duration: 740.989288ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:41.330826Z","caller":"traceutil/trace.go:171","msg":"trace[272423442] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:998; }","duration":"303.23385ms","start":"2026-02-26T01:37:41.027557Z","end":"2026-02-26T01:37:41.330791Z","steps":["trace[272423442] 'read index received' (duration: 303.201289ms)","trace[272423442] 'applied index is now lower than readState.Index' (duration: 11.411µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:41.330947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:40.589594Z","time spent":"741.270915ms","remote":"127.0.0.1:46974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5353,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:41.331001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.418664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:37:41.331056Z","caller":"traceutil/trace.go:171","msg":"trace[1599629875] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:961; }","duration":"303.527067ms","start":"2026-02-26T01:37:41.027515Z","end":"2026-02-26T01:37:41.331042Z","steps":["trace[1599629875] 'agreement among raft nodes before linearized reading' (duration: 303.408074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:41.331103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:41.027495Z","time spent":"303.594379ms","remote":"127.0.0.1:47276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:37:42.106337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.002014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:37:42.106478Z","caller":"traceutil/trace.go:171","msg":"trace[1552696150] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"1.042709693s","start":"2026-02-26T01:37:41.063737Z","end":"2026-02-26T01:37:42.106447Z","steps":["trace[1552696150] 'process raft request' (duration: 424.549185ms)","trace[1552696150] 'compare' (duration: 617.941303ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:37:42.106524Z","caller":"traceutil/trace.go:171","msg":"trace[1165241003] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"775.597066ms","start":"2026-02-26T01:37:41.330895Z","end":"2026-02-26T01:37:42.106492Z","steps":["trace[1165241003] 'read index received' (duration: 157.349406ms)","trace[1165241003] 'applied index is now lower than readState.Index' (duration: 618.2414ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:42.10663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:41.063719Z","time spent":"1.042839216s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:42.568692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.288157ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-26T01:37:42.568775Z","caller":"traceutil/trace.go:171","msg":"trace[1406390815] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"462.155014ms","start":"2026-02-26T01:37:42.106592Z","end":"2026-02-26T01:37:42.568747Z","steps":["trace[1406390815] 'read index received' (duration: 95.742µs)","trace[1406390815] 'applied index is now lower than readState.Index' (duration: 462.056962ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:42.568824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.4223773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-26T01:37:42.568873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086677822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T01:37:42.56889Z","caller":"traceutil/trace.go:171","msg":"trace[1230849799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:962; }","duration":"1.086733384s","start":"2026-02-26T01:37:41.482151Z","end":"2026-02-26T01:37:42.568884Z","steps":["trace[1230849799] 'agreement among raft nodes before linearized reading' (duration: 1.086651192s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:42.568888Z","caller":"traceutil/trace.go:171","msg":"trace[855345072] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"1.422486422s","start":"2026-02-26T01:37:41.146381Z","end":"2026-02-26T01:37:42.568868Z","steps":["trace[855345072] 'agreement among raft nodes before linearized reading' (duration: 960.287987ms)","trace[855345072] 'range keys from in-memory index tree' (duration: 462.030241ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:42.568916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:41.48213Z","time spent":"1.086779494s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T01:37:42.568954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.516958582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:42.568996Z","caller":"traceutil/trace.go:171","msg":"trace[1308774398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"1.517029733s","start":"2026-02-26T01:37:41.051957Z","end":"2026-02-26T01:37:42.568987Z","steps":["trace[1308774398] 'agreement among raft nodes before linearized reading' (duration: 1.054719426s)","trace[1308774398] 'range keys from in-memory index tree' (duration: 462.256886ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:42.569023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:41.051942Z","time spent":"1.517076445s","remote":"127.0.0.1:46792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:37:42.568936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:41.146362Z","time spent":"1.422568904s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T01:37:42.569213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086951019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-26T01:37:42.569253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.130089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T01:37:42.569354Z","caller":"traceutil/trace.go:171","msg":"trace[148194961] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:962; }","duration":"530.274462ms","start":"2026-02-26T01:37:42.039057Z","end":"2026-02-26T01:37:42.569331Z","steps":["trace[148194961] 'agreement among raft nodes before linearized reading' (duration: 530.018446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:42.569446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:42.039035Z","time spent":"530.396176ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T01:37:42.569285Z","caller":"traceutil/trace.go:171","msg":"trace[1325452236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:962; }","duration":"1.087061981s","start":"2026-02-26T01:37:41.482206Z","end":"2026-02-26T01:37:42.569268Z","steps":["trace[1325452236] 'agreement among raft nodes before linearized reading' (duration: 1.086864386s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:42.568867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382826496s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-26T01:37:42.569615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:41.482177Z","time spent":"1.08742767s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T01:37:42.569653Z","caller":"traceutil/trace.go:171","msg":"trace[463924385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:962; }","duration":"1.383643805s","start":"2026-02-26T01:37:41.185986Z","end":"2026-02-26T01:37:42.56963Z","steps":["trace[463924385] 'agreement among raft nodes before linearized reading' (duration: 920.662542ms)","trace[463924385] 'range keys from in-memory index tree' (duration: 462.077582ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:42.569716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:41.185969Z","time spent":"1.383729887s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T01:37:42.761652Z","caller":"traceutil/trace.go:171","msg":"trace[1237771215] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"188.906012ms","start":"2026-02-26T01:37:42.57272Z","end":"2026-02-26T01:37:42.761626Z","steps":["trace[1237771215] 'process raft request' (duration: 188.745578ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:43.576945Z","caller":"traceutil/trace.go:171","msg":"trace[1758806677] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"1.003518829s","start":"2026-02-26T01:37:42.573386Z","end":"2026-02-26T01:37:43.576905Z","steps":["trace[1758806677] 'process raft request' (duration: 1.003416467s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:43.577093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:42.57337Z","time spent":"1.003649342s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:37:43.577223Z","caller":"traceutil/trace.go:171","msg":"trace[1654344075] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"1.003906908s","start":"2026-02-26T01:37:42.573307Z","end":"2026-02-26T01:37:43.577214Z","steps":["trace[1654344075] 'process raft request' (duration: 1.003335344s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:43.577241Z","caller":"traceutil/trace.go:171","msg":"trace[1177753988] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"1.002527826s","start":"2026-02-26T01:37:42.574698Z","end":"2026-02-26T01:37:43.577225Z","steps":["trace[1177753988] 'process raft request' (duration: 1.002170978s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:43.577268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:42.5733Z","time spent":"1.003943159s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:43.577323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:42.574676Z","time spent":"1.002602708s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:37:43.577368Z","caller":"traceutil/trace.go:171","msg":"trace[1595121258] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1000; }","duration":"1.000416346s","start":"2026-02-26T01:37:42.576942Z","end":"2026-02-26T01:37:43.577358Z","steps":["trace[1595121258] 'read index received' (duration: 184.452487ms)","trace[1595121258] 'applied index is now lower than readState.Index' (duration: 815.962819ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:43.577442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000507288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:43.577477Z","caller":"traceutil/trace.go:171","msg":"trace[2071057913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"1.00056507s","start":"2026-02-26T01:37:42.576904Z","end":"2026-02-26T01:37:43.577469Z","steps":["trace[2071057913] 'agreement among raft nodes before linearized reading' (duration: 1.000511868s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:43.577506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:42.576892Z","time spent":"1.00060547s","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:37:43.577504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.768564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T01:37:43.577531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.567173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:43.577577Z","caller":"traceutil/trace.go:171","msg":"trace[1482495781] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:967; }","duration":"964.878807ms","start":"2026-02-26T01:37:42.612678Z","end":"2026-02-26T01:37:43.577557Z","steps":["trace[1482495781] 'agreement among raft nodes before linearized reading' (duration: 964.768094ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:43.577598Z","caller":"traceutil/trace.go:171","msg":"trace[1233839188] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:967; }","duration":"299.679336ms","start":"2026-02-26T01:37:43.277903Z","end":"2026-02-26T01:37:43.577582Z","steps":["trace[1233839188] 'agreement among raft nodes before linearized reading' (duration: 299.571764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:43.57763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:42.612536Z","time spent":"965.083861ms","remote":"127.0.0.1:47044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:37:43.577793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.864888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T01:37:43.57784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.11261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:37:43.578868Z","caller":"traceutil/trace.go:171","msg":"trace[1817703789] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:967; }","duration":"429.170176ms","start":"2026-02-26T01:37:43.149679Z","end":"2026-02-26T01:37:43.57885Z","steps":["trace[1817703789] 'agreement among raft nodes before linearized reading' (duration: 428.12636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:43.578945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:43.14966Z","time spent":"429.266457ms","remote":"127.0.0.1:47122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-26T01:37:43.578417Z","caller":"traceutil/trace.go:171","msg":"trace[1355164770] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:967; }","duration":"251.904929ms","start":"2026-02-26T01:37:43.325919Z","end":"2026-02-26T01:37:43.577824Z","steps":["trace[1355164770] 'agreement among raft nodes before linearized reading' (duration: 251.847037ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:43.57778Z","caller":"traceutil/trace.go:171","msg":"trace[1719766023] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"1.002433254s","start":"2026-02-26T01:37:42.574974Z","end":"2026-02-26T01:37:43.577407Z","steps":["trace[1719766023] 'process raft request' (duration: 1.002196649s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:43.57985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:42.574962Z","time spent":"1.004828651s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:37:44.951909Z","caller":"traceutil/trace.go:171","msg":"trace[1462383219] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"171.801707ms","start":"2026-02-26T01:37:44.780081Z","end":"2026-02-26T01:37:44.951883Z","steps":["trace[1462383219] 'read index received' (duration: 171.536801ms)","trace[1462383219] 'applied index is now lower than readState.Index' (duration: 263.916µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:44.952064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.959492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:44.952075Z","caller":"traceutil/trace.go:171","msg":"trace[2084127685] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"182.341286ms","start":"2026-02-26T01:37:44.769665Z","end":"2026-02-26T01:37:44.952006Z","steps":["trace[2084127685] 'process raft request' (duration: 182.034509ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:44.95213Z","caller":"traceutil/trace.go:171","msg":"trace[1582593604] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:968; }","duration":"172.055443ms","start":"2026-02-26T01:37:44.780039Z","end":"2026-02-26T01:37:44.952094Z","steps":["trace[1582593604] 'agreement among raft nodes before linearized reading' (duration: 171.93888ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:45.76352Z","caller":"traceutil/trace.go:171","msg":"trace[1836750486] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"177.060532ms","start":"2026-02-26T01:37:45.586433Z","end":"2026-02-26T01:37:45.763494Z","steps":["trace[1836750486] 'read index received' (duration: 176.873448ms)","trace[1836750486] 'applied index is now lower than readState.Index' (duration: 185.994µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:37:45.763652Z","caller":"traceutil/trace.go:171","msg":"trace[1538342102] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"177.879382ms","start":"2026-02-26T01:37:45.585759Z","end":"2026-02-26T01:37:45.763638Z","steps":["trace[1538342102] 'process raft request' (duration: 177.593994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:45.764039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.585045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-26T01:37:45.764052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.732714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T01:37:45.764101Z","caller":"traceutil/trace.go:171","msg":"trace[471608158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"177.678497ms","start":"2026-02-26T01:37:45.586408Z","end":"2026-02-26T01:37:45.764086Z","steps":["trace[471608158] 'agreement among raft nodes before linearized reading' (duration: 177.520973ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:45.764114Z","caller":"traceutil/trace.go:171","msg":"trace[1917057562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:969; }","duration":"176.827726ms","start":"2026-02-26T01:37:45.58727Z","end":"2026-02-26T01:37:45.764097Z","steps":["trace[1917057562] 'agreement among raft nodes before linearized reading' (duration: 176.445667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:45.764156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.1019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T01:37:45.764235Z","caller":"traceutil/trace.go:171","msg":"trace[183005681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:969; }","duration":"176.200812ms","start":"2026-02-26T01:37:45.588013Z","end":"2026-02-26T01:37:45.764214Z","steps":["trace[183005681] 'agreement among raft nodes before linearized reading' (duration: 175.971737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:45.988758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.725117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:37:45.988904Z","caller":"traceutil/trace.go:171","msg":"trace[2138853449] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"219.880403ms","start":"2026-02-26T01:37:45.769011Z","end":"2026-02-26T01:37:45.988892Z","steps":["trace[2138853449] 'process raft request' (duration: 219.852343ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:45.988949Z","caller":"traceutil/trace.go:171","msg":"trace[571853966] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"220.255541ms","start":"2026-02-26T01:37:45.768675Z","end":"2026-02-26T01:37:45.988931Z","steps":["trace[571853966] 'process raft request' (duration: 220.154359ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:45.988988Z","caller":"traceutil/trace.go:171","msg":"trace[2126669753] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"220.751754ms","start":"2026-02-26T01:37:45.768184Z","end":"2026-02-26T01:37:45.988936Z","steps":["trace[2126669753] 'process raft request' (duration: 81.776551ms)","trace[2126669753] 'compare' (duration: 138.638995ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:46.484333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.351904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:46.484423Z","caller":"traceutil/trace.go:171","msg":"trace[1203508655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"140.476368ms","start":"2026-02-26T01:37:46.343917Z","end":"2026-02-26T01:37:46.484393Z","steps":["trace[1203508655] 'range keys from in-memory index tree' (duration: 140.291533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:46.484628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.677296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T01:37:46.484765Z","caller":"traceutil/trace.go:171","msg":"trace[1020985683] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:972; }","duration":"138.838679ms","start":"2026-02-26T01:37:46.345904Z","end":"2026-02-26T01:37:46.484743Z","steps":["trace[1020985683] 'range keys from in-memory index tree' (duration: 138.48041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:47.636241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088705392173155,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:37:48.006791Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.046367s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T01:37:48.007284Z","caller":"traceutil/trace.go:171","msg":"trace[376573140] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"871.868649ms","start":"2026-02-26T01:37:47.135393Z","end":"2026-02-26T01:37:48.007262Z","steps":["trace[376573140] 'read index received' (duration: 871.51489ms)","trace[376573140] 'applied index is now lower than readState.Index' (duration: 352.619µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:37:48.007312Z","caller":"traceutil/trace.go:171","msg":"trace[79297085] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"1.046973463s","start":"2026-02-26T01:37:46.960313Z","end":"2026-02-26T01:37:48.007286Z","steps":["trace[79297085] 'process raft request' (duration: 1.046679876s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:48.007452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:46.960288Z","time spent":"1.047088486s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:48.007785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.372181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:37:48.007881Z","caller":"traceutil/trace.go:171","msg":"trace[200139398] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:974; }","duration":"872.496204ms","start":"2026-02-26T01:37:47.135345Z","end":"2026-02-26T01:37:48.007841Z","steps":["trace[200139398] 'agreement among raft nodes before linearized reading' (duration: 872.018262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:48.007919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:47.135326Z","time spent":"872.583527ms","remote":"127.0.0.1:46974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:37:48.00752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.551615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:48.008015Z","caller":"traceutil/trace.go:171","msg":"trace[730565759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"660.116059ms","start":"2026-02-26T01:37:47.347886Z","end":"2026-02-26T01:37:48.008002Z","steps":["trace[730565759] 'agreement among raft nodes before linearized reading' (duration: 659.520494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:48.008374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:47.347855Z","time spent":"660.500948ms","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:37:48.008398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.861058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:37:48.008461Z","caller":"traceutil/trace.go:171","msg":"trace[1681533289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:974; }","duration":"241.005791ms","start":"2026-02-26T01:37:47.767442Z","end":"2026-02-26T01:37:48.008448Z","steps":["trace[1681533289] 'agreement among raft nodes before linearized reading' (duration: 240.08801ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:49.280627Z","caller":"traceutil/trace.go:171","msg":"trace[779079612] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"1.268529995s","start":"2026-02-26T01:37:48.012076Z","end":"2026-02-26T01:37:49.280606Z","steps":["trace[779079612] 'process raft request' (duration: 1.268354341s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:49.280775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:48.012049Z","time spent":"1.268642398s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:37:49.592517Z","caller":"traceutil/trace.go:171","msg":"trace[117136546] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"1.580215026s","start":"2026-02-26T01:37:48.012285Z","end":"2026-02-26T01:37:49.5925Z","steps":["trace[117136546] 'process raft request' (duration: 1.580044832s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:49.592651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:48.012267Z","time spent":"1.580323718s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:37:49.592859Z","caller":"traceutil/trace.go:171","msg":"trace[17773942] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"1.580531713s","start":"2026-02-26T01:37:48.012302Z","end":"2026-02-26T01:37:49.592834Z","steps":["trace[17773942] 'process raft request' (duration: 1.580159824s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:49.592991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.309109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:37:49.593014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:48.012284Z","time spent":"1.580663327s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:37:49.593055Z","caller":"traceutil/trace.go:171","msg":"trace[1949479676] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:978; }","duration":"457.422952ms","start":"2026-02-26T01:37:49.135618Z","end":"2026-02-26T01:37:49.593041Z","steps":["trace[1949479676] 'agreement among raft nodes before linearized reading' (duration: 457.310679ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:49.592866Z","caller":"traceutil/trace.go:171","msg":"trace[1466357634] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1013; }","duration":"457.183196ms","start":"2026-02-26T01:37:49.135658Z","end":"2026-02-26T01:37:49.592841Z","steps":["trace[1466357634] 'read index received' (duration: 144.848851ms)","trace[1466357634] 'applied index is now lower than readState.Index' (duration: 312.333275ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:49.593101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:49.135603Z","time spent":"457.488683ms","remote":"127.0.0.1:50466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-02-26T01:37:49.593178Z","caller":"traceutil/trace.go:171","msg":"trace[557965665] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.580131683s","start":"2026-02-26T01:37:48.012856Z","end":"2026-02-26T01:37:49.592988Z","steps":["trace[557965665] 'process raft request' (duration: 1.579870557s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:49.593552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:48.012843Z","time spent":"1.580541935s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:49.967049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.449191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:37:49.96718Z","caller":"traceutil/trace.go:171","msg":"trace[1902595912] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"374.041592ms","start":"2026-02-26T01:37:49.593122Z","end":"2026-02-26T01:37:49.967163Z","steps":["trace[1902595912] 'read index received' (duration: 243.325675ms)","trace[1902595912] 'applied index is now lower than readState.Index' (duration: 130.714747ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:49.967262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.874779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:49.967288Z","caller":"traceutil/trace.go:171","msg":"trace[875392544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"622.93861ms","start":"2026-02-26T01:37:49.34434Z","end":"2026-02-26T01:37:49.967279Z","steps":["trace[875392544] 'agreement among raft nodes before linearized reading' (duration: 622.868108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:49.967319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:49.344321Z","time spent":"622.990702ms","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T01:37:49.967528Z","caller":"traceutil/trace.go:171","msg":"trace[787101364] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"685.213751ms","start":"2026-02-26T01:37:49.282302Z","end":"2026-02-26T01:37:49.967515Z","steps":["trace[787101364] 'process raft request' (duration: 554.217717ms)","trace[787101364] 'compare' (duration: 130.313648ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:49.967595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:49.282284Z","time spent":"685.279313ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:37:50.386434Z","caller":"traceutil/trace.go:171","msg":"trace[1910428930] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"352.611587ms","start":"2026-02-26T01:37:50.033782Z","end":"2026-02-26T01:37:50.386393Z","steps":["trace[1910428930] 'process raft request' (duration: 306.536969ms)","trace[1910428930] 'compare' (duration: 45.242718ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:50.386592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:50.033767Z","time spent":"352.74714ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:51.846768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088705392173171,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T01:37:51.980015Z","caller":"traceutil/trace.go:171","msg":"trace[1150964838] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"690.141338ms","start":"2026-02-26T01:37:51.289848Z","end":"2026-02-26T01:37:51.979989Z","steps":["trace[1150964838] 'process raft request' (duration: 689.964633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:51.980219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:51.289831Z","time spent":"690.257811ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:52.736998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.853792ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-26T01:37:52.737182Z","caller":"traceutil/trace.go:171","msg":"trace[1164173415] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1019; }","duration":"1.390998499s","start":"2026-02-26T01:37:51.346163Z","end":"2026-02-26T01:37:52.737161Z","steps":["trace[1164173415] 'read index received' (duration: 633.619272ms)","trace[1164173415] 'applied index is now lower than readState.Index' (duration: 757.377397ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:37:52.737322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391106511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:37:52.737372Z","caller":"traceutil/trace.go:171","msg":"trace[523069755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"1.391202784s","start":"2026-02-26T01:37:51.346158Z","end":"2026-02-26T01:37:52.737361Z","steps":["trace[523069755] 'agreement among raft nodes before linearized reading' (duration: 1.391042s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:52.737429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:51.346118Z","time spent":"1.391302286s","remote":"127.0.0.1:46792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T01:37:52.809393Z","caller":"traceutil/trace.go:171","msg":"trace[1851894980] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"394.890245ms","start":"2026-02-26T01:37:52.414478Z","end":"2026-02-26T01:37:52.809368Z","steps":["trace[1851894980] 'process raft request' (duration: 394.688151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:52.809527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:52.41445Z","time spent":"395.011129ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:52.809623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212039744s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-26T01:37:52.809676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211712996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-26T01:37:52.809716Z","caller":"traceutil/trace.go:171","msg":"trace[601325751] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:982; }","duration":"1.212180647s","start":"2026-02-26T01:37:51.597511Z","end":"2026-02-26T01:37:52.809692Z","steps":["trace[601325751] 'agreement among raft nodes before linearized reading' (duration: 1.21188977s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:52.809744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.616967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T01:37:52.809854Z","caller":"traceutil/trace.go:171","msg":"trace[595358076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:982; }","duration":"418.7782ms","start":"2026-02-26T01:37:52.391058Z","end":"2026-02-26T01:37:52.809836Z","steps":["trace[595358076] 'agreement among raft nodes before linearized reading' (duration: 418.472343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:52.809783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:51.597502Z","time spent":"1.212268689s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T01:37:52.809907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:52.391041Z","time spent":"418.856343ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T01:37:52.809733Z","caller":"traceutil/trace.go:171","msg":"trace[657093256] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:982; }","duration":"1.211813319s","start":"2026-02-26T01:37:51.597908Z","end":"2026-02-26T01:37:52.809721Z","steps":["trace[657093256] 'agreement among raft nodes before linearized reading' (duration: 1.211618354s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:52.810056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:51.597902Z","time spent":"1.212137376s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T01:37:52.809733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212366271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:37:52.810144Z","caller":"traceutil/trace.go:171","msg":"trace[1480331030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:982; }","duration":"1.212806421s","start":"2026-02-26T01:37:51.597327Z","end":"2026-02-26T01:37:52.810133Z","steps":["trace[1480331030] 'agreement among raft nodes before linearized reading' (duration: 1.21230243s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:52.810174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:51.597308Z","time spent":"1.212858403s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T01:37:53.674336Z","caller":"traceutil/trace.go:171","msg":"trace[1685502934] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"860.006359ms","start":"2026-02-26T01:37:52.814309Z","end":"2026-02-26T01:37:53.674316Z","steps":["trace[1685502934] 'process raft request' (duration: 859.871215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:53.674481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:52.814285Z","time spent":"860.10088ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:37:54.367921Z","caller":"traceutil/trace.go:171","msg":"trace[547760093] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"1.553548736s","start":"2026-02-26T01:37:52.814355Z","end":"2026-02-26T01:37:54.367904Z","steps":["trace[547760093] 'process raft request' (duration: 1.551235631s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:54.367962Z","caller":"traceutil/trace.go:171","msg":"trace[1754662962] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"1.553516525s","start":"2026-02-26T01:37:52.814432Z","end":"2026-02-26T01:37:54.367948Z","steps":["trace[1754662962] 'process raft request' (duration: 1.553225979s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:54.368024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:52.81433Z","time spent":"1.553638478s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:54.368035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:52.814406Z","time spent":"1.553590616s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:37:54.368117Z","caller":"traceutil/trace.go:171","msg":"trace[408914513] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1023; }","duration":"384.165612ms","start":"2026-02-26T01:37:53.983943Z","end":"2026-02-26T01:37:54.368109Z","steps":["trace[408914513] 'read index received' (duration: 381.658963ms)","trace[408914513] 'applied index is now lower than readState.Index' (duration: 2.505529ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:37:54.368148Z","caller":"traceutil/trace.go:171","msg":"trace[1582693592] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"1.552165552s","start":"2026-02-26T01:37:52.815958Z","end":"2026-02-26T01:37:54.368124Z","steps":["trace[1582693592] 'process raft request' (duration: 1.551955457s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:54.368229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.285295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:37:54.368251Z","caller":"traceutil/trace.go:171","msg":"trace[254642692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:986; }","duration":"384.343516ms","start":"2026-02-26T01:37:53.983901Z","end":"2026-02-26T01:37:54.368244Z","steps":["trace[254642692] 'agreement among raft nodes before linearized reading' (duration: 384.265904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:54.368267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:53.983881Z","time spent":"384.381807ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T01:37:54.368286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:52.815945Z","time spent":"1.552280596s","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:38:08.902301Z","caller":"traceutil/trace.go:171","msg":"trace[70542653] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"468.448542ms","start":"2026-02-26T01:38:08.433827Z","end":"2026-02-26T01:38:08.902275Z","steps":["trace[70542653] 'process raft request' (duration: 468.296869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:38:08.902487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:38:08.433811Z","time spent":"468.575805ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:38:08.944172Z","caller":"traceutil/trace.go:171","msg":"trace[1717462747] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"508.924377ms","start":"2026-02-26T01:38:08.435224Z","end":"2026-02-26T01:38:08.944148Z","steps":["trace[1717462747] 'process raft request' (duration: 508.763733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:38:08.944328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:38:08.435203Z","time spent":"509.03936ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:38:08.944369Z","caller":"traceutil/trace.go:171","msg":"trace[664711698] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1122; }","duration":"318.85358ms","start":"2026-02-26T01:38:08.625485Z","end":"2026-02-26T01:38:08.944338Z","steps":["trace[664711698] 'read index received' (duration: 276.630733ms)","trace[664711698] 'applied index is now lower than readState.Index' (duration: 42.220937ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:38:08.944509Z","caller":"traceutil/trace.go:171","msg":"trace[1055331351] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"508.60462ms","start":"2026-02-26T01:38:08.43582Z","end":"2026-02-26T01:38:08.944424Z","steps":["trace[1055331351] 'process raft request' (duration: 508.291902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:38:08.944638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.134236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:38:08.944674Z","caller":"traceutil/trace.go:171","msg":"trace[263208607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1082; }","duration":"319.236258ms","start":"2026-02-26T01:38:08.625428Z","end":"2026-02-26T01:38:08.944664Z","steps":["trace[263208607] 'agreement among raft nodes before linearized reading' (duration: 319.028643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:38:08.944721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:38:08.625407Z","time spent":"319.29514ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T01:38:08.944717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:38:08.435781Z","time spent":"508.842985ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:38:09.406677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.323301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:38:09.406804Z","caller":"traceutil/trace.go:171","msg":"trace[1614080498] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1125; }","duration":"416.889854ms","start":"2026-02-26T01:38:08.989893Z","end":"2026-02-26T01:38:09.406783Z","steps":["trace[1614080498] 'read index received' (duration: 108.390849ms)","trace[1614080498] 'applied index is now lower than readState.Index' (duration: 308.494565ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:38:09.406946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.046877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:38:09.406981Z","caller":"traceutil/trace.go:171","msg":"trace[646915851] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"457.594156ms","start":"2026-02-26T01:38:08.949226Z","end":"2026-02-26T01:38:09.40682Z","steps":["trace[646915851] 'process raft request' (duration: 149.021619ms)","trace[646915851] 'compare' (duration: 307.938002ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:38:09.40702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.789512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-02-26T01:38:09.407079Z","caller":"traceutil/trace.go:171","msg":"trace[185380802] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1083; }","duration":"297.884775ms","start":"2026-02-26T01:38:09.109181Z","end":"2026-02-26T01:38:09.407066Z","steps":["trace[185380802] 'agreement among raft nodes before linearized reading' (duration: 297.726641ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:38:09.407029Z","caller":"traceutil/trace.go:171","msg":"trace[2120254969] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1083; }","duration":"417.16132ms","start":"2026-02-26T01:38:08.989851Z","end":"2026-02-26T01:38:09.407012Z","steps":["trace[2120254969] 'agreement among raft nodes before linearized reading' (duration: 417.040547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:38:09.407192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:38:08.949203Z","time spent":"457.868862ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:38:09.407278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:38:08.989831Z","time spent":"417.365725ms","remote":"127.0.0.1:50440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-26T01:38:09.813082Z","caller":"traceutil/trace.go:171","msg":"trace[1759141360] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"401.358368ms","start":"2026-02-26T01:38:09.411697Z","end":"2026-02-26T01:38:09.813055Z","steps":["trace[1759141360] 'process raft request' (duration: 401.193334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:38:09.813229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:38:09.411679Z","time spent":"401.47727ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:38:10.077079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.938964ms","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":"warn","ts":"2026-02-26T01:38:10.077108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.245151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-26T01:38:10.077176Z","caller":"traceutil/trace.go:171","msg":"trace[1462276988] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1084; }","duration":"170.088337ms","start":"2026-02-26T01:38:09.907059Z","end":"2026-02-26T01:38:10.077147Z","steps":["trace[1462276988] 'range keys from in-memory index tree' (duration: 169.691268ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:38:10.077176Z","caller":"traceutil/trace.go:171","msg":"trace[1193059480] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1084; }","duration":"148.343633ms","start":"2026-02-26T01:38:09.928814Z","end":"2026-02-26T01:38:10.077158Z","steps":["trace[1193059480] 'range keys from in-memory index tree' (duration: 148.105388ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:38:22.891161Z","caller":"traceutil/trace.go:171","msg":"trace[49190372] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"114.404772ms","start":"2026-02-26T01:38:22.776737Z","end":"2026-02-26T01:38:22.891142Z","steps":["trace[49190372] 'process raft request' (duration: 114.29931ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:38:41.336398Z","caller":"traceutil/trace.go:171","msg":"trace[673697494] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"117.150547ms","start":"2026-02-26T01:38:41.219222Z","end":"2026-02-26T01:38:41.336373Z","steps":["trace[673697494] 'process raft request' (duration: 116.984294ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:38:43.776168Z","caller":"traceutil/trace.go:171","msg":"trace[415239025] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"103.548376ms","start":"2026-02-26T01:38:43.672584Z","end":"2026-02-26T01:38:43.776133Z","steps":["trace[415239025] 'process raft request' (duration: 103.218588ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:38:56.476862Z","caller":"traceutil/trace.go:171","msg":"trace[766092182] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"127.053263ms","start":"2026-02-26T01:38:56.349776Z","end":"2026-02-26T01:38:56.476829Z","steps":["trace[766092182] 'process raft request' (duration: 126.681564ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:38:56.502595Z","caller":"traceutil/trace.go:171","msg":"trace[484810266] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"124.945071ms","start":"2026-02-26T01:38:56.377617Z","end":"2026-02-26T01:38:56.502562Z","steps":["trace[484810266] 'process raft request' (duration: 124.761457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:39:06.759006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.917425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-26T01:39:06.759093Z","caller":"traceutil/trace.go:171","msg":"trace[1451826249] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1318; }","duration":"151.041049ms","start":"2026-02-26T01:39:06.608032Z","end":"2026-02-26T01:39:06.759073Z","steps":["trace[1451826249] 'range keys from in-memory index tree' (duration: 150.744982ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:39:09.440281Z","caller":"traceutil/trace.go:171","msg":"trace[1915472065] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"110.281434ms","start":"2026-02-26T01:39:09.32997Z","end":"2026-02-26T01:39:09.440251Z","steps":["trace[1915472065] 'process raft request' (duration: 110.001008ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:39:15.57853Z","caller":"traceutil/trace.go:171","msg":"trace[238522951] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"108.737968ms","start":"2026-02-26T01:39:15.469764Z","end":"2026-02-26T01:39:15.578502Z","steps":["trace[238522951] 'process raft request' (duration: 108.561394ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:48.15624Z","caller":"traceutil/trace.go:171","msg":"trace[1146195782] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"254.171628ms","start":"2026-02-26T01:40:47.902032Z","end":"2026-02-26T01:40:48.156204Z","steps":["trace[1146195782] 'process raft request' (duration: 156.282146ms)","trace[1146195782] 'compare' (duration: 97.576635ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:41:01.403629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.547383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2813"} {"level":"info","ts":"2026-02-26T01:41:01.403706Z","caller":"traceutil/trace.go:171","msg":"trace[896985285] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1812; }","duration":"288.686107ms","start":"2026-02-26T01:41:01.115002Z","end":"2026-02-26T01:41:01.403688Z","steps":["trace[896985285] 'range keys from in-memory index tree' (duration: 288.444972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:41:01.403623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.951232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:41:01.403653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.692163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-26T01:41:01.403825Z","caller":"traceutil/trace.go:171","msg":"trace[913008302] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1812; }","duration":"321.203998ms","start":"2026-02-26T01:41:01.082608Z","end":"2026-02-26T01:41:01.403812Z","steps":["trace[913008302] 'count revisions from in-memory index tree' (duration: 320.789129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:41:01.403865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:41:01.082583Z","time spent":"321.26744ms","remote":"127.0.0.1:47068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-26T01:41:01.403872Z","caller":"traceutil/trace.go:171","msg":"trace[1818099827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1812; }","duration":"139.943049ms","start":"2026-02-26T01:41:01.263904Z","end":"2026-02-26T01:41:01.403847Z","steps":["trace[1818099827] 'range keys from in-memory index tree' (duration: 139.486189ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:41:03.585365Z","caller":"traceutil/trace.go:171","msg":"trace[1191174647] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1901; }","duration":"154.470884ms","start":"2026-02-26T01:41:03.430863Z","end":"2026-02-26T01:41:03.585334Z","steps":["trace[1191174647] 'read index received' (duration: 72.581552ms)","trace[1191174647] 'applied index is now lower than readState.Index' (duration: 81.888062ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:41:03.585439Z","caller":"traceutil/trace.go:171","msg":"trace[1499621631] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"162.154175ms","start":"2026-02-26T01:41:03.423251Z","end":"2026-02-26T01:41:03.585405Z","steps":["trace[1499621631] 'process raft request' (duration: 80.240393ms)","trace[1499621631] 'compare' (duration: 81.704668ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:41:03.585597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.71235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-26T01:41:03.58565Z","caller":"traceutil/trace.go:171","msg":"trace[428106397] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1823; }","duration":"154.804783ms","start":"2026-02-26T01:41:03.430828Z","end":"2026-02-26T01:41:03.585633Z","steps":["trace[428106397] 'agreement among raft nodes before linearized reading' (duration: 154.629498ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:41:04.432245Z","caller":"traceutil/trace.go:171","msg":"trace[827972172] linearizableReadLoop","detail":"{readStateIndex:1905; appliedIndex:1904; }","duration":"146.473396ms","start":"2026-02-26T01:41:04.28575Z","end":"2026-02-26T01:41:04.432224Z","steps":["trace[827972172] 'read index received' (duration: 146.388664ms)","trace[827972172] 'applied index is now lower than readState.Index' (duration: 83.692µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:41:04.432352Z","caller":"traceutil/trace.go:171","msg":"trace[1214780161] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"200.899145ms","start":"2026-02-26T01:41:04.231442Z","end":"2026-02-26T01:41:04.432341Z","steps":["trace[1214780161] 'process raft request' (duration: 200.613819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:41:04.432603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.843434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-26T01:41:04.432684Z","caller":"traceutil/trace.go:171","msg":"trace[992075780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1826; }","duration":"146.978507ms","start":"2026-02-26T01:41:04.285685Z","end":"2026-02-26T01:41:04.432664Z","steps":["trace[992075780] 'agreement among raft nodes before linearized reading' (duration: 146.720761ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:41:21.640618Z","caller":"traceutil/trace.go:171","msg":"trace[1834449161] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1986; }","duration":"101.890877ms","start":"2026-02-26T01:41:21.538694Z","end":"2026-02-26T01:41:21.640585Z","steps":["trace[1834449161] 'read index received' (duration: 51.504742ms)","trace[1834449161] 'applied index is now lower than readState.Index' (duration: 50.384175ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:41:21.64101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.262446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14480"} {"level":"info","ts":"2026-02-26T01:41:21.641151Z","caller":"traceutil/trace.go:171","msg":"trace[994374355] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1904; }","duration":"102.475521ms","start":"2026-02-26T01:41:21.538655Z","end":"2026-02-26T01:41:21.641131Z","steps":["trace[994374355] 'agreement among raft nodes before linearized reading' (duration: 102.134543ms)"],"step_count":1}