{"level":"warn","ts":"2026-03-17T21:57:39.75142Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-17T21:57:39.751568Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.244:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.244:2380","--initial-cluster=instance=https://199.19.213.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.244:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-17T21:57:39.75165Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-17T21:57:39.751659Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.244:2380"]} {"level":"info","ts":"2026-03-17T21:57:39.751686Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-17T21:57:39.75243Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.244:2379"]} {"level":"info","ts":"2026-03-17T21:57:39.752535Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.244:2380"],"listen-peer-urls":["https://199.19.213.244:2380"],"advertise-client-urls":["https://199.19.213.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.244:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-17T21:57:39.794898Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"42.12221ms"} {"level":"info","ts":"2026-03-17T21:57:39.990029Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"95a1d32286abc938","cluster-id":"f9243fb70f4df4f4"} {"level":"info","ts":"2026-03-17T21:57:39.990269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 switched to configuration voters=()"} {"level":"info","ts":"2026-03-17T21:57:39.99046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became follower at term 0"} {"level":"info","ts":"2026-03-17T21:57:39.990515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 95a1d32286abc938 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-17T21:57:39.990531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became follower at term 1"} {"level":"info","ts":"2026-03-17T21:57:39.990608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 switched to configuration voters=(10782131128143431992)"} {"level":"warn","ts":"2026-03-17T21:57:40.214799Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-17T21:57:40.231703Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-17T21:57:40.279052Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-17T21:57:40.362811Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"95a1d32286abc938","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-17T21:57:40.363132Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-17T21:57:40.364213Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-17T21:57:40.364245Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-17T21:57:40.364459Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"95a1d32286abc938","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-17T21:57:40.368374Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-17T21:57:40.368444Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.244:2380"} {"level":"info","ts":"2026-03-17T21:57:40.368757Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.244:2380"} {"level":"info","ts":"2026-03-17T21:57:40.369934Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"95a1d32286abc938","initial-advertise-peer-urls":["https://199.19.213.244:2380"],"listen-peer-urls":["https://199.19.213.244:2380"],"advertise-client-urls":["https://199.19.213.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-17T21:57:40.369987Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-17T21:57:40.41105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 switched to configuration voters=(10782131128143431992)"} {"level":"info","ts":"2026-03-17T21:57:40.411197Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f9243fb70f4df4f4","local-member-id":"95a1d32286abc938","added-peer-id":"95a1d32286abc938","added-peer-peer-urls":["https://199.19.213.244:2380"]} {"level":"info","ts":"2026-03-17T21:57:40.491559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 is starting a new election at term 1"} {"level":"info","ts":"2026-03-17T21:57:40.491648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-17T21:57:40.491676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 received MsgPreVoteResp from 95a1d32286abc938 at term 1"} {"level":"info","ts":"2026-03-17T21:57:40.491691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became candidate at term 2"} {"level":"info","ts":"2026-03-17T21:57:40.491698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 received MsgVoteResp from 95a1d32286abc938 at term 2"} {"level":"info","ts":"2026-03-17T21:57:40.491708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became leader at term 2"} {"level":"info","ts":"2026-03-17T21:57:40.491717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 95a1d32286abc938 elected leader 95a1d32286abc938 at term 2"} {"level":"info","ts":"2026-03-17T21:57:40.584386Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"95a1d32286abc938","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.244:2379]}","request-path":"/0/members/95a1d32286abc938/attributes","cluster-id":"f9243fb70f4df4f4","publish-timeout":"7s"} {"level":"info","ts":"2026-03-17T21:57:40.584455Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-17T21:57:40.584906Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:57:40.585207Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-17T21:57:40.587229Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-17T21:57:40.587544Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.244:2379"} {"level":"info","ts":"2026-03-17T21:57:40.58867Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-17T21:57:40.5887Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-17T21:57:40.606082Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f9243fb70f4df4f4","local-member-id":"95a1d32286abc938","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:57:40.606294Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:57:40.606338Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:57:43.839268Z","caller":"traceutil/trace.go:171","msg":"trace[1047312441] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"103.442455ms","start":"2026-03-17T21:57:43.735564Z","end":"2026-03-17T21:57:43.839007Z","steps":["trace[1047312441] 'process raft request' (duration: 37.183191ms)","trace[1047312441] 'compare' (duration: 66.091169ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:57:44.144224Z","caller":"traceutil/trace.go:171","msg":"trace[2014563575] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"258.912745ms","start":"2026-03-17T21:57:43.885283Z","end":"2026-03-17T21:57:44.144195Z","steps":["trace[2014563575] 'process raft request' (duration: 239.269987ms)","trace[2014563575] 'compare' (duration: 19.509165ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:57:45.128617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.249127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-17T21:57:45.128786Z","caller":"traceutil/trace.go:171","msg":"trace[1821625263] linearizableReadLoop","detail":"{readStateIndex:104; appliedIndex:103; }","duration":"499.845688ms","start":"2026-03-17T21:57:44.628918Z","end":"2026-03-17T21:57:45.128764Z","steps":["trace[1821625263] 'read index received' (duration: 46.763µs)","trace[1821625263] 'applied index is now lower than readState.Index' (duration: 499.797035ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:57:45.128871Z","caller":"traceutil/trace.go:171","msg":"trace[1813604008] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"573.381339ms","start":"2026-03-17T21:57:44.55544Z","end":"2026-03-17T21:57:45.128822Z","steps":["trace[1813604008] 'process raft request' (duration: 55.241359ms)","trace[1813604008] 'compare' (duration: 517.037472ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:57:45.12913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.161205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-17T21:57:45.129285Z","caller":"traceutil/trace.go:171","msg":"trace[1245281680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:100; }","duration":"500.416252ms","start":"2026-03-17T21:57:44.62885Z","end":"2026-03-17T21:57:45.129266Z","steps":["trace[1245281680] 'agreement among raft nodes before linearized reading' (duration: 500.036432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:45.129094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:44.555399Z","time spent":"573.582914ms","remote":"127.0.0.1:38922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T21:57:45.129388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:44.628822Z","time spent":"500.551675ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-17T21:57:51.700126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027549335s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:57:51.700332Z","caller":"traceutil/trace.go:171","msg":"trace[1717617627] linearizableReadLoop","detail":"{readStateIndex:304; appliedIndex:303; }","duration":"1.228510362s","start":"2026-03-17T21:57:50.471746Z","end":"2026-03-17T21:57:51.700256Z","steps":["trace[1717617627] 'read index received' (duration: 200.608368ms)","trace[1717617627] 'applied index is now lower than readState.Index' (duration: 1.027897404s)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:57:51.70064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21101449s","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-03-17T21:57:51.700699Z","caller":"traceutil/trace.go:171","msg":"trace[1400627277] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:293; }","duration":"1.211119122s","start":"2026-03-17T21:57:50.489565Z","end":"2026-03-17T21:57:51.700684Z","steps":["trace[1400627277] 'agreement among raft nodes before linearized reading' (duration: 1.210972039s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:51.70074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:50.489524Z","time spent":"1.211206104s","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-17T21:57:51.700729Z","caller":"traceutil/trace.go:171","msg":"trace[914819725] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"1.268870596s","start":"2026-03-17T21:57:50.431803Z","end":"2026-03-17T21:57:51.700673Z","steps":["trace[914819725] 'process raft request' (duration: 240.667354ms)","trace[914819725] 'compare' (duration: 1.027269648s)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:57:51.700878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229110578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T21:57:51.700922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:50.431763Z","time spent":"1.269068961s","remote":"127.0.0.1:38768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T21:57:51.700924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.52695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-17T21:57:51.700985Z","caller":"traceutil/trace.go:171","msg":"trace[207559433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:293; }","duration":"1.229283072s","start":"2026-03-17T21:57:50.471678Z","end":"2026-03-17T21:57:51.700962Z","steps":["trace[207559433] 'agreement among raft nodes before linearized reading' (duration: 1.228774909s)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:51.701022Z","caller":"traceutil/trace.go:171","msg":"trace[122458327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:293; }","duration":"506.696044ms","start":"2026-03-17T21:57:51.194301Z","end":"2026-03-17T21:57:51.700997Z","steps":["trace[122458327] 'agreement among raft nodes before linearized reading' (duration: 506.482949ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:51.701056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:50.471646Z","time spent":"1.229399325s","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-17T21:57:51.701111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:51.194261Z","time spent":"506.805967ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-17T21:57:52.44256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.656892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:57:52.44304Z","caller":"traceutil/trace.go:171","msg":"trace[1282425856] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"737.721899ms","start":"2026-03-17T21:57:51.70528Z","end":"2026-03-17T21:57:52.443002Z","steps":["trace[1282425856] 'process raft request' (duration: 372.295697ms)","trace[1282425856] 'compare' (duration: 363.906922ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:57:52.443121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:51.705274Z","time spent":"737.812902ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:57:52.443414Z","caller":"traceutil/trace.go:171","msg":"trace[1056618171] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"737.685738ms","start":"2026-03-17T21:57:51.705679Z","end":"2026-03-17T21:57:52.443365Z","steps":["trace[1056618171] 'process raft request' (duration: 737.031351ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:52.443612Z","caller":"traceutil/trace.go:171","msg":"trace[2126135679] linearizableReadLoop","detail":"{readStateIndex:307; appliedIndex:304; }","duration":"737.618217ms","start":"2026-03-17T21:57:51.705973Z","end":"2026-03-17T21:57:52.443592Z","steps":["trace[2126135679] 'read index received' (duration: 31.428503ms)","trace[2126135679] 'applied index is now lower than readState.Index' (duration: 706.187664ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:57:52.443801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.804991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-17T21:57:52.443863Z","caller":"traceutil/trace.go:171","msg":"trace[265150751] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:296; }","duration":"737.885553ms","start":"2026-03-17T21:57:51.705963Z","end":"2026-03-17T21:57:52.443848Z","steps":["trace[265150751] 'agreement among raft nodes before linearized reading' (duration: 737.76587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:52.443858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:51.705672Z","time spent":"737.858302ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:57:52.443905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:51.705958Z","time spent":"737.935674ms","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-17T21:57:52.444846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.870135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-17T21:57:52.444963Z","caller":"traceutil/trace.go:171","msg":"trace[1089439589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:297; }","duration":"704.05796ms","start":"2026-03-17T21:57:51.740883Z","end":"2026-03-17T21:57:52.444941Z","steps":["trace[1089439589] 'agreement among raft nodes before linearized reading' (duration: 703.830893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:52.445012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:51.740861Z","time spent":"704.142052ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-17T21:57:52.4451Z","caller":"traceutil/trace.go:171","msg":"trace[1927276064] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"702.875849ms","start":"2026-03-17T21:57:51.742179Z","end":"2026-03-17T21:57:52.445055Z","steps":["trace[1927276064] 'process raft request' (duration: 701.529174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:52.445317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.712386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-17T21:57:52.445394Z","caller":"traceutil/trace.go:171","msg":"trace[1022043244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:297; }","duration":"472.827439ms","start":"2026-03-17T21:57:51.972538Z","end":"2026-03-17T21:57:52.445365Z","steps":["trace[1022043244] 'agreement among raft nodes before linearized reading' (duration: 472.650354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:52.445441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:51.972498Z","time spent":"472.933772ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-17T21:57:52.445353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:51.742165Z","time spent":"703.113195ms","remote":"127.0.0.1:38648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T21:58:21.525669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.17872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-588mc\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-17T21:58:21.525828Z","caller":"traceutil/trace.go:171","msg":"trace[1245686958] range","detail":"{range_begin:/registry/pods/kube-system/cilium-588mc; range_end:; response_count:1; response_revision:495; }","duration":"449.408685ms","start":"2026-03-17T21:58:21.076391Z","end":"2026-03-17T21:58:21.5258Z","steps":["trace[1245686958] 'range keys from in-memory index tree' (duration: 448.785871ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:21.526093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:21.076363Z","time spent":"449.711954ms","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-588mc\" "} {"level":"warn","ts":"2026-03-17T21:58:22.069511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.756497ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-17T21:58:22.069651Z","caller":"traceutil/trace.go:171","msg":"trace[2458256] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:513; }","duration":"728.233848ms","start":"2026-03-17T21:58:21.341394Z","end":"2026-03-17T21:58:22.069627Z","steps":["trace[2458256] 'read index received' (duration: 214.361248ms)","trace[2458256] 'applied index is now lower than readState.Index' (duration: 513.87067ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:22.069775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.381851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:22.069813Z","caller":"traceutil/trace.go:171","msg":"trace[1440370361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"728.431582ms","start":"2026-03-17T21:58:21.341369Z","end":"2026-03-17T21:58:22.0698Z","steps":["trace[1440370361] 'agreement among raft nodes before linearized reading' (duration: 728.34367ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:22.069852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:21.341276Z","time spent":"728.567905ms","remote":"127.0.0.1:38600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-17T21:58:22.096234Z","caller":"traceutil/trace.go:171","msg":"trace[819650018] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"556.731143ms","start":"2026-03-17T21:58:21.539479Z","end":"2026-03-17T21:58:22.096211Z","steps":["trace[819650018] 'process raft request' (duration: 556.557969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:22.096383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:21.539442Z","time spent":"556.854796ms","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:58:22.524272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.882373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:22.524616Z","caller":"traceutil/trace.go:171","msg":"trace[718414974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"183.295253ms","start":"2026-03-17T21:58:22.341287Z","end":"2026-03-17T21:58:22.524582Z","steps":["trace[718414974] 'range keys from in-memory index tree' (duration: 182.72239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:23.211079Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499511614215559774,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T21:58:23.711746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499511614215559774,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T21:58:23.979965Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.281791286s","expected-duration":"1s"} {"level":"info","ts":"2026-03-17T21:58:23.980547Z","caller":"traceutil/trace.go:171","msg":"trace[1606876164] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.282498093s","start":"2026-03-17T21:58:22.698015Z","end":"2026-03-17T21:58:23.980513Z","steps":["trace[1606876164] 'process raft request' (duration: 1.282270807s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:23.980792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:22.697994Z","time spent":"1.282671996s","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:58:23.989836Z","caller":"traceutil/trace.go:171","msg":"trace[582540623] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:515; }","duration":"1.279617144s","start":"2026-03-17T21:58:22.710186Z","end":"2026-03-17T21:58:23.989803Z","steps":["trace[582540623] 'read index received' (duration: 1.27011981s)","trace[582540623] 'applied index is now lower than readState.Index' (duration: 9.495854ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:23.989976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.279777018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-17T21:58:23.990006Z","caller":"traceutil/trace.go:171","msg":"trace[1076125408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:498; }","duration":"1.279881471s","start":"2026-03-17T21:58:22.710116Z","end":"2026-03-17T21:58:23.989997Z","steps":["trace[1076125408] 'agreement among raft nodes before linearized reading' (duration: 1.279798869s)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:23.989987Z","caller":"traceutil/trace.go:171","msg":"trace[1759261379] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"385.903814ms","start":"2026-03-17T21:58:23.60403Z","end":"2026-03-17T21:58:23.989934Z","steps":["trace[1759261379] 'process raft request' (duration: 385.651528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:23.990035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:22.71009Z","time spent":"1.279937921s","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-17T21:58:23.990077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268216775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-17T21:58:23.99016Z","caller":"traceutil/trace.go:171","msg":"trace[793614608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"1.268354928s","start":"2026-03-17T21:58:22.721784Z","end":"2026-03-17T21:58:23.990139Z","steps":["trace[793614608] 'agreement among raft nodes before linearized reading' (duration: 1.268193654s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:23.990181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:23.603991Z","time spent":"386.082308ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:58:23.990211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:22.721772Z","time spent":"1.268431199s","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-17T21:58:23.990215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.215084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:23.990366Z","caller":"traceutil/trace.go:171","msg":"trace[1265548248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"513.44444ms","start":"2026-03-17T21:58:23.476897Z","end":"2026-03-17T21:58:23.990341Z","steps":["trace[1265548248] 'agreement among raft nodes before linearized reading' (duration: 513.224404ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:23.99042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:23.47686Z","time spent":"513.550251ms","remote":"127.0.0.1:38588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-17T21:58:23.990582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257654375s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-17T21:58:23.990699Z","caller":"traceutil/trace.go:171","msg":"trace[868402621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:498; }","duration":"1.257826599s","start":"2026-03-17T21:58:22.732844Z","end":"2026-03-17T21:58:23.99067Z","steps":["trace[868402621] 'agreement among raft nodes before linearized reading' (duration: 1.257359248s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:23.990758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:22.732797Z","time spent":"1.257950692s","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-17T21:58:25.532596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.858259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T21:58:25.532596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.251929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:25.532692Z","caller":"traceutil/trace.go:171","msg":"trace[1381371384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"278.033564ms","start":"2026-03-17T21:58:25.254636Z","end":"2026-03-17T21:58:25.53267Z","steps":["trace[1381371384] 'range keys from in-memory index tree' (duration: 277.757946ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:25.532697Z","caller":"traceutil/trace.go:171","msg":"trace[593117519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:512; }","duration":"267.461804ms","start":"2026-03-17T21:58:25.26522Z","end":"2026-03-17T21:58:25.532682Z","steps":["trace[593117519] 'range keys from in-memory index tree' (duration: 267.154357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:25.532664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.457687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:25.532772Z","caller":"traceutil/trace.go:171","msg":"trace[1209350950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"191.649192ms","start":"2026-03-17T21:58:25.341099Z","end":"2026-03-17T21:58:25.532748Z","steps":["trace[1209350950] 'range keys from in-memory index tree' (duration: 191.305134ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:27.291733Z","caller":"traceutil/trace.go:171","msg":"trace[1608764153] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"155.944527ms","start":"2026-03-17T21:58:27.135755Z","end":"2026-03-17T21:58:27.291699Z","steps":["trace[1608764153] 'process raft request' (duration: 155.670881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:27.555625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.185861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:58:27.555769Z","caller":"traceutil/trace.go:171","msg":"trace[72477894] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"217.117194ms","start":"2026-03-17T21:58:27.33863Z","end":"2026-03-17T21:58:27.555747Z","steps":["trace[72477894] 'read index received' (duration: 99.513454ms)","trace[72477894] 'applied index is now lower than readState.Index' (duration: 117.60251ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:27.555948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.317178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:27.555976Z","caller":"traceutil/trace.go:171","msg":"trace[87637522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"217.40401ms","start":"2026-03-17T21:58:27.338562Z","end":"2026-03-17T21:58:27.555966Z","steps":["trace[87637522] 'agreement among raft nodes before linearized reading' (duration: 217.297368ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:27.556566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.43213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-03-17T21:58:27.55665Z","caller":"traceutil/trace.go:171","msg":"trace[383101737] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:549; }","duration":"206.582234ms","start":"2026-03-17T21:58:27.350049Z","end":"2026-03-17T21:58:27.556631Z","steps":["trace[383101737] 'agreement among raft nodes before linearized reading' (duration: 206.43113ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:27.55749Z","caller":"traceutil/trace.go:171","msg":"trace[2079961229] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"402.245541ms","start":"2026-03-17T21:58:27.155177Z","end":"2026-03-17T21:58:27.557423Z","steps":["trace[2079961229] 'process raft request' (duration: 283.164205ms)","trace[2079961229] 'compare' (duration: 116.990636ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:27.557691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:27.155165Z","time spent":"402.431315ms","remote":"127.0.0.1:38632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T21:58:27.800625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.151547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:58:27.800798Z","caller":"traceutil/trace.go:171","msg":"trace[1314328968] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"168.124016ms","start":"2026-03-17T21:58:27.632648Z","end":"2026-03-17T21:58:27.800772Z","steps":["trace[1314328968] 'process raft request' (duration: 37.733893ms)","trace[1314328968] 'compare' (duration: 129.911911ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:28.153283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.429515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:58:28.15363Z","caller":"traceutil/trace.go:171","msg":"trace[956320834] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"403.218044ms","start":"2026-03-17T21:58:27.750391Z","end":"2026-03-17T21:58:28.153609Z","steps":["trace[956320834] 'read index received' (duration: 266.383759ms)","trace[956320834] 'applied index is now lower than readState.Index' (duration: 136.832874ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:58:28.153665Z","caller":"traceutil/trace.go:171","msg":"trace[1152924289] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"513.664584ms","start":"2026-03-17T21:58:27.639967Z","end":"2026-03-17T21:58:28.153631Z","steps":["trace[1152924289] 'process raft request' (duration: 376.770188ms)","trace[1152924289] 'compare' (duration: 136.20831ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:28.153779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.388857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-03-17T21:58:28.153817Z","caller":"traceutil/trace.go:171","msg":"trace[1717553593] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:553; }","duration":"403.480709ms","start":"2026-03-17T21:58:27.750321Z","end":"2026-03-17T21:58:28.153802Z","steps":["trace[1717553593] 'agreement among raft nodes before linearized reading' (duration: 403.377587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:28.153843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:27.639936Z","time spent":"513.809148ms","remote":"127.0.0.1:38632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:58:28.153858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:27.750293Z","time spent":"403.556031ms","remote":"127.0.0.1:38632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4107,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-03-17T21:58:28.154191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.772568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-03-17T21:58:28.154271Z","caller":"traceutil/trace.go:171","msg":"trace[1757209869] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:554; }","duration":"203.877831ms","start":"2026-03-17T21:58:27.950352Z","end":"2026-03-17T21:58:28.15423Z","steps":["trace[1757209869] 'agreement among raft nodes before linearized reading' (duration: 203.780379ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:28.154381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.270954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-17T21:58:28.154382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.475876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-17T21:58:28.154424Z","caller":"traceutil/trace.go:171","msg":"trace[1431297642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:554; }","duration":"120.338075ms","start":"2026-03-17T21:58:28.034075Z","end":"2026-03-17T21:58:28.154413Z","steps":["trace[1431297642] 'agreement among raft nodes before linearized reading' (duration: 120.1284ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:28.154412Z","caller":"traceutil/trace.go:171","msg":"trace[2092553275] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"332.506101ms","start":"2026-03-17T21:58:27.821872Z","end":"2026-03-17T21:58:28.154378Z","steps":["trace[2092553275] 'process raft request' (duration: 332.148123ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:28.154482Z","caller":"traceutil/trace.go:171","msg":"trace[1784630784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:554; }","duration":"119.602348ms","start":"2026-03-17T21:58:28.034855Z","end":"2026-03-17T21:58:28.154457Z","steps":["trace[1784630784] 'agreement among raft nodes before linearized reading' (duration: 119.303361ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:28.154485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.417846ms","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-03-17T21:58:28.154596Z","caller":"traceutil/trace.go:171","msg":"trace[271484250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:554; }","duration":"158.59522ms","start":"2026-03-17T21:58:27.995971Z","end":"2026-03-17T21:58:28.154566Z","steps":["trace[271484250] 'agreement among raft nodes before linearized reading' (duration: 158.403885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:28.154596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:27.82184Z","time spent":"332.666905ms","remote":"127.0.0.1:38648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T21:58:28.155108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.684494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-17T21:58:28.155171Z","caller":"traceutil/trace.go:171","msg":"trace[777264623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:554; }","duration":"120.766106ms","start":"2026-03-17T21:58:28.034391Z","end":"2026-03-17T21:58:28.155157Z","steps":["trace[777264623] 'agreement among raft nodes before linearized reading' (duration: 119.834554ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:28.496227Z","caller":"traceutil/trace.go:171","msg":"trace[1155983051] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"142.401407ms","start":"2026-03-17T21:58:28.353795Z","end":"2026-03-17T21:58:28.496196Z","steps":["trace[1155983051] 'process raft request' (duration: 142.226883ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:28.635918Z","caller":"traceutil/trace.go:171","msg":"trace[251214899] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"129.474282ms","start":"2026-03-17T21:58:28.506407Z","end":"2026-03-17T21:58:28.635881Z","steps":["trace[251214899] 'process raft request' (duration: 84.207322ms)","trace[251214899] 'compare' (duration: 45.122616ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:58:29.151729Z","caller":"traceutil/trace.go:171","msg":"trace[1110025427] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"172.874988ms","start":"2026-03-17T21:58:28.978827Z","end":"2026-03-17T21:58:29.151702Z","steps":["trace[1110025427] 'process raft request' (duration: 172.299863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:42.824643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499511614215560114,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-17T21:58:43.01163Z","caller":"traceutil/trace.go:171","msg":"trace[489650316] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"721.778684ms","start":"2026-03-17T21:58:42.289833Z","end":"2026-03-17T21:58:43.011611Z","steps":["trace[489650316] 'process raft request' (duration: 721.507748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:43.011797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:42.289817Z","time spent":"721.887316ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:58:43.011916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.016783ms","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-03-17T21:58:43.011986Z","caller":"traceutil/trace.go:171","msg":"trace[1733760501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:684; }","duration":"688.111104ms","start":"2026-03-17T21:58:42.323858Z","end":"2026-03-17T21:58:43.011969Z","steps":["trace[1733760501] 'agreement among raft nodes before linearized reading' (duration: 687.875429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:43.012024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:42.323846Z","time spent":"688.170187ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-17T21:58:43.011551Z","caller":"traceutil/trace.go:171","msg":"trace[1131690880] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"687.626384ms","start":"2026-03-17T21:58:42.323892Z","end":"2026-03-17T21:58:43.011518Z","steps":["trace[1131690880] 'read index received' (duration: 687.362868ms)","trace[1131690880] 'applied index is now lower than readState.Index' (duration: 262.276µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:43.012341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.688515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-17T21:58:43.012388Z","caller":"traceutil/trace.go:171","msg":"trace[959294855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:684; }","duration":"682.782007ms","start":"2026-03-17T21:58:42.329595Z","end":"2026-03-17T21:58:43.012377Z","steps":["trace[959294855] 'agreement among raft nodes before linearized reading' (duration: 682.682585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:43.012417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:42.329566Z","time spent":"682.845058ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-17T21:58:43.012421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.450331ms","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-03-17T21:58:43.012538Z","caller":"traceutil/trace.go:171","msg":"trace[397963758] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:684; }","duration":"344.669218ms","start":"2026-03-17T21:58:42.667842Z","end":"2026-03-17T21:58:43.012511Z","steps":["trace[397963758] 'agreement among raft nodes before linearized reading' (duration: 344.420471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:43.012622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:42.667813Z","time spent":"344.796032ms","remote":"127.0.0.1:38814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-03-17T21:58:43.012757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.178767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-17T21:58:43.012795Z","caller":"traceutil/trace.go:171","msg":"trace[1111767765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:684; }","duration":"683.261319ms","start":"2026-03-17T21:58:42.329521Z","end":"2026-03-17T21:58:43.012783Z","steps":["trace[1111767765] 'agreement among raft nodes before linearized reading' (duration: 682.675834ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:43.012822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:42.329496Z","time spent":"683.32034ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-17T21:58:43.466853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.992763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:58:43.467152Z","caller":"traceutil/trace.go:171","msg":"trace[176330293] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:708; }","duration":"126.18327ms","start":"2026-03-17T21:58:43.340945Z","end":"2026-03-17T21:58:43.467128Z","steps":["trace[176330293] 'read index received' (duration: 29.471357ms)","trace[176330293] 'applied index is now lower than readState.Index' (duration: 96.710473ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:58:43.467286Z","caller":"traceutil/trace.go:171","msg":"trace[66772717] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"449.889495ms","start":"2026-03-17T21:58:43.017384Z","end":"2026-03-17T21:58:43.467273Z","steps":["trace[66772717] 'process raft request' (duration: 449.624978ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:43.467249Z","caller":"traceutil/trace.go:171","msg":"trace[1111886271] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"449.986677ms","start":"2026-03-17T21:58:43.017225Z","end":"2026-03-17T21:58:43.467212Z","steps":["trace[1111886271] 'process raft request' (duration: 274.464132ms)","trace[1111886271] 'compare' (duration: 174.791338ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:58:43.467378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:43.017353Z","time spent":"449.970287ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:58:43.467434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:43.017198Z","time spent":"450.133851ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:58:43.467395Z","caller":"traceutil/trace.go:171","msg":"trace[831725380] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"449.69203ms","start":"2026-03-17T21:58:43.017651Z","end":"2026-03-17T21:58:43.467343Z","steps":["trace[831725380] 'process raft request' (duration: 449.424644ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:58:43.467615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:58:43.017638Z","time spent":"449.858054ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:58:43.46906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.107525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:58:43.469122Z","caller":"traceutil/trace.go:171","msg":"trace[1209489737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"128.23092ms","start":"2026-03-17T21:58:43.340877Z","end":"2026-03-17T21:58:43.469108Z","steps":["trace[1209489737] 'agreement among raft nodes before linearized reading' (duration: 127.142853ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:45.277171Z","caller":"traceutil/trace.go:171","msg":"trace[532900073] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"256.561942ms","start":"2026-03-17T21:58:45.020576Z","end":"2026-03-17T21:58:45.277138Z","steps":["trace[532900073] 'process raft request' (duration: 256.351387ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:01.454615Z","caller":"traceutil/trace.go:171","msg":"trace[2121257021] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"116.520449ms","start":"2026-03-17T21:59:01.338073Z","end":"2026-03-17T21:59:01.454593Z","steps":["trace[2121257021] 'read index received' (duration: 110.562666ms)","trace[2121257021] 'applied index is now lower than readState.Index' (duration: 5.956783ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:59:01.454749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.665322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:59:01.45477Z","caller":"traceutil/trace.go:171","msg":"trace[1221049113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"116.745334ms","start":"2026-03-17T21:59:01.338018Z","end":"2026-03-17T21:59:01.454764Z","steps":["trace[1221049113] 'agreement among raft nodes before linearized reading' (duration: 116.680063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:09.65877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.030073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:59:09.658853Z","caller":"traceutil/trace.go:171","msg":"trace[160386040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"312.120535ms","start":"2026-03-17T21:59:09.346714Z","end":"2026-03-17T21:59:09.658834Z","steps":["trace[160386040] 'range keys from in-memory index tree' (duration: 311.928711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:09.65893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:09.346653Z","time spent":"312.265978ms","remote":"127.0.0.1:38588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-17T21:59:09.658796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.782959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-17T21:59:09.659528Z","caller":"traceutil/trace.go:171","msg":"trace[746185035] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:764; }","duration":"158.582289ms","start":"2026-03-17T21:59:09.500931Z","end":"2026-03-17T21:59:09.659513Z","steps":["trace[746185035] 'range keys from in-memory index tree' (duration: 157.607635ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:10.07971Z","caller":"traceutil/trace.go:171","msg":"trace[1608490337] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"415.94477ms","start":"2026-03-17T21:59:09.663739Z","end":"2026-03-17T21:59:10.079684Z","steps":["trace[1608490337] 'process raft request' (duration: 415.867448ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:10.079727Z","caller":"traceutil/trace.go:171","msg":"trace[886061434] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"416.108004ms","start":"2026-03-17T21:59:09.663581Z","end":"2026-03-17T21:59:10.079688Z","steps":["trace[886061434] 'process raft request' (duration: 398.4604ms)","trace[886061434] 'compare' (duration: 17.444319ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:59:10.079888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:09.663733Z","time spent":"416.055352ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:59:10.079927Z","caller":"traceutil/trace.go:171","msg":"trace[929169917] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:795; }","duration":"182.34949ms","start":"2026-03-17T21:59:09.897546Z","end":"2026-03-17T21:59:10.079895Z","steps":["trace[929169917] 'read index received' (duration: 164.405398ms)","trace[929169917] 'applied index is now lower than readState.Index' (duration: 17.942352ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:59:10.079987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:09.66357Z","time spent":"416.249547ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:59:10.080043Z","caller":"traceutil/trace.go:171","msg":"trace[2032500109] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"416.238197ms","start":"2026-03-17T21:59:09.663794Z","end":"2026-03-17T21:59:10.080032Z","steps":["trace[2032500109] 'process raft request' (duration: 415.855218ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:10.080104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:09.663774Z","time spent":"416.301619ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:59:10.080205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.645056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-17T21:59:10.080249Z","caller":"traceutil/trace.go:171","msg":"trace[552664021] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:768; }","duration":"182.747289ms","start":"2026-03-17T21:59:09.897488Z","end":"2026-03-17T21:59:10.080236Z","steps":["trace[552664021] 'agreement among raft nodes before linearized reading' (duration: 182.519763ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:10.269366Z","caller":"traceutil/trace.go:171","msg":"trace[925976868] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"184.401589ms","start":"2026-03-17T21:59:10.084937Z","end":"2026-03-17T21:59:10.269338Z","steps":["trace[925976868] 'process raft request' (duration: 162.330819ms)","trace[925976868] 'compare' (duration: 21.799723ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:59:11.86643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.84844ms","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-03-17T21:59:11.866505Z","caller":"traceutil/trace.go:171","msg":"trace[1735588984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:769; }","duration":"177.976044ms","start":"2026-03-17T21:59:11.688517Z","end":"2026-03-17T21:59:11.866493Z","steps":["trace[1735588984] 'agreement among raft nodes before linearized reading' (duration: 177.710307ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:11.866167Z","caller":"traceutil/trace.go:171","msg":"trace[1150345045] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"177.531273ms","start":"2026-03-17T21:59:11.688571Z","end":"2026-03-17T21:59:11.866103Z","steps":["trace[1150345045] 'read index received' (duration: 86.562939ms)","trace[1150345045] 'applied index is now lower than readState.Index' (duration: 90.966564ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:59:12.009886Z","caller":"traceutil/trace.go:171","msg":"trace[509653270] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"136.638011ms","start":"2026-03-17T21:59:11.873224Z","end":"2026-03-17T21:59:12.009862Z","steps":["trace[509653270] 'process raft request' (duration: 136.481107ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:22.540791Z","caller":"traceutil/trace.go:171","msg":"trace[1576652604] linearizableReadLoop","detail":"{readStateIndex:888; appliedIndex:887; }","duration":"201.079119ms","start":"2026-03-17T21:59:22.339697Z","end":"2026-03-17T21:59:22.540776Z","steps":["trace[1576652604] 'read index received' (duration: 200.983047ms)","trace[1576652604] 'applied index is now lower than readState.Index' (duration: 95.462µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:59:22.540846Z","caller":"traceutil/trace.go:171","msg":"trace[70669366] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"202.002841ms","start":"2026-03-17T21:59:22.338809Z","end":"2026-03-17T21:59:22.540812Z","steps":["trace[70669366] 'process raft request' (duration: 201.853208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:22.540913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.201552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:59:22.540938Z","caller":"traceutil/trace.go:171","msg":"trace[841806443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:854; }","duration":"201.276204ms","start":"2026-03-17T21:59:22.339654Z","end":"2026-03-17T21:59:22.54093Z","steps":["trace[841806443] 'agreement among raft nodes before linearized reading' (duration: 201.220373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:41.441836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.198626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:59:41.441948Z","caller":"traceutil/trace.go:171","msg":"trace[1524023228] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"822.977255ms","start":"2026-03-17T21:59:40.61895Z","end":"2026-03-17T21:59:41.441927Z","steps":["trace[1524023228] 'process raft request' (duration: 625.588194ms)","trace[1524023228] 'compare' (duration: 197.101084ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:59:41.442006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:40.618933Z","time spent":"823.045136ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:59:41.823416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.006755ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-17T21:59:41.823658Z","caller":"traceutil/trace.go:171","msg":"trace[2074364744] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"483.086262ms","start":"2026-03-17T21:59:41.340493Z","end":"2026-03-17T21:59:41.823579Z","steps":["trace[2074364744] 'read index received' (duration: 334.81749ms)","trace[2074364744] 'applied index is now lower than readState.Index' (duration: 148.266501ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:59:41.823797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.286996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:59:41.823935Z","caller":"traceutil/trace.go:171","msg":"trace[1740063941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"483.487311ms","start":"2026-03-17T21:59:41.34043Z","end":"2026-03-17T21:59:41.823917Z","steps":["trace[1740063941] 'agreement among raft nodes before linearized reading' (duration: 483.296996ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:41.823999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:41.340398Z","time spent":"483.586903ms","remote":"127.0.0.1:38600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-17T21:59:43.57287Z","caller":"traceutil/trace.go:171","msg":"trace[1844102802] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"125.999322ms","start":"2026-03-17T21:59:43.446844Z","end":"2026-03-17T21:59:43.572843Z","steps":["trace[1844102802] 'read index received' (duration: 125.747255ms)","trace[1844102802] 'applied index is now lower than readState.Index' (duration: 251.067µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:59:43.572968Z","caller":"traceutil/trace.go:171","msg":"trace[749413699] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"196.158724ms","start":"2026-03-17T21:59:43.376753Z","end":"2026-03-17T21:59:43.572912Z","steps":["trace[749413699] 'process raft request' (duration: 195.933229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:43.573321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.428152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-17T21:59:43.573552Z","caller":"traceutil/trace.go:171","msg":"trace[784512312] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:931; }","duration":"126.567495ms","start":"2026-03-17T21:59:43.446792Z","end":"2026-03-17T21:59:43.573359Z","steps":["trace[784512312] 'agreement among raft nodes before linearized reading' (duration: 126.185546ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:43.57345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.089768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:59:43.573634Z","caller":"traceutil/trace.go:171","msg":"trace[361229798] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:931; }","duration":"111.373414ms","start":"2026-03-17T21:59:43.462238Z","end":"2026-03-17T21:59:43.573611Z","steps":["trace[361229798] 'agreement among raft nodes before linearized reading' (duration: 111.109338ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:43.683993Z","caller":"traceutil/trace.go:171","msg":"trace[1501945531] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"104.271175ms","start":"2026-03-17T21:59:43.579688Z","end":"2026-03-17T21:59:43.683959Z","steps":["trace[1501945531] 'process raft request' (duration: 104.194553ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:43.684004Z","caller":"traceutil/trace.go:171","msg":"trace[2138751189] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"104.593063ms","start":"2026-03-17T21:59:43.579374Z","end":"2026-03-17T21:59:43.683967Z","steps":["trace[2138751189] 'process raft request' (duration: 46.034213ms)","trace[2138751189] 'compare' (duration: 58.332385ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:59:43.830598Z","caller":"traceutil/trace.go:171","msg":"trace[679164641] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"117.244784ms","start":"2026-03-17T21:59:43.713327Z","end":"2026-03-17T21:59:43.830571Z","steps":["trace[679164641] 'process raft request' (duration: 70.77915ms)","trace[679164641] 'compare' (duration: 46.28676ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:59:44.660829Z","caller":"traceutil/trace.go:171","msg":"trace[1621283134] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"320.453189ms","start":"2026-03-17T21:59:44.340354Z","end":"2026-03-17T21:59:44.660808Z","steps":["trace[1621283134] 'read index received' (duration: 320.268334ms)","trace[1621283134] 'applied index is now lower than readState.Index' (duration: 183.705µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:59:44.66095Z","caller":"traceutil/trace.go:171","msg":"trace[1117748492] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"343.478596ms","start":"2026-03-17T21:59:44.317461Z","end":"2026-03-17T21:59:44.660939Z","steps":["trace[1117748492] 'process raft request' (duration: 343.174139ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:44.661091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:44.317439Z","time spent":"343.541458ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:59:44.661244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.780774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-17T21:59:44.661308Z","caller":"traceutil/trace.go:171","msg":"trace[2028701707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:938; }","duration":"298.929848ms","start":"2026-03-17T21:59:44.362363Z","end":"2026-03-17T21:59:44.661292Z","steps":["trace[2028701707] 'agreement among raft nodes before linearized reading' (duration: 298.702723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:44.661496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.546091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-17T21:59:44.661522Z","caller":"traceutil/trace.go:171","msg":"trace[2041588650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:938; }","duration":"237.606362ms","start":"2026-03-17T21:59:44.423907Z","end":"2026-03-17T21:59:44.661514Z","steps":["trace[2041588650] 'agreement among raft nodes before linearized reading' (duration: 237.53229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:44.661539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.384184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-17T21:59:44.661584Z","caller":"traceutil/trace.go:171","msg":"trace[135459690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:938; }","duration":"215.452205ms","start":"2026-03-17T21:59:44.44612Z","end":"2026-03-17T21:59:44.661572Z","steps":["trace[135459690] 'agreement among raft nodes before linearized reading' (duration: 215.301012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:44.661602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.254347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:59:44.661664Z","caller":"traceutil/trace.go:171","msg":"trace[1377168465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"321.315519ms","start":"2026-03-17T21:59:44.340303Z","end":"2026-03-17T21:59:44.661619Z","steps":["trace[1377168465] 'agreement among raft nodes before linearized reading' (duration: 321.278658ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:44.661699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:44.340279Z","time spent":"321.4132ms","remote":"127.0.0.1:38600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-17T21:59:44.80856Z","caller":"traceutil/trace.go:171","msg":"trace[1553004568] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"142.418861ms","start":"2026-03-17T21:59:44.666112Z","end":"2026-03-17T21:59:44.808531Z","steps":["trace[1553004568] 'process raft request' (duration: 76.562047ms)","trace[1553004568] 'compare' (duration: 65.721591ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:59:45.357937Z","caller":"traceutil/trace.go:171","msg":"trace[1781978356] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"691.624072ms","start":"2026-03-17T21:59:44.666281Z","end":"2026-03-17T21:59:45.357905Z","steps":["trace[1781978356] 'process raft request' (duration: 691.329715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:45.358102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:44.666198Z","time spent":"691.814245ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:59:45.358352Z","caller":"traceutil/trace.go:171","msg":"trace[1490577559] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"691.958279ms","start":"2026-03-17T21:59:44.666354Z","end":"2026-03-17T21:59:45.358312Z","steps":["trace[1490577559] 'process raft request' (duration: 691.477948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:45.358555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:44.666304Z","time spent":"692.140473ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:59:46.374714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.790753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-17T21:59:46.374804Z","caller":"traceutil/trace.go:171","msg":"trace[2016132267] range","detail":"{range_begin:/registry/masterleases/199.19.213.244; range_end:; response_count:1; response_revision:942; }","duration":"295.927156ms","start":"2026-03-17T21:59:46.078858Z","end":"2026-03-17T21:59:46.374785Z","steps":["trace[2016132267] 'range keys from in-memory index tree' (duration: 295.617819ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:46.375214Z","caller":"traceutil/trace.go:171","msg":"trace[1507584858] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"178.856817ms","start":"2026-03-17T21:59:46.196347Z","end":"2026-03-17T21:59:46.375203Z","steps":["trace[1507584858] 'read index received' (duration: 175.495957ms)","trace[1507584858] 'applied index is now lower than readState.Index' (duration: 3.35993ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:59:46.375291Z","caller":"traceutil/trace.go:171","msg":"trace[172343912] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"252.824062ms","start":"2026-03-17T21:59:46.122436Z","end":"2026-03-17T21:59:46.37526Z","steps":["trace[172343912] 'process raft request' (duration: 249.470643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:46.375331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.735649ms","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-03-17T21:59:46.37536Z","caller":"traceutil/trace.go:171","msg":"trace[1631213906] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:943; }","duration":"126.79417ms","start":"2026-03-17T21:59:46.248558Z","end":"2026-03-17T21:59:46.375352Z","steps":["trace[1631213906] 'agreement among raft nodes before linearized reading' (duration: 126.735059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:46.375344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.98135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:59:46.375418Z","caller":"traceutil/trace.go:171","msg":"trace[761046217] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:943; }","duration":"179.098512ms","start":"2026-03-17T21:59:46.196299Z","end":"2026-03-17T21:59:46.375398Z","steps":["trace[761046217] 'agreement among raft nodes before linearized reading' (duration: 178.968899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:56.296691Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499511614215560898,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T21:59:56.646749Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.245231346s","expected-duration":"1s"} {"level":"info","ts":"2026-03-17T21:59:56.647077Z","caller":"traceutil/trace.go:171","msg":"trace[643412366] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"1.245660805s","start":"2026-03-17T21:59:55.401395Z","end":"2026-03-17T21:59:56.647056Z","steps":["trace[643412366] 'process raft request' (duration: 1.245528542s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:56.647261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:55.401376Z","time spent":"1.245804579s","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:59:56.920971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.400416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:59:56.921199Z","caller":"traceutil/trace.go:171","msg":"trace[1256704190] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1049; }","duration":"1.12494709s","start":"2026-03-17T21:59:55.796231Z","end":"2026-03-17T21:59:56.921178Z","steps":["trace[1256704190] 'read index received' (duration: 850.719499ms)","trace[1256704190] 'applied index is now lower than readState.Index' (duration: 274.226611ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:59:56.921228Z","caller":"traceutil/trace.go:171","msg":"trace[146932135] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"148.799794ms","start":"2026-03-17T21:59:56.772409Z","end":"2026-03-17T21:59:56.921208Z","steps":["trace[146932135] 'process raft request' (duration: 148.69267ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:56.921632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125312008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-17T21:59:56.921742Z","caller":"traceutil/trace.go:171","msg":"trace[2141805314] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1011; }","duration":"1.125533623s","start":"2026-03-17T21:59:55.796178Z","end":"2026-03-17T21:59:56.921712Z","steps":["trace[2141805314] 'agreement among raft nodes before linearized reading' (duration: 1.125194526s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:56.921814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:55.796156Z","time spent":"1.125636175s","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-17T21:59:56.921848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019940966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:59:56.921921Z","caller":"traceutil/trace.go:171","msg":"trace[761697550] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1011; }","duration":"1.020060869s","start":"2026-03-17T21:59:55.901839Z","end":"2026-03-17T21:59:56.921899Z","steps":["trace[761697550] 'agreement among raft nodes before linearized reading' (duration: 1.019592708s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:56.921906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.090223ms","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:529"} {"level":"warn","ts":"2026-03-17T21:59:56.921982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:55.901822Z","time spent":"1.02014375s","remote":"127.0.0.1:35658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-03-17T21:59:56.92195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.261277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-17T21:59:56.922022Z","caller":"traceutil/trace.go:171","msg":"trace[556233328] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1011; }","duration":"874.306658ms","start":"2026-03-17T21:59:56.047689Z","end":"2026-03-17T21:59:56.921995Z","steps":["trace[556233328] 'agreement among raft nodes before linearized reading' (duration: 873.922729ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:56.92205Z","caller":"traceutil/trace.go:171","msg":"trace[1418742661] range","detail":"{range_begin:/registry/masterleases/199.19.213.244; range_end:; response_count:1; response_revision:1011; }","duration":"842.398181ms","start":"2026-03-17T21:59:56.079629Z","end":"2026-03-17T21:59:56.922028Z","steps":["trace[1418742661] 'agreement among raft nodes before linearized reading' (duration: 841.811567ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:56.922094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:56.047631Z","time spent":"874.451401ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-17T21:59:56.922096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:56.079616Z","time spent":"842.469372ms","remote":"127.0.0.1:38616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.244\" "} {"level":"info","ts":"2026-03-17T21:59:56.922452Z","caller":"traceutil/trace.go:171","msg":"trace[1927490294] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"1.516463345s","start":"2026-03-17T21:59:55.404637Z","end":"2026-03-17T21:59:56.9211Z","steps":["trace[1927490294] 'process raft request' (duration: 1.270827494s)","trace[1927490294] 'compare' (duration: 245.14561ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:59:56.922581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:59:55.404626Z","time spent":"1.517871379s","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T22:00:51.37289Z","caller":"traceutil/trace.go:171","msg":"trace[1035666694] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"107.694805ms","start":"2026-03-17T22:00:51.265157Z","end":"2026-03-17T22:00:51.372852Z","steps":["trace[1035666694] 'process raft request' (duration: 107.330276ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:00:51.373682Z","caller":"traceutil/trace.go:171","msg":"trace[1297098723] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"107.766296ms","start":"2026-03-17T22:00:51.265385Z","end":"2026-03-17T22:00:51.373151Z","steps":["trace[1297098723] 'process raft request' (duration: 107.385787ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:05.516509Z","caller":"traceutil/trace.go:171","msg":"trace[1611566463] linearizableReadLoop","detail":"{readStateIndex:1603; appliedIndex:1602; }","duration":"101.610595ms","start":"2026-03-17T22:02:05.414778Z","end":"2026-03-17T22:02:05.516388Z","steps":["trace[1611566463] 'read index received' (duration: 100.734663ms)","trace[1611566463] 'applied index is now lower than readState.Index' (duration: 873.242µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:02:05.516883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.065455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-17T22:02:05.516999Z","caller":"traceutil/trace.go:171","msg":"trace[195481048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1536; }","duration":"102.182918ms","start":"2026-03-17T22:02:05.414747Z","end":"2026-03-17T22:02:05.51693Z","steps":["trace[195481048] 'agreement among raft nodes before linearized reading' (duration: 101.86985ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:05.517529Z","caller":"traceutil/trace.go:171","msg":"trace[1743872484] transaction","detail":"{read_only:false; response_revision:1536; number_of_response:1; }","duration":"132.427167ms","start":"2026-03-17T22:02:05.385068Z","end":"2026-03-17T22:02:05.517496Z","steps":["trace[1743872484] 'process raft request' (duration: 130.490071ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:05.670915Z","caller":"traceutil/trace.go:171","msg":"trace[358083006] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"146.125993ms","start":"2026-03-17T22:02:05.524749Z","end":"2026-03-17T22:02:05.670875Z","steps":["trace[358083006] 'process raft request' (duration: 139.769553ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:02:06.002179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.790117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T22:02:06.002415Z","caller":"traceutil/trace.go:171","msg":"trace[852264402] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"326.829367ms","start":"2026-03-17T22:02:05.675539Z","end":"2026-03-17T22:02:06.002368Z","steps":["trace[852264402] 'process raft request' (duration: 182.744743ms)","trace[852264402] 'compare' (duration: 143.597983ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:02:06.002313Z","caller":"traceutil/trace.go:171","msg":"trace[1338357032] linearizableReadLoop","detail":"{readStateIndex:1605; appliedIndex:1604; }","duration":"260.924721ms","start":"2026-03-17T22:02:05.741372Z","end":"2026-03-17T22:02:06.002297Z","steps":["trace[1338357032] 'read index received' (duration: 116.789095ms)","trace[1338357032] 'applied index is now lower than readState.Index' (duration: 144.132395ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:02:06.002667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.29963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-17T22:02:06.002675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:02:05.675516Z","time spent":"327.036452ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T22:02:06.002712Z","caller":"traceutil/trace.go:171","msg":"trace[594202317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1538; }","duration":"261.409072ms","start":"2026-03-17T22:02:05.741289Z","end":"2026-03-17T22:02:06.002698Z","steps":["trace[594202317] 'agreement among raft nodes before linearized reading' (duration: 261.140806ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:02:06.002843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.735986ms","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:530"} {"level":"info","ts":"2026-03-17T22:02:06.002972Z","caller":"traceutil/trace.go:171","msg":"trace[824071511] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1538; }","duration":"228.89868ms","start":"2026-03-17T22:02:05.774039Z","end":"2026-03-17T22:02:06.002937Z","steps":["trace[824071511] 'agreement among raft nodes before linearized reading' (duration: 228.636413ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:06.169769Z","caller":"traceutil/trace.go:171","msg":"trace[1191555569] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"162.42166ms","start":"2026-03-17T22:02:06.007316Z","end":"2026-03-17T22:02:06.169737Z","steps":["trace[1191555569] 'process raft request' (duration: 116.96093ms)","trace[1191555569] 'compare' (duration: 45.307757ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:02:06.171134Z","caller":"traceutil/trace.go:171","msg":"trace[196769227] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"162.253357ms","start":"2026-03-17T22:02:06.008821Z","end":"2026-03-17T22:02:06.171074Z","steps":["trace[196769227] 'process raft request' (duration: 161.934758ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:06.171486Z","caller":"traceutil/trace.go:171","msg":"trace[2025339080] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"162.522723ms","start":"2026-03-17T22:02:06.008951Z","end":"2026-03-17T22:02:06.171474Z","steps":["trace[2025339080] 'process raft request' (duration: 162.436981ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:06.171502Z","caller":"traceutil/trace.go:171","msg":"trace[1203626828] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"162.582404ms","start":"2026-03-17T22:02:06.008894Z","end":"2026-03-17T22:02:06.171476Z","steps":["trace[1203626828] 'process raft request' (duration: 162.02225ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:07.776361Z","caller":"traceutil/trace.go:171","msg":"trace[1960936442] linearizableReadLoop","detail":"{readStateIndex:1612; appliedIndex:1611; }","duration":"100.669713ms","start":"2026-03-17T22:02:07.675666Z","end":"2026-03-17T22:02:07.776336Z","steps":["trace[1960936442] 'read index received' (duration: 100.541279ms)","trace[1960936442] 'applied index is now lower than readState.Index' (duration: 126.954µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:02:07.7765Z","caller":"traceutil/trace.go:171","msg":"trace[1751832836] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"104.290979ms","start":"2026-03-17T22:02:07.672174Z","end":"2026-03-17T22:02:07.776465Z","steps":["trace[1751832836] 'process raft request' (duration: 104.004222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:02:07.776703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.012631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-17T22:02:07.776771Z","caller":"traceutil/trace.go:171","msg":"trace[1730940940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1544; }","duration":"101.110663ms","start":"2026-03-17T22:02:07.675643Z","end":"2026-03-17T22:02:07.776753Z","steps":["trace[1730940940] 'agreement among raft nodes before linearized reading' (duration: 100.788615ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:15.72741Z","caller":"traceutil/trace.go:171","msg":"trace[1401013175] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"187.126098ms","start":"2026-03-17T22:02:15.540264Z","end":"2026-03-17T22:02:15.72739Z","steps":["trace[1401013175] 'process raft request' (duration: 187.003715ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:28.668914Z","caller":"traceutil/trace.go:171","msg":"trace[1293882148] transaction","detail":"{read_only:false; response_revision:1630; number_of_response:1; }","duration":"125.927621ms","start":"2026-03-17T22:02:28.542941Z","end":"2026-03-17T22:02:28.668868Z","steps":["trace[1293882148] 'process raft request' (duration: 34.682784ms)","trace[1293882148] 'compare' (duration: 91.095544ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:02:28.669022Z","caller":"traceutil/trace.go:171","msg":"trace[1744277316] linearizableReadLoop","detail":"{readStateIndex:1702; appliedIndex:1701; }","duration":"125.81824ms","start":"2026-03-17T22:02:28.543171Z","end":"2026-03-17T22:02:28.668989Z","steps":["trace[1744277316] 'read index received' (duration: 34.447928ms)","trace[1744277316] 'applied index is now lower than readState.Index' (duration: 91.367332ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:02:28.668952Z","caller":"traceutil/trace.go:171","msg":"trace[956762558] transaction","detail":"{read_only:false; response_revision:1631; number_of_response:1; }","duration":"123.925126ms","start":"2026-03-17T22:02:28.545001Z","end":"2026-03-17T22:02:28.668926Z","steps":["trace[956762558] 'process raft request' (duration: 123.868683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:02:28.669213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.034435ms","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:530"} {"level":"info","ts":"2026-03-17T22:02:28.669271Z","caller":"traceutil/trace.go:171","msg":"trace[519538717] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1631; }","duration":"126.142497ms","start":"2026-03-17T22:02:28.543116Z","end":"2026-03-17T22:02:28.669258Z","steps":["trace[519538717] 'agreement among raft nodes before linearized reading' (duration: 125.967703ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:32.741924Z","caller":"traceutil/trace.go:171","msg":"trace[1426431407] transaction","detail":"{read_only:false; response_revision:1645; number_of_response:1; }","duration":"173.332298ms","start":"2026-03-17T22:02:32.568553Z","end":"2026-03-17T22:02:32.741886Z","steps":["trace[1426431407] 'process raft request' (duration: 173.085592ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:32.985212Z","caller":"traceutil/trace.go:171","msg":"trace[1877518887] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"111.946941ms","start":"2026-03-17T22:02:32.873203Z","end":"2026-03-17T22:02:32.98515Z","steps":["trace[1877518887] 'process raft request' (duration: 50.094341ms)","trace[1877518887] 'compare' (duration: 61.540322ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:02:38.344307Z","caller":"traceutil/trace.go:171","msg":"trace[563794830] linearizableReadLoop","detail":"{readStateIndex:1741; appliedIndex:1740; }","duration":"267.520668ms","start":"2026-03-17T22:02:38.076757Z","end":"2026-03-17T22:02:38.344278Z","steps":["trace[563794830] 'read index received' (duration: 267.236241ms)","trace[563794830] 'applied index is now lower than readState.Index' (duration: 283.326µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:02:38.344645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.864005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-17T22:02:38.344693Z","caller":"traceutil/trace.go:171","msg":"trace[2010991546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1667; }","duration":"268.018469ms","start":"2026-03-17T22:02:38.076655Z","end":"2026-03-17T22:02:38.344674Z","steps":["trace[2010991546] 'agreement among raft nodes before linearized reading' (duration: 267.795094ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:38.345045Z","caller":"traceutil/trace.go:171","msg":"trace[1348264372] transaction","detail":"{read_only:false; response_revision:1667; number_of_response:1; }","duration":"458.746542ms","start":"2026-03-17T22:02:37.885662Z","end":"2026-03-17T22:02:38.344408Z","steps":["trace[1348264372] 'process raft request' (duration: 458.422024ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:02:38.345512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:02:37.885643Z","time spent":"459.738856ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T22:02:47.101814Z","caller":"traceutil/trace.go:171","msg":"trace[140184615] transaction","detail":"{read_only:false; response_revision:1708; number_of_response:1; }","duration":"147.649048ms","start":"2026-03-17T22:02:46.954108Z","end":"2026-03-17T22:02:47.101757Z","steps":["trace[140184615] 'process raft request' (duration: 147.395633ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:55.237592Z","caller":"traceutil/trace.go:171","msg":"trace[1504722905] transaction","detail":"{read_only:false; response_revision:1742; number_of_response:1; }","duration":"193.735165ms","start":"2026-03-17T22:02:55.043818Z","end":"2026-03-17T22:02:55.237553Z","steps":["trace[1504722905] 'process raft request' (duration: 123.497976ms)","trace[1504722905] 'compare' (duration: 70.032534ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:04.234368Z","caller":"traceutil/trace.go:171","msg":"trace[545151762] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"105.388903ms","start":"2026-03-17T22:03:04.128954Z","end":"2026-03-17T22:03:04.234343Z","steps":["trace[545151762] 'process raft request' (duration: 105.179839ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:04.818627Z","caller":"traceutil/trace.go:171","msg":"trace[1859598885] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"119.838628ms","start":"2026-03-17T22:03:04.698754Z","end":"2026-03-17T22:03:04.818592Z","steps":["trace[1859598885] 'process raft request' (duration: 96.133204ms)","trace[1859598885] 'compare' (duration: 23.54281ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:05.129032Z","caller":"traceutil/trace.go:171","msg":"trace[149954493] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"213.64347ms","start":"2026-03-17T22:03:04.915352Z","end":"2026-03-17T22:03:05.128995Z","steps":["trace[149954493] 'process raft request' (duration: 140.420158ms)","trace[149954493] 'compare' (duration: 72.926494ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:05.170274Z","caller":"traceutil/trace.go:171","msg":"trace[366545142] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"240.071796ms","start":"2026-03-17T22:03:04.930158Z","end":"2026-03-17T22:03:05.17023Z","steps":["trace[366545142] 'process raft request' (duration: 239.844661ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:06.370576Z","caller":"traceutil/trace.go:171","msg":"trace[249618964] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"483.542521ms","start":"2026-03-17T22:03:05.886992Z","end":"2026-03-17T22:03:06.370535Z","steps":["trace[249618964] 'process raft request' (duration: 483.232044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:06.370779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:05.886966Z","time spent":"483.705695ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T22:03:06.370541Z","caller":"traceutil/trace.go:171","msg":"trace[1919084242] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1883; }","duration":"279.653166ms","start":"2026-03-17T22:03:06.090847Z","end":"2026-03-17T22:03:06.370501Z","steps":["trace[1919084242] 'read index received' (duration: 279.276977ms)","trace[1919084242] 'applied index is now lower than readState.Index' (duration: 374.349µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:03:06.371388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.522965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-17T22:03:06.371685Z","caller":"traceutil/trace.go:171","msg":"trace[1601326780] range","detail":"{range_begin:/registry/masterleases/199.19.213.244; range_end:; response_count:1; response_revision:1804; }","duration":"280.891195ms","start":"2026-03-17T22:03:06.090772Z","end":"2026-03-17T22:03:06.371663Z","steps":["trace[1601326780] 'agreement among raft nodes before linearized reading' (duration: 279.863561ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:06.77553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.425239ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-03-17T22:03:06.775685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:06.372837Z","time spent":"402.844604ms","remote":"127.0.0.1:38616","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-17T22:03:06.775629Z","caller":"traceutil/trace.go:171","msg":"trace[25416474] linearizableReadLoop","detail":"{readStateIndex:1885; appliedIndex:1884; }","duration":"133.721257ms","start":"2026-03-17T22:03:06.641893Z","end":"2026-03-17T22:03:06.775614Z","steps":["trace[25416474] 'read index received' (duration: 53.481µs)","trace[25416474] 'applied index is now lower than readState.Index' (duration: 133.666076ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:03:06.775974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.076346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-17T22:03:06.776025Z","caller":"traceutil/trace.go:171","msg":"trace[767466950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1804; }","duration":"134.184968ms","start":"2026-03-17T22:03:06.641825Z","end":"2026-03-17T22:03:06.77601Z","steps":["trace[767466950] 'agreement among raft nodes before linearized reading' (duration: 133.969813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:07.188471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.503598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T22:03:07.188832Z","caller":"traceutil/trace.go:171","msg":"trace[944263105] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"411.586583ms","start":"2026-03-17T22:03:06.777184Z","end":"2026-03-17T22:03:07.188771Z","steps":["trace[944263105] 'process raft request' (duration: 207.700537ms)","trace[944263105] 'compare' (duration: 203.377204ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:03:07.189036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:06.777162Z","time spent":"411.773497ms","remote":"127.0.0.1:38616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T22:03:07.189309Z","caller":"traceutil/trace.go:171","msg":"trace[280394932] linearizableReadLoop","detail":"{readStateIndex:1887; appliedIndex:1885; }","duration":"366.282416ms","start":"2026-03-17T22:03:06.823013Z","end":"2026-03-17T22:03:07.189295Z","steps":["trace[280394932] 'read index received' (duration: 161.98414ms)","trace[280394932] 'applied index is now lower than readState.Index' (duration: 204.297336ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:07.189358Z","caller":"traceutil/trace.go:171","msg":"trace[409252746] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"407.779533ms","start":"2026-03-17T22:03:06.781559Z","end":"2026-03-17T22:03:07.189339Z","steps":["trace[409252746] 'process raft request' (duration: 407.015495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:07.189458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:06.781546Z","time spent":"407.874595ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T22:03:07.190315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.288099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-17T22:03:07.190417Z","caller":"traceutil/trace.go:171","msg":"trace[1139300117] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1806; }","duration":"367.456654ms","start":"2026-03-17T22:03:06.822936Z","end":"2026-03-17T22:03:07.190393Z","steps":["trace[1139300117] 'agreement among raft nodes before linearized reading' (duration: 366.594104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:07.191134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:06.822904Z","time spent":"367.561026ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-17T22:03:07.421469Z","caller":"traceutil/trace.go:171","msg":"trace[1821461902] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"224.462166ms","start":"2026-03-17T22:03:07.196955Z","end":"2026-03-17T22:03:07.421417Z","steps":["trace[1821461902] 'process raft request' (duration: 196.314367ms)","trace[1821461902] 'compare' (duration: 27.873182ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:07.421653Z","caller":"traceutil/trace.go:171","msg":"trace[1712543654] linearizableReadLoop","detail":"{readStateIndex:1889; appliedIndex:1887; }","duration":"224.616109ms","start":"2026-03-17T22:03:07.197007Z","end":"2026-03-17T22:03:07.421624Z","steps":["trace[1712543654] 'read index received' (duration: 90.480641ms)","trace[1712543654] 'applied index is now lower than readState.Index' (duration: 134.132128ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:07.421792Z","caller":"traceutil/trace.go:171","msg":"trace[444408585] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"223.443661ms","start":"2026-03-17T22:03:07.198303Z","end":"2026-03-17T22:03:07.421746Z","steps":["trace[444408585] 'process raft request' (duration: 223.045152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:07.423276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.240558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-17T22:03:07.423455Z","caller":"traceutil/trace.go:171","msg":"trace[247452274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1809; }","duration":"226.471023ms","start":"2026-03-17T22:03:07.196959Z","end":"2026-03-17T22:03:07.42343Z","steps":["trace[247452274] 'agreement among raft nodes before linearized reading' (duration: 224.846974ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:09.670421Z","caller":"traceutil/trace.go:171","msg":"trace[1663897649] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1900; }","duration":"172.164073ms","start":"2026-03-17T22:03:09.498234Z","end":"2026-03-17T22:03:09.670398Z","steps":["trace[1663897649] 'read index received' (duration: 10.152792ms)","trace[1663897649] 'applied index is now lower than readState.Index' (duration: 162.010241ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:09.670544Z","caller":"traceutil/trace.go:171","msg":"trace[2011298027] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"238.13985ms","start":"2026-03-17T22:03:09.432363Z","end":"2026-03-17T22:03:09.670503Z","steps":["trace[2011298027] 'process raft request' (duration: 235.445296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:09.670686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.778963ms","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:530"} {"level":"info","ts":"2026-03-17T22:03:09.670733Z","caller":"traceutil/trace.go:171","msg":"trace[964226179] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1821; }","duration":"155.881555ms","start":"2026-03-17T22:03:09.514839Z","end":"2026-03-17T22:03:09.67072Z","steps":["trace[964226179] 'agreement among raft nodes before linearized reading' (duration: 155.704621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:09.670947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.557101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-17T22:03:09.671049Z","caller":"traceutil/trace.go:171","msg":"trace[1281931276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1821; }","duration":"172.862018ms","start":"2026-03-17T22:03:09.498162Z","end":"2026-03-17T22:03:09.671024Z","steps":["trace[1281931276] 'agreement among raft nodes before linearized reading' (duration: 172.373287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:13.853631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.84327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-17T22:03:13.853729Z","caller":"traceutil/trace.go:171","msg":"trace[729222156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1839; }","duration":"163.008435ms","start":"2026-03-17T22:03:13.690702Z","end":"2026-03-17T22:03:13.85371Z","steps":["trace[729222156] 'range keys from in-memory index tree' (duration: 162.588725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:13.853846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.814887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-17T22:03:13.853901Z","caller":"traceutil/trace.go:171","msg":"trace[28461203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1839; }","duration":"156.896499ms","start":"2026-03-17T22:03:13.696992Z","end":"2026-03-17T22:03:13.853889Z","steps":["trace[28461203] 'range keys from in-memory index tree' (duration: 156.714195ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:13.854123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.690264ms","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:530"} {"level":"info","ts":"2026-03-17T22:03:13.854316Z","caller":"traceutil/trace.go:171","msg":"trace[2082366868] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1839; }","duration":"156.900689ms","start":"2026-03-17T22:03:13.697401Z","end":"2026-03-17T22:03:13.854302Z","steps":["trace[2082366868] 'range keys from in-memory index tree' (duration: 156.497299ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:16.031461Z","caller":"traceutil/trace.go:171","msg":"trace[1245313308] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"105.454995ms","start":"2026-03-17T22:03:15.925974Z","end":"2026-03-17T22:03:16.031429Z","steps":["trace[1245313308] 'process raft request' (duration: 105.404224ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:16.031508Z","caller":"traceutil/trace.go:171","msg":"trace[316651027] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"105.630189ms","start":"2026-03-17T22:03:15.92584Z","end":"2026-03-17T22:03:16.031471Z","steps":["trace[316651027] 'process raft request' (duration: 90.701065ms)","trace[316651027] 'compare' (duration: 14.675598ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:17.421028Z","caller":"traceutil/trace.go:171","msg":"trace[1602278373] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"126.593098ms","start":"2026-03-17T22:03:17.294401Z","end":"2026-03-17T22:03:17.420994Z","steps":["trace[1602278373] 'process raft request' (duration: 126.383763ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:17.761133Z","caller":"traceutil/trace.go:171","msg":"trace[64432221] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"172.535241ms","start":"2026-03-17T22:03:17.58858Z","end":"2026-03-17T22:03:17.761115Z","steps":["trace[64432221] 'read index received' (duration: 172.153592ms)","trace[64432221] 'applied index is now lower than readState.Index' (duration: 380.659µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:17.761232Z","caller":"traceutil/trace.go:171","msg":"trace[1458554747] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"216.460684ms","start":"2026-03-17T22:03:17.544737Z","end":"2026-03-17T22:03:17.761198Z","steps":["trace[1458554747] 'process raft request' (duration: 215.985083ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:17.7613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.711065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-17T22:03:17.761326Z","caller":"traceutil/trace.go:171","msg":"trace[1666710568] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1857; }","duration":"172.794417ms","start":"2026-03-17T22:03:17.588524Z","end":"2026-03-17T22:03:17.761319Z","steps":["trace[1666710568] 'agreement among raft nodes before linearized reading' (duration: 172.659984ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:20.183162Z","caller":"traceutil/trace.go:171","msg":"trace[2088171707] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"134.338243ms","start":"2026-03-17T22:03:20.0488Z","end":"2026-03-17T22:03:20.183138Z","steps":["trace[2088171707] 'process raft request' (duration: 134.163459ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:20.183373Z","caller":"traceutil/trace.go:171","msg":"trace[1196806414] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"134.502357ms","start":"2026-03-17T22:03:20.048833Z","end":"2026-03-17T22:03:20.183335Z","steps":["trace[1196806414] 'process raft request' (duration: 134.251731ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:22.018134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.514881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-17T22:03:22.01802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.492668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-17T22:03:22.018235Z","caller":"traceutil/trace.go:171","msg":"trace[689319901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1877; }","duration":"230.761904ms","start":"2026-03-17T22:03:21.787458Z","end":"2026-03-17T22:03:22.01822Z","steps":["trace[689319901] 'range keys from in-memory index tree' (duration: 230.250612ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:22.018233Z","caller":"traceutil/trace.go:171","msg":"trace[939849093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1877; }","duration":"159.663525ms","start":"2026-03-17T22:03:21.858547Z","end":"2026-03-17T22:03:22.01821Z","steps":["trace[939849093] 'range keys from in-memory index tree' (duration: 159.300265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:22.521487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.227678ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-17T22:03:22.521614Z","caller":"traceutil/trace.go:171","msg":"trace[1445884806] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1961; }","duration":"542.835342ms","start":"2026-03-17T22:03:21.978755Z","end":"2026-03-17T22:03:22.52159Z","steps":["trace[1445884806] 'read index received' (duration: 114.511172ms)","trace[1445884806] 'applied index is now lower than readState.Index' (duration: 428.32202ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:03:22.521971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.18405ms","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:530"} {"level":"info","ts":"2026-03-17T22:03:22.522068Z","caller":"traceutil/trace.go:171","msg":"trace[1479506874] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1877; }","duration":"543.343863ms","start":"2026-03-17T22:03:21.978702Z","end":"2026-03-17T22:03:22.522046Z","steps":["trace[1479506874] 'agreement among raft nodes before linearized reading' (duration: 542.986335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:22.522116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:21.978681Z","time spent":"543.426036ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-03-17T22:03:22.5973Z","caller":"traceutil/trace.go:171","msg":"trace[2064312632] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"574.755501ms","start":"2026-03-17T22:03:22.022515Z","end":"2026-03-17T22:03:22.597271Z","steps":["trace[2064312632] 'process raft request' (duration: 574.698529ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:22.59734Z","caller":"traceutil/trace.go:171","msg":"trace[548912800] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"574.867593ms","start":"2026-03-17T22:03:22.022446Z","end":"2026-03-17T22:03:22.597314Z","steps":["trace[548912800] 'process raft request' (duration: 574.541245ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:22.597427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.574472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T22:03:22.597471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:22.022489Z","time spent":"574.895323ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T22:03:22.597488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:22.022428Z","time spent":"574.978966ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T22:03:22.597491Z","caller":"traceutil/trace.go:171","msg":"trace[1606712585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1879; }","duration":"257.717585ms","start":"2026-03-17T22:03:22.339754Z","end":"2026-03-17T22:03:22.597472Z","steps":["trace[1606712585] 'agreement among raft nodes before linearized reading' (duration: 257.581052ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:22.597496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.405116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-17T22:03:22.597548Z","caller":"traceutil/trace.go:171","msg":"trace[709499841] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1879; }","duration":"469.494968ms","start":"2026-03-17T22:03:22.128038Z","end":"2026-03-17T22:03:22.597533Z","steps":["trace[709499841] 'agreement among raft nodes before linearized reading' (duration: 469.289873ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:22.597581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:22.12802Z","time spent":"469.55332ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-17T22:03:22.597645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.532153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-17T22:03:22.597668Z","caller":"traceutil/trace.go:171","msg":"trace[1937823594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1879; }","duration":"409.606025ms","start":"2026-03-17T22:03:22.188056Z","end":"2026-03-17T22:03:22.597662Z","steps":["trace[1937823594] 'agreement among raft nodes before linearized reading' (duration: 409.519253ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:22.597683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:22.188025Z","time spent":"409.654946ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-17T22:03:22.861797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.729616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T22:03:22.861975Z","caller":"traceutil/trace.go:171","msg":"trace[1850481794] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1965; }","duration":"221.710359ms","start":"2026-03-17T22:03:22.640247Z","end":"2026-03-17T22:03:22.861958Z","steps":["trace[1850481794] 'read index received' (duration: 45.579063ms)","trace[1850481794] 'applied index is now lower than readState.Index' (duration: 176.127626ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:22.862066Z","caller":"traceutil/trace.go:171","msg":"trace[1121042930] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"258.003232ms","start":"2026-03-17T22:03:22.604056Z","end":"2026-03-17T22:03:22.862059Z","steps":["trace[1121042930] 'process raft request' (duration: 257.850849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:22.86221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.967486ms","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-03-17T22:03:22.862231Z","caller":"traceutil/trace.go:171","msg":"trace[1602274683] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1882; }","duration":"222.026038ms","start":"2026-03-17T22:03:22.640198Z","end":"2026-03-17T22:03:22.862224Z","steps":["trace[1602274683] 'agreement among raft nodes before linearized reading' (duration: 221.982037ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:22.862943Z","caller":"traceutil/trace.go:171","msg":"trace[1193916547] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"259.831325ms","start":"2026-03-17T22:03:22.60249Z","end":"2026-03-17T22:03:22.862321Z","steps":["trace[1193916547] 'process raft request' (duration: 83.467204ms)","trace[1193916547] 'compare' (duration: 175.560852ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:29.788347Z","caller":"traceutil/trace.go:171","msg":"trace[1628539778] linearizableReadLoop","detail":"{readStateIndex:1999; appliedIndex:1998; }","duration":"156.96913ms","start":"2026-03-17T22:03:29.631358Z","end":"2026-03-17T22:03:29.788327Z","steps":["trace[1628539778] 'read index received' (duration: 156.815377ms)","trace[1628539778] 'applied index is now lower than readState.Index' (duration: 152.863µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:29.78865Z","caller":"traceutil/trace.go:171","msg":"trace[1073759614] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"160.175087ms","start":"2026-03-17T22:03:29.628459Z","end":"2026-03-17T22:03:29.788634Z","steps":["trace[1073759614] 'process raft request' (duration: 159.744736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:29.788951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.569715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-03-17T22:03:29.789009Z","caller":"traceutil/trace.go:171","msg":"trace[1424826399] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:1913; }","duration":"157.663217ms","start":"2026-03-17T22:03:29.631329Z","end":"2026-03-17T22:03:29.788992Z","steps":["trace[1424826399] 'agreement among raft nodes before linearized reading' (duration: 157.534034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:29.78906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.148136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:job-controller\" ","response":"range_response_count:1 size:731"} {"level":"info","ts":"2026-03-17T22:03:29.789251Z","caller":"traceutil/trace.go:171","msg":"trace[841380598] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:job-controller; range_end:; response_count:1; response_revision:1913; }","duration":"107.376562ms","start":"2026-03-17T22:03:29.6818Z","end":"2026-03-17T22:03:29.789176Z","steps":["trace[841380598] 'agreement among raft nodes before linearized reading' (duration: 107.043854ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:38.001992Z","caller":"traceutil/trace.go:171","msg":"trace[1166954628] linearizableReadLoop","detail":"{readStateIndex:2033; appliedIndex:2032; }","duration":"175.938532ms","start":"2026-03-17T22:03:37.826023Z","end":"2026-03-17T22:03:38.001962Z","steps":["trace[1166954628] 'read index received' (duration: 175.764058ms)","trace[1166954628] 'applied index is now lower than readState.Index' (duration: 173.064µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:38.00208Z","caller":"traceutil/trace.go:171","msg":"trace[36582676] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"180.632443ms","start":"2026-03-17T22:03:37.821426Z","end":"2026-03-17T22:03:38.002058Z","steps":["trace[36582676] 'process raft request' (duration: 180.343776ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:38.002197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.148236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"info","ts":"2026-03-17T22:03:38.002283Z","caller":"traceutil/trace.go:171","msg":"trace[2077404329] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:1945; }","duration":"176.231268ms","start":"2026-03-17T22:03:37.826007Z","end":"2026-03-17T22:03:38.002239Z","steps":["trace[2077404329] 'agreement among raft nodes before linearized reading' (duration: 176.114525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:38.002316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.425758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-proxy\" ","response":"range_response_count:1 size:1949"} {"level":"info","ts":"2026-03-17T22:03:38.002348Z","caller":"traceutil/trace.go:171","msg":"trace[1794967485] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1945; }","duration":"140.484088ms","start":"2026-03-17T22:03:37.861854Z","end":"2026-03-17T22:03:38.002338Z","steps":["trace[1794967485] 'agreement among raft nodes before linearized reading' (duration: 140.414887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:38.616723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.083842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"warn","ts":"2026-03-17T22:03:38.616758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.011299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-03-17T22:03:38.616822Z","caller":"traceutil/trace.go:171","msg":"trace[878759746] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1945; }","duration":"231.219026ms","start":"2026-03-17T22:03:38.38558Z","end":"2026-03-17T22:03:38.616799Z","steps":["trace[878759746] 'range keys from in-memory index tree' (duration: 230.98425ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:38.616835Z","caller":"traceutil/trace.go:171","msg":"trace[1805745615] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:1945; }","duration":"228.138462ms","start":"2026-03-17T22:03:38.388675Z","end":"2026-03-17T22:03:38.616813Z","steps":["trace[1805745615] 'range keys from in-memory index tree' (duration: 227.847596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:38.616889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.154553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-03-17T22:03:38.616902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.779996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/\" range_end:\"/registry/rolebindings/kube-system0\" limit:500 ","response":"range_response_count:12 size:8411"} {"level":"info","ts":"2026-03-17T22:03:38.617009Z","caller":"traceutil/trace.go:171","msg":"trace[1735778901] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1945; }","duration":"114.310457ms","start":"2026-03-17T22:03:38.502669Z","end":"2026-03-17T22:03:38.616979Z","steps":["trace[1735778901] 'range keys from in-memory index tree' (duration: 113.904318ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:38.617026Z","caller":"traceutil/trace.go:171","msg":"trace[1668956110] range","detail":"{range_begin:/registry/rolebindings/kube-system/; range_end:/registry/rolebindings/kube-system0; response_count:12; response_revision:1945; }","duration":"193.95817ms","start":"2026-03-17T22:03:38.423041Z","end":"2026-03-17T22:03:38.616999Z","steps":["trace[1668956110] 'range keys from in-memory index tree' (duration: 193.504829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:38.616724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.721724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/\" range_end:\"/registry/rolebindings/kube-public0\" limit:500 ","response":"range_response_count:2 size:1230"} {"level":"info","ts":"2026-03-17T22:03:38.617117Z","caller":"traceutil/trace.go:171","msg":"trace[646718386] range","detail":"{range_begin:/registry/rolebindings/kube-public/; range_end:/registry/rolebindings/kube-public0; response_count:2; response_revision:1945; }","duration":"457.171965ms","start":"2026-03-17T22:03:38.159921Z","end":"2026-03-17T22:03:38.617093Z","steps":["trace[646718386] 'range keys from in-memory index tree' (duration: 456.629742ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:38.617175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:38.159903Z","time spent":"457.250547ms","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":2,"response size":1254,"request content":"key:\"/registry/rolebindings/kube-public/\" range_end:\"/registry/rolebindings/kube-public0\" limit:500 "} {"level":"warn","ts":"2026-03-17T22:03:38.61724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.020475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T22:03:38.61729Z","caller":"traceutil/trace.go:171","msg":"trace[1761122694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1945; }","duration":"280.104107ms","start":"2026-03-17T22:03:38.337171Z","end":"2026-03-17T22:03:38.617275Z","steps":["trace[1761122694] 'range keys from in-memory index tree' (duration: 279.924602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:43.924457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.037271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/csi-rbd-secret\" ","response":"range_response_count:1 size:880"} {"level":"warn","ts":"2026-03-17T22:03:43.924535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.364827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/kubeadm-certs\" ","response":"range_response_count:1 size:11459"} {"level":"info","ts":"2026-03-17T22:03:43.924596Z","caller":"traceutil/trace.go:171","msg":"trace[1961661099] range","detail":"{range_begin:/registry/secrets/kube-system/csi-rbd-secret; range_end:; response_count:1; response_revision:1968; }","duration":"162.225316ms","start":"2026-03-17T22:03:43.76233Z","end":"2026-03-17T22:03:43.924555Z","steps":["trace[1961661099] 'range keys from in-memory index tree' (duration: 161.799836ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:43.924747Z","caller":"traceutil/trace.go:171","msg":"trace[1679940656] range","detail":"{range_begin:/registry/secrets/kube-system/kubeadm-certs; range_end:; response_count:1; response_revision:1968; }","duration":"148.586872ms","start":"2026-03-17T22:03:43.776059Z","end":"2026-03-17T22:03:43.924646Z","steps":["trace[1679940656] 'range keys from in-memory index tree' (duration: 148.161082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:47.177659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.327021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:363690"} {"level":"info","ts":"2026-03-17T22:03:47.177752Z","caller":"traceutil/trace.go:171","msg":"trace[1203150259] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1983; }","duration":"115.445494ms","start":"2026-03-17T22:03:47.062287Z","end":"2026-03-17T22:03:47.177733Z","steps":["trace[1203150259] 'range keys from in-memory index tree' (duration: 115.056664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:49.455319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.137351ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T22:03:49.455469Z","caller":"traceutil/trace.go:171","msg":"trace[946451500] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1991; }","duration":"153.310115ms","start":"2026-03-17T22:03:49.30214Z","end":"2026-03-17T22:03:49.45545Z","steps":["trace[946451500] 'range keys from in-memory index tree' (duration: 153.121879ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:49.455927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.629941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T22:03:49.455998Z","caller":"traceutil/trace.go:171","msg":"trace[674214835] linearizableReadLoop","detail":"{readStateIndex:2082; appliedIndex:2080; }","duration":"247.166904ms","start":"2026-03-17T22:03:49.208819Z","end":"2026-03-17T22:03:49.455986Z","steps":["trace[674214835] 'read index received' (duration: 88.583895ms)","trace[674214835] 'applied index is now lower than readState.Index' (duration: 158.581649ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:49.456268Z","caller":"traceutil/trace.go:171","msg":"trace[928266035] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"249.07966ms","start":"2026-03-17T22:03:49.207172Z","end":"2026-03-17T22:03:49.456251Z","steps":["trace[928266035] 'process raft request' (duration: 135.005809ms)","trace[928266035] 'compare' (duration: 112.989356ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:03:49.456556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.734417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/ceph-csi-rbd-nodeplugin-http-metrics\" ","response":"range_response_count:1 size:1185"} {"level":"info","ts":"2026-03-17T22:03:49.456601Z","caller":"traceutil/trace.go:171","msg":"trace[1320482534] range","detail":"{range_begin:/registry/services/specs/kube-system/ceph-csi-rbd-nodeplugin-http-metrics; range_end:; response_count:1; response_revision:1992; }","duration":"247.791799ms","start":"2026-03-17T22:03:49.208796Z","end":"2026-03-17T22:03:49.456588Z","steps":["trace[1320482534] 'agreement among raft nodes before linearized reading' (duration: 247.632865ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:49.456806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.996116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics\" ","response":"range_response_count:1 size:1193"} {"level":"info","ts":"2026-03-17T22:03:49.456842Z","caller":"traceutil/trace.go:171","msg":"trace[1486621740] range","detail":"{range_begin:/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics; range_end:; response_count:1; response_revision:1992; }","duration":"169.073778ms","start":"2026-03-17T22:03:49.28776Z","end":"2026-03-17T22:03:49.456834Z","steps":["trace[1486621740] 'agreement among raft nodes before linearized reading' (duration: 168.985736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:49.457288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.768392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T22:03:49.457337Z","caller":"traceutil/trace.go:171","msg":"trace[874438461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1992; }","duration":"118.853544ms","start":"2026-03-17T22:03:49.338473Z","end":"2026-03-17T22:03:49.457326Z","steps":["trace[874438461] 'agreement among raft nodes before linearized reading' (duration: 118.791713ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:49.457549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.605722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-03-17T22:03:49.457671Z","caller":"traceutil/trace.go:171","msg":"trace[327894133] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1992; }","duration":"127.765716ms","start":"2026-03-17T22:03:49.329872Z","end":"2026-03-17T22:03:49.457638Z","steps":["trace[327894133] 'agreement among raft nodes before linearized reading' (duration: 127.458669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:49.737306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.058451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T22:03:49.737384Z","caller":"traceutil/trace.go:171","msg":"trace[1449082589] linearizableReadLoop","detail":"{readStateIndex:2084; appliedIndex:2082; }","duration":"274.005542ms","start":"2026-03-17T22:03:49.463365Z","end":"2026-03-17T22:03:49.73737Z","steps":["trace[1449082589] 'read index received' (duration: 30.743851ms)","trace[1449082589] 'applied index is now lower than readState.Index' (duration: 243.260971ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:49.737455Z","caller":"traceutil/trace.go:171","msg":"trace[926990570] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"274.219298ms","start":"2026-03-17T22:03:49.463228Z","end":"2026-03-17T22:03:49.737447Z","steps":["trace[926990570] 'process raft request' (duration: 119.956842ms)","trace[926990570] 'compare' (duration: 153.963229ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:03:49.737996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.593408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics\" ","response":"range_response_count:1 size:1193"} {"level":"info","ts":"2026-03-17T22:03:49.738115Z","caller":"traceutil/trace.go:171","msg":"trace[944138254] range","detail":"{range_begin:/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics; range_end:; response_count:1; response_revision:1994; }","duration":"274.743701ms","start":"2026-03-17T22:03:49.463342Z","end":"2026-03-17T22:03:49.738086Z","steps":["trace[944138254] 'agreement among raft nodes before linearized reading' (duration: 274.380922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:49.738226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.663851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:118 size:91039"} {"level":"info","ts":"2026-03-17T22:03:49.738314Z","caller":"traceutil/trace.go:171","msg":"trace[727157333] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:118; response_revision:1994; }","duration":"272.768533ms","start":"2026-03-17T22:03:49.46553Z","end":"2026-03-17T22:03:49.738298Z","steps":["trace[727157333] 'agreement among raft nodes before linearized reading' (duration: 271.948833ms)"],"step_count":1}