{"level":"warn","ts":"2026-02-24T12:58:23.031036Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T12:58:23.031127Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.85: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.85:2380","--initial-cluster=instance=https://199.19.213.85:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.85:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.85:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-24T12:58:23.031186Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T12:58:23.031194Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.85:2380"]} {"level":"info","ts":"2026-02-24T12:58:23.03124Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-24T12:58:23.032562Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.85:2379"]} {"level":"info","ts":"2026-02-24T12:58:23.032858Z","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.85:2380"],"listen-peer-urls":["https://199.19.213.85:2380"],"advertise-client-urls":["https://199.19.213.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.85:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-24T12:58:23.040534Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.190244ms"} {"level":"info","ts":"2026-02-24T12:58:23.092015Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a0c678c4e560a44","cluster-id":"dbaaf49260b7a32b"} {"level":"info","ts":"2026-02-24T12:58:23.092188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T12:58:23.09224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became follower at term 0"} {"level":"info","ts":"2026-02-24T12:58:23.092253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a0c678c4e560a44 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T12:58:23.092263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became follower at term 1"} {"level":"info","ts":"2026-02-24T12:58:23.092321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 switched to configuration voters=(7641596520048233028)"} {"level":"warn","ts":"2026-02-24T12:58:23.141787Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T12:58:23.143773Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T12:58:23.14531Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-24T12:58:23.148587Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a0c678c4e560a44","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T12:58:23.148896Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a0c678c4e560a44","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T12:58:23.149741Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T12:58:23.149866Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T12:58:23.14991Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T12:58:23.153646Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-24T12:58:23.153734Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.85:2380"} {"level":"info","ts":"2026-02-24T12:58:23.153783Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.85:2380"} {"level":"info","ts":"2026-02-24T12:58:23.153956Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a0c678c4e560a44","initial-advertise-peer-urls":["https://199.19.213.85:2380"],"listen-peer-urls":["https://199.19.213.85:2380"],"advertise-client-urls":["https://199.19.213.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T12:58:23.15399Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T12:58:23.154505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 switched to configuration voters=(7641596520048233028)"} {"level":"info","ts":"2026-02-24T12:58:23.154708Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dbaaf49260b7a32b","local-member-id":"6a0c678c4e560a44","added-peer-id":"6a0c678c4e560a44","added-peer-peer-urls":["https://199.19.213.85:2380"]} {"level":"info","ts":"2026-02-24T12:58:23.393336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T12:58:23.393448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T12:58:23.393501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 received MsgPreVoteResp from 6a0c678c4e560a44 at term 1"} {"level":"info","ts":"2026-02-24T12:58:23.39361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became candidate at term 2"} {"level":"info","ts":"2026-02-24T12:58:23.39363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 received MsgVoteResp from 6a0c678c4e560a44 at term 2"} {"level":"info","ts":"2026-02-24T12:58:23.393647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became leader at term 2"} {"level":"info","ts":"2026-02-24T12:58:23.393719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a0c678c4e560a44 elected leader 6a0c678c4e560a44 at term 2"} {"level":"info","ts":"2026-02-24T12:58:23.410102Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a0c678c4e560a44","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.85:2379]}","request-path":"/0/members/6a0c678c4e560a44/attributes","cluster-id":"dbaaf49260b7a32b","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T12:58:23.410099Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T12:58:23.41105Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T12:58:23.411787Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T12:58:23.411903Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T12:58:23.41425Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.85:2379"} {"level":"info","ts":"2026-02-24T12:58:23.417101Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T12:58:23.425744Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T12:58:23.443464Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dbaaf49260b7a32b","local-member-id":"6a0c678c4e560a44","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T12:58:23.443661Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T12:58:23.443762Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-24T12:58:37.530545Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":739888379922085769,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T12:58:37.58998Z","caller":"traceutil/trace.go:171","msg":"trace[1275600909] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"559.786652ms","start":"2026-02-24T12:58:37.030166Z","end":"2026-02-24T12:58:37.589953Z","steps":["trace[1275600909] 'read index received' (duration: 559.650719ms)","trace[1275600909] 'applied index is now lower than readState.Index' (duration: 134.533µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:58:37.589989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:58:36.932117Z","time spent":"657.86593ms","remote":"127.0.0.1:34738","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-24T12:58:37.590169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.965266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-24T12:58:37.590232Z","caller":"traceutil/trace.go:171","msg":"trace[392041450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:398; }","duration":"560.077708ms","start":"2026-02-24T12:58:37.030138Z","end":"2026-02-24T12:58:37.590216Z","steps":["trace[392041450] 'agreement among raft nodes before linearized reading' (duration: 559.934605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:58:37.590262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:58:37.03012Z","time spent":"560.13617ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T12:58:37.590445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.769366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T12:58:37.590495Z","caller":"traceutil/trace.go:171","msg":"trace[1804093574] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:398; }","duration":"211.853148ms","start":"2026-02-24T12:58:37.378629Z","end":"2026-02-24T12:58:37.590482Z","steps":["trace[1804093574] 'agreement among raft nodes before linearized reading' (duration: 211.748906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:58:38.531306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.813597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T12:58:38.531583Z","caller":"traceutil/trace.go:171","msg":"trace[1171057949] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:415; }","duration":"384.889832ms","start":"2026-02-24T12:58:38.146677Z","end":"2026-02-24T12:58:38.531567Z","steps":["trace[1171057949] 'read index received' (duration: 43.979221ms)","trace[1171057949] 'applied index is now lower than readState.Index' (duration: 340.908541ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:58:38.531747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.036356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T12:58:38.531786Z","caller":"traceutil/trace.go:171","msg":"trace[1910222235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:401; }","duration":"385.157529ms","start":"2026-02-24T12:58:38.146616Z","end":"2026-02-24T12:58:38.531773Z","steps":["trace[1910222235] 'agreement among raft nodes before linearized reading' (duration: 385.024936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:58:38.531833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:58:38.146556Z","time spent":"385.265202ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T12:58:38.531796Z","caller":"traceutil/trace.go:171","msg":"trace[505038551] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"937.668905ms","start":"2026-02-24T12:58:37.594087Z","end":"2026-02-24T12:58:38.531756Z","steps":["trace[505038551] 'process raft request' (duration: 937.349967ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:58:38.53182Z","caller":"traceutil/trace.go:171","msg":"trace[722629341] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"937.397298ms","start":"2026-02-24T12:58:37.594391Z","end":"2026-02-24T12:58:38.531788Z","steps":["trace[722629341] 'process raft request' (duration: 937.141102ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:58:38.531695Z","caller":"traceutil/trace.go:171","msg":"trace[1064242474] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"940.589381ms","start":"2026-02-24T12:58:37.591059Z","end":"2026-02-24T12:58:38.531649Z","steps":["trace[1064242474] 'process raft request' (duration: 599.687799ms)","trace[1064242474] 'compare' (duration: 339.639833ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:58:38.53201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:58:37.594054Z","time spent":"937.867869ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T12:58:38.532117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:58:37.59437Z","time spent":"937.650945ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T12:58:38.532205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:58:37.591043Z","time spent":"941.031931ms","remote":"127.0.0.1:34738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T12:58:40.949101Z","caller":"traceutil/trace.go:171","msg":"trace[1045308302] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"136.271748ms","start":"2026-02-24T12:58:40.812804Z","end":"2026-02-24T12:58:40.949075Z","steps":["trace[1045308302] 'process raft request' (duration: 136.101074ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:58:50.69031Z","caller":"traceutil/trace.go:171","msg":"trace[1279940148] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"102.354639ms","start":"2026-02-24T12:58:50.58793Z","end":"2026-02-24T12:58:50.690285Z","steps":["trace[1279940148] 'process raft request' (duration: 102.139254ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:58:50.695269Z","caller":"traceutil/trace.go:171","msg":"trace[1528979178] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"104.490597ms","start":"2026-02-24T12:58:50.590744Z","end":"2026-02-24T12:58:50.695235Z","steps":["trace[1528979178] 'process raft request' (duration: 104.158319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:58:56.967924Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.246674252s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T12:58:56.968383Z","caller":"traceutil/trace.go:171","msg":"trace[822208712] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"251.254134ms","start":"2026-02-24T12:58:56.717103Z","end":"2026-02-24T12:58:56.968357Z","steps":["trace[822208712] 'read index received' (duration: 251.017499ms)","trace[822208712] 'applied index is now lower than readState.Index' (duration: 235.585µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:58:56.968695Z","caller":"traceutil/trace.go:171","msg":"trace[366779584] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.247537521s","start":"2026-02-24T12:58:55.721144Z","end":"2026-02-24T12:58:56.968681Z","steps":["trace[366779584] 'process raft request' (duration: 1.247061321s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:58:56.968826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:58:55.72112Z","time spent":"1.247621384s","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T12:58:56.969163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.038962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T12:58:56.969215Z","caller":"traceutil/trace.go:171","msg":"trace[2103179610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"252.151155ms","start":"2026-02-24T12:58:56.717049Z","end":"2026-02-24T12:58:56.9692Z","steps":["trace[2103179610] 'agreement among raft nodes before linearized reading' (duration: 251.979211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:58:56.969452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.689174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T12:58:56.969493Z","caller":"traceutil/trace.go:171","msg":"trace[1578529707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:510; }","duration":"251.767176ms","start":"2026-02-24T12:58:56.717714Z","end":"2026-02-24T12:58:56.969481Z","steps":["trace[1578529707] 'agreement among raft nodes before linearized reading' (duration: 251.651984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:58:56.969861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.26935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-24T12:58:56.969923Z","caller":"traceutil/trace.go:171","msg":"trace[851619926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:510; }","duration":"235.355013ms","start":"2026-02-24T12:58:56.734543Z","end":"2026-02-24T12:58:56.969898Z","steps":["trace[851619926] 'agreement among raft nodes before linearized reading' (duration: 235.23125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:58:56.96984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.078477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:58:56.970093Z","caller":"traceutil/trace.go:171","msg":"trace[369588054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"166.382833ms","start":"2026-02-24T12:58:56.803673Z","end":"2026-02-24T12:58:56.970056Z","steps":["trace[369588054] 'agreement among raft nodes before linearized reading' (duration: 166.085206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:58:56.970169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.583207ms","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-02-24T12:58:56.970229Z","caller":"traceutil/trace.go:171","msg":"trace[1856241004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"235.68799ms","start":"2026-02-24T12:58:56.734524Z","end":"2026-02-24T12:58:56.970212Z","steps":["trace[1856241004] 'agreement among raft nodes before linearized reading' (duration: 235.521196ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:59:03.226518Z","caller":"traceutil/trace.go:171","msg":"trace[220196724] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"227.635266ms","start":"2026-02-24T12:59:02.998861Z","end":"2026-02-24T12:59:03.226496Z","steps":["trace[220196724] 'read index received' (duration: 227.449762ms)","trace[220196724] 'applied index is now lower than readState.Index' (duration: 184.564µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:59:03.226622Z","caller":"traceutil/trace.go:171","msg":"trace[1992910643] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"228.109368ms","start":"2026-02-24T12:59:02.998506Z","end":"2026-02-24T12:59:03.226615Z","steps":["trace[1992910643] 'process raft request' (duration: 227.859702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:03.22678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.915713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T12:59:03.226819Z","caller":"traceutil/trace.go:171","msg":"trace[1002735901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"227.997194ms","start":"2026-02-24T12:59:02.998814Z","end":"2026-02-24T12:59:03.226812Z","steps":["trace[1002735901] 'agreement among raft nodes before linearized reading' (duration: 227.924132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:03.226958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.934413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-24T12:59:03.227031Z","caller":"traceutil/trace.go:171","msg":"trace[484575625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"224.032874ms","start":"2026-02-24T12:59:03.002982Z","end":"2026-02-24T12:59:03.227015Z","steps":["trace[484575625] 'agreement among raft nodes before linearized reading' (duration: 223.892311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:03.227164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.866173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T12:59:03.227242Z","caller":"traceutil/trace.go:171","msg":"trace[1453519081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"202.972235ms","start":"2026-02-24T12:59:03.024253Z","end":"2026-02-24T12:59:03.227225Z","steps":["trace[1453519081] 'agreement among raft nodes before linearized reading' (duration: 202.806241ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:59:04.008317Z","caller":"traceutil/trace.go:171","msg":"trace[59814170] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:552; }","duration":"322.062484ms","start":"2026-02-24T12:59:03.686235Z","end":"2026-02-24T12:59:04.008297Z","steps":["trace[59814170] 'read index received' (duration: 232.296313ms)","trace[59814170] 'applied index is now lower than readState.Index' (duration: 89.764901ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:59:04.008423Z","caller":"traceutil/trace.go:171","msg":"trace[911617468] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"774.331009ms","start":"2026-02-24T12:59:03.234084Z","end":"2026-02-24T12:59:04.008415Z","steps":["trace[911617468] 'process raft request' (duration: 684.489176ms)","trace[911617468] 'compare' (duration: 89.580337ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:59:04.008523Z","caller":"traceutil/trace.go:171","msg":"trace[2038386] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"773.722245ms","start":"2026-02-24T12:59:03.23479Z","end":"2026-02-24T12:59:04.008512Z","steps":["trace[2038386] 'process raft request' (duration: 773.467289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:04.00855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.172614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T12:59:04.008581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8mwx9\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-24T12:59:04.008593Z","caller":"traceutil/trace.go:171","msg":"trace[183141287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"237.248466ms","start":"2026-02-24T12:59:03.771333Z","end":"2026-02-24T12:59:04.008581Z","steps":["trace[183141287] 'agreement among raft nodes before linearized reading' (duration: 237.181194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:04.008593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:03.234759Z","time spent":"773.787986ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":416,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T12:59:04.008603Z","caller":"traceutil/trace.go:171","msg":"trace[767974004] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8mwx9; range_end:; response_count:1; response_revision:531; }","duration":"322.401312ms","start":"2026-02-24T12:59:03.686196Z","end":"2026-02-24T12:59:04.008597Z","steps":["trace[767974004] 'agreement among raft nodes before linearized reading' (duration: 322.280419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:04.008622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:03.686178Z","time spent":"322.439372ms","remote":"127.0.0.1:34866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-8mwx9\" "} {"level":"warn","ts":"2026-02-24T12:59:04.008906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:03.234071Z","time spent":"774.370791ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T12:59:05.471538Z","caller":"traceutil/trace.go:171","msg":"trace[1513870337] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"219.611754ms","start":"2026-02-24T12:59:05.251899Z","end":"2026-02-24T12:59:05.471511Z","steps":["trace[1513870337] 'read index received' (duration: 219.361168ms)","trace[1513870337] 'applied index is now lower than readState.Index' (duration: 249.256µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:59:05.47164Z","caller":"traceutil/trace.go:171","msg":"trace[2042506106] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"236.113799ms","start":"2026-02-24T12:59:05.23549Z","end":"2026-02-24T12:59:05.471604Z","steps":["trace[2042506106] 'process raft request' (duration: 235.867774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:05.471833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.975792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T12:59:05.471898Z","caller":"traceutil/trace.go:171","msg":"trace[2098272182] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"220.095915ms","start":"2026-02-24T12:59:05.251787Z","end":"2026-02-24T12:59:05.471883Z","steps":["trace[2098272182] 'agreement among raft nodes before linearized reading' (duration: 219.83577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:06.272639Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":739888379922086043,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T12:59:06.773192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":739888379922086043,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T12:59:06.787924Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.311392512s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T12:59:06.788187Z","caller":"traceutil/trace.go:171","msg":"trace[391808831] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"1.016511546s","start":"2026-02-24T12:59:05.77166Z","end":"2026-02-24T12:59:06.788172Z","steps":["trace[391808831] 'read index received' (duration: 1.016348222s)","trace[391808831] 'applied index is now lower than readState.Index' (duration: 162.754µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:59:06.788246Z","caller":"traceutil/trace.go:171","msg":"trace[1912518195] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.312000265s","start":"2026-02-24T12:59:05.476239Z","end":"2026-02-24T12:59:06.788239Z","steps":["trace[1912518195] 'process raft request' (duration: 1.311822951s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:06.788322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:05.476211Z","time spent":"1.312056607s","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T12:59:06.788395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016711561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T12:59:06.788446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.046837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-02-24T12:59:06.788434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.070768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T12:59:06.788479Z","caller":"traceutil/trace.go:171","msg":"trace[1878451360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"776.123199ms","start":"2026-02-24T12:59:06.012347Z","end":"2026-02-24T12:59:06.78847Z","steps":["trace[1878451360] 'agreement among raft nodes before linearized reading' (duration: 776.001937ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:59:06.788477Z","caller":"traceutil/trace.go:171","msg":"trace[1788256970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"1.016838293s","start":"2026-02-24T12:59:05.771621Z","end":"2026-02-24T12:59:06.788459Z","steps":["trace[1788256970] 'agreement among raft nodes before linearized reading' (duration: 1.01669431s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:06.788514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:06.012323Z","time spent":"776.18507ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T12:59:06.788501Z","caller":"traceutil/trace.go:171","msg":"trace[1343405627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"776.181151ms","start":"2026-02-24T12:59:06.012303Z","end":"2026-02-24T12:59:06.788484Z","steps":["trace[1343405627] 'agreement among raft nodes before linearized reading' (duration: 775.980706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:06.788522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:05.771601Z","time spent":"1.016913055s","remote":"127.0.0.1:34710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T12:59:06.788637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:06.012277Z","time spent":"776.273352ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T12:59:09.116174Z","caller":"traceutil/trace.go:171","msg":"trace[1468659361] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"152.871386ms","start":"2026-02-24T12:59:08.96328Z","end":"2026-02-24T12:59:09.116151Z","steps":["trace[1468659361] 'process raft request' (duration: 152.725953ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:59:18.254052Z","caller":"traceutil/trace.go:171","msg":"trace[272892379] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"299.376148ms","start":"2026-02-24T12:59:17.954653Z","end":"2026-02-24T12:59:18.254029Z","steps":["trace[272892379] 'process raft request' (duration: 299.236865ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:59:24.304198Z","caller":"traceutil/trace.go:171","msg":"trace[1071995946] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"506.752723ms","start":"2026-02-24T12:59:23.797422Z","end":"2026-02-24T12:59:24.304175Z","steps":["trace[1071995946] 'process raft request' (duration: 506.62927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:24.304355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:23.797391Z","time spent":"506.905246ms","remote":"127.0.0.1:34774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T12:59:25.131913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.840443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:59:25.132238Z","caller":"traceutil/trace.go:171","msg":"trace[1126699557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"361.228421ms","start":"2026-02-24T12:59:24.770968Z","end":"2026-02-24T12:59:25.132197Z","steps":["trace[1126699557] 'range keys from in-memory index tree' (duration: 360.696038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:25.132339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:24.770938Z","time spent":"361.357644ms","remote":"127.0.0.1:34724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T12:59:25.132327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.972866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T12:59:25.132432Z","caller":"traceutil/trace.go:171","msg":"trace[1874032327] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:716; }","duration":"695.160161ms","start":"2026-02-24T12:59:24.437246Z","end":"2026-02-24T12:59:25.132406Z","steps":["trace[1874032327] 'range keys from in-memory index tree' (duration: 694.73572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:25.132508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:24.437216Z","time spent":"695.277874ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T12:59:25.132599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.606473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-24T12:59:25.132319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.249704ms","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-02-24T12:59:25.132695Z","caller":"traceutil/trace.go:171","msg":"trace[116860794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:716; }","duration":"228.725866ms","start":"2026-02-24T12:59:24.903941Z","end":"2026-02-24T12:59:25.132667Z","steps":["trace[116860794] 'range keys from in-memory index tree' (duration: 228.051099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:25.132559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.079912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-24T12:59:25.132819Z","caller":"traceutil/trace.go:171","msg":"trace[592317794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:716; }","duration":"228.388618ms","start":"2026-02-24T12:59:24.904414Z","end":"2026-02-24T12:59:25.132803Z","steps":["trace[592317794] 'range keys from in-memory index tree' (duration: 227.918337ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:59:25.132637Z","caller":"traceutil/trace.go:171","msg":"trace[427627779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:716; }","duration":"264.677914ms","start":"2026-02-24T12:59:24.867949Z","end":"2026-02-24T12:59:25.132627Z","steps":["trace[427627779] 'range keys from in-memory index tree' (duration: 264.466619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:26.017564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":739888379922086391,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T12:59:26.026398Z","caller":"traceutil/trace.go:171","msg":"trace[1819032458] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"888.358608ms","start":"2026-02-24T12:59:25.138018Z","end":"2026-02-24T12:59:26.026377Z","steps":["trace[1819032458] 'process raft request' (duration: 888.231845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:26.026517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:25.138006Z","time spent":"888.44896ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T12:59:26.51919Z","caller":"traceutil/trace.go:171","msg":"trace[434829796] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"1.38106382s","start":"2026-02-24T12:59:25.138097Z","end":"2026-02-24T12:59:26.51916Z","steps":["trace[434829796] 'process raft request' (duration: 1.380836344s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:26.519385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:25.138068Z","time spent":"1.381210643s","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T12:59:26.519625Z","caller":"traceutil/trace.go:171","msg":"trace[1400436424] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:744; }","duration":"1.023945113s","start":"2026-02-24T12:59:25.495649Z","end":"2026-02-24T12:59:26.519594Z","steps":["trace[1400436424] 'read index received' (duration: 530.608947ms)","trace[1400436424] 'applied index is now lower than readState.Index' (duration: 493.335076ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:59:26.519766Z","caller":"traceutil/trace.go:171","msg":"trace[1657893351] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"1.381396177s","start":"2026-02-24T12:59:25.138152Z","end":"2026-02-24T12:59:26.519548Z","steps":["trace[1657893351] 'process raft request' (duration: 1.380958547s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:26.520455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:25.138129Z","time spent":"1.382273658s","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T12:59:26.519892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:59:26.520609Z","caller":"traceutil/trace.go:171","msg":"trace[84505026] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:720; }","duration":"1.025022047s","start":"2026-02-24T12:59:25.495571Z","end":"2026-02-24T12:59:26.520593Z","steps":["trace[84505026] 'agreement among raft nodes before linearized reading' (duration: 1.024248949s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:26.520671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:25.495541Z","time spent":"1.025115309s","remote":"127.0.0.1:46794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-02-24T12:59:26.519891Z","caller":"traceutil/trace.go:171","msg":"trace[1604211465] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"1.381404567s","start":"2026-02-24T12:59:25.138451Z","end":"2026-02-24T12:59:26.519855Z","steps":["trace[1604211465] 'process raft request' (duration: 1.381010648s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:26.520818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:25.138441Z","time spent":"1.382338009s","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T12:59:26.51999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.817631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:59:26.520955Z","caller":"traceutil/trace.go:171","msg":"trace[768078210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"749.826285ms","start":"2026-02-24T12:59:25.771113Z","end":"2026-02-24T12:59:26.52094Z","steps":["trace[768078210] 'agreement among raft nodes before linearized reading' (duration: 748.855663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:26.520992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:25.771087Z","time spent":"749.897357ms","remote":"127.0.0.1:34724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T12:59:26.519978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.805962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T12:59:26.521069Z","caller":"traceutil/trace.go:171","msg":"trace[1767337026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:720; }","duration":"924.746275ms","start":"2026-02-24T12:59:25.596314Z","end":"2026-02-24T12:59:26.52106Z","steps":["trace[1767337026] 'agreement among raft nodes before linearized reading' (duration: 923.563569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:26.521104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:25.59629Z","time spent":"924.804206ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T12:59:26.520032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.293178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:59:26.521176Z","caller":"traceutil/trace.go:171","msg":"trace[1612711043] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:720; }","duration":"259.494385ms","start":"2026-02-24T12:59:26.261674Z","end":"2026-02-24T12:59:26.521169Z","steps":["trace[1612711043] 'agreement among raft nodes before linearized reading' (duration: 258.336259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:26.520047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.689362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:59:26.52134Z","caller":"traceutil/trace.go:171","msg":"trace[1422447325] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:720; }","duration":"798.058242ms","start":"2026-02-24T12:59:25.723271Z","end":"2026-02-24T12:59:26.521329Z","steps":["trace[1422447325] 'agreement among raft nodes before linearized reading' (duration: 796.701432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:26.521365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:25.723243Z","time spent":"798.113433ms","remote":"127.0.0.1:34964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-02-24T12:59:27.034705Z","caller":"traceutil/trace.go:171","msg":"trace[457880616] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:748; }","duration":"262.669998ms","start":"2026-02-24T12:59:26.771996Z","end":"2026-02-24T12:59:27.034666Z","steps":["trace[457880616] 'read index received' (duration: 205.947206ms)","trace[457880616] 'applied index is now lower than readState.Index' (duration: 56.721012ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:59:27.034767Z","caller":"traceutil/trace.go:171","msg":"trace[819175352] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"509.537696ms","start":"2026-02-24T12:59:26.525197Z","end":"2026-02-24T12:59:27.034735Z","steps":["trace[819175352] 'process raft request' (duration: 452.782064ms)","trace[819175352] 'compare' (duration: 56.413404ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:59:27.034876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.867493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:59:27.034934Z","caller":"traceutil/trace.go:171","msg":"trace[11685331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"262.974715ms","start":"2026-02-24T12:59:26.771943Z","end":"2026-02-24T12:59:27.034917Z","steps":["trace[11685331] 'agreement among raft nodes before linearized reading' (duration: 262.858313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:27.034927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:26.525164Z","time spent":"509.663659ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T12:59:27.035038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.122315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.85\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T12:59:27.035146Z","caller":"traceutil/trace.go:171","msg":"trace[1559107171] range","detail":"{range_begin:/registry/masterleases/199.19.213.85; range_end:; response_count:1; response_revision:721; }","duration":"106.296919ms","start":"2026-02-24T12:59:26.928825Z","end":"2026-02-24T12:59:27.035122Z","steps":["trace[1559107171] 'agreement among raft nodes before linearized reading' (duration: 106.071684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:28.271661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":739888379922086407,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T12:59:28.4082Z","caller":"traceutil/trace.go:171","msg":"trace[1171687971] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:751; }","duration":"637.184641ms","start":"2026-02-24T12:59:27.770982Z","end":"2026-02-24T12:59:28.408167Z","steps":["trace[1171687971] 'read index received' (duration: 636.780802ms)","trace[1171687971] 'applied index is now lower than readState.Index' (duration: 402.709µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:59:28.408417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.408316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:59:28.408499Z","caller":"traceutil/trace.go:171","msg":"trace[1754566011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:723; }","duration":"637.543369ms","start":"2026-02-24T12:59:27.770941Z","end":"2026-02-24T12:59:28.408484Z","steps":["trace[1754566011] 'agreement among raft nodes before linearized reading' (duration: 637.390796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:28.408549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:27.770922Z","time spent":"637.618262ms","remote":"127.0.0.1:34710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T12:59:28.408556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.608806ms","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-02-24T12:59:28.408601Z","caller":"traceutil/trace.go:171","msg":"trace[230199960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:723; }","duration":"378.74735ms","start":"2026-02-24T12:59:28.02984Z","end":"2026-02-24T12:59:28.408587Z","steps":["trace[230199960] 'agreement among raft nodes before linearized reading' (duration: 378.514694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:28.408653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:28.02982Z","time spent":"378.819381ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T12:59:28.408977Z","caller":"traceutil/trace.go:171","msg":"trace[1844393789] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"908.27486ms","start":"2026-02-24T12:59:27.5Z","end":"2026-02-24T12:59:28.408275Z","steps":["trace[1844393789] 'process raft request' (duration: 907.84427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:28.409157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:27.49997Z","time spent":"909.093548ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T12:59:28.850171Z","caller":"traceutil/trace.go:171","msg":"trace[1508276891] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"435.604434ms","start":"2026-02-24T12:59:28.414543Z","end":"2026-02-24T12:59:28.850147Z","steps":["trace[1508276891] 'process raft request' (duration: 417.308267ms)","trace[1508276891] 'compare' (duration: 17.964259ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:59:28.850287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:28.414515Z","time spent":"435.712646ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T12:59:28.850333Z","caller":"traceutil/trace.go:171","msg":"trace[231404001] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:752; }","duration":"308.366748ms","start":"2026-02-24T12:59:28.54157Z","end":"2026-02-24T12:59:28.849937Z","steps":["trace[231404001] 'read index received' (duration: 290.291206ms)","trace[231404001] 'applied index is now lower than readState.Index' (duration: 18.074782ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:59:28.850543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.960952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T12:59:28.850574Z","caller":"traceutil/trace.go:171","msg":"trace[1033456965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:724; }","duration":"309.036323ms","start":"2026-02-24T12:59:28.541525Z","end":"2026-02-24T12:59:28.850562Z","steps":["trace[1033456965] 'agreement among raft nodes before linearized reading' (duration: 308.911311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:28.850602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:28.5415Z","time spent":"309.094375ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T12:59:28.850731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.980179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-24T12:59:28.850772Z","caller":"traceutil/trace.go:171","msg":"trace[1690284730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:724; }","duration":"308.034071ms","start":"2026-02-24T12:59:28.542725Z","end":"2026-02-24T12:59:28.850759Z","steps":["trace[1690284730] 'agreement among raft nodes before linearized reading' (duration: 307.888988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:28.850799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:28.542718Z","time spent":"308.074522ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T12:59:28.850848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.853276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T12:59:28.850952Z","caller":"traceutil/trace.go:171","msg":"trace[440664023] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:724; }","duration":"307.99318ms","start":"2026-02-24T12:59:28.542941Z","end":"2026-02-24T12:59:28.850934Z","steps":["trace[440664023] 'agreement among raft nodes before linearized reading' (duration: 307.760815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:28.851162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:28.542935Z","time spent":"308.047341ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T12:59:49.37613Z","caller":"traceutil/trace.go:171","msg":"trace[923195073] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"372.516707ms","start":"2026-02-24T12:59:49.003587Z","end":"2026-02-24T12:59:49.376103Z","steps":["trace[923195073] 'read index received' (duration: 352.639235ms)","trace[923195073] 'applied index is now lower than readState.Index' (duration: 19.876312ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:59:49.376366Z","caller":"traceutil/trace.go:171","msg":"trace[763412053] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"403.3714ms","start":"2026-02-24T12:59:48.972981Z","end":"2026-02-24T12:59:49.376353Z","steps":["trace[763412053] 'process raft request' (duration: 383.327084ms)","trace[763412053] 'compare' (duration: 19.539335ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:59:49.376477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:48.972973Z","time spent":"403.431601ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T12:59:49.376499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.355118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-24T12:59:49.376525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.411669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-24T12:59:49.376562Z","caller":"traceutil/trace.go:171","msg":"trace[480055898] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"196.4603ms","start":"2026-02-24T12:59:49.180082Z","end":"2026-02-24T12:59:49.376542Z","steps":["trace[480055898] 'agreement among raft nodes before linearized reading' (duration: 196.271286ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:59:49.376593Z","caller":"traceutil/trace.go:171","msg":"trace[1626326347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:786; }","duration":"366.572013ms","start":"2026-02-24T12:59:49.010004Z","end":"2026-02-24T12:59:49.376576Z","steps":["trace[1626326347] 'agreement among raft nodes before linearized reading' (duration: 366.325837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:49.376643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:49.009973Z","time spent":"366.660314ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T12:59:49.376713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.200134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-24T12:59:49.376703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.775535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T12:59:49.376747Z","caller":"traceutil/trace.go:171","msg":"trace[1641959659] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:786; }","duration":"359.254685ms","start":"2026-02-24T12:59:49.017482Z","end":"2026-02-24T12:59:49.376737Z","steps":["trace[1641959659] 'agreement among raft nodes before linearized reading' (duration: 359.160863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:49.376741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.139221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-24T12:59:49.376781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:49.017472Z","time spent":"359.302696ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T12:59:49.376768Z","caller":"traceutil/trace.go:171","msg":"trace[21803788] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:786; }","duration":"322.918049ms","start":"2026-02-24T12:59:49.053832Z","end":"2026-02-24T12:59:49.37675Z","steps":["trace[21803788] 'agreement among raft nodes before linearized reading' (duration: 322.785426ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:59:49.376792Z","caller":"traceutil/trace.go:171","msg":"trace[466026609] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:786; }","duration":"373.197462ms","start":"2026-02-24T12:59:49.003582Z","end":"2026-02-24T12:59:49.376779Z","steps":["trace[466026609] 'agreement among raft nodes before linearized reading' (duration: 373.060109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:49.376835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:49.053804Z","time spent":"323.01687ms","remote":"127.0.0.1:46810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-24T12:59:49.37684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:59:49.003555Z","time spent":"373.277625ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T12:59:49.528547Z","caller":"traceutil/trace.go:171","msg":"trace[1835786251] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"146.834601ms","start":"2026-02-24T12:59:49.381678Z","end":"2026-02-24T12:59:49.528512Z","steps":["trace[1835786251] 'process raft request' (duration: 146.509704ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:59:49.528575Z","caller":"traceutil/trace.go:171","msg":"trace[364018915] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"146.356821ms","start":"2026-02-24T12:59:49.382177Z","end":"2026-02-24T12:59:49.528534Z","steps":["trace[364018915] 'process raft request' (duration: 146.250249ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:59:49.528816Z","caller":"traceutil/trace.go:171","msg":"trace[979894305] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"146.054784ms","start":"2026-02-24T12:59:49.38275Z","end":"2026-02-24T12:59:49.528805Z","steps":["trace[979894305] 'process raft request' (duration: 145.733267ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:59:51.295318Z","caller":"traceutil/trace.go:171","msg":"trace[1879745939] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"293.690584ms","start":"2026-02-24T12:59:51.001579Z","end":"2026-02-24T12:59:51.295269Z","steps":["trace[1879745939] 'process raft request' (duration: 293.52673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:51.68606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.886169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-24T12:59:51.686106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.355926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-24T12:59:51.686147Z","caller":"traceutil/trace.go:171","msg":"trace[93379593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:792; }","duration":"153.036873ms","start":"2026-02-24T12:59:51.533091Z","end":"2026-02-24T12:59:51.686128Z","steps":["trace[93379593] 'range keys from in-memory index tree' (duration: 152.648894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:51.686143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.545581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-24T12:59:51.686176Z","caller":"traceutil/trace.go:171","msg":"trace[921128716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:792; }","duration":"152.437989ms","start":"2026-02-24T12:59:51.533718Z","end":"2026-02-24T12:59:51.686156Z","steps":["trace[921128716] 'range keys from in-memory index tree' (duration: 152.174843ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:59:51.686198Z","caller":"traceutil/trace.go:171","msg":"trace[795106117] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"152.629753ms","start":"2026-02-24T12:59:51.533555Z","end":"2026-02-24T12:59:51.686185Z","steps":["trace[795106117] 'range keys from in-memory index tree' (duration: 152.423269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:59:51.686368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.469416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T12:59:51.686391Z","caller":"traceutil/trace.go:171","msg":"trace[1498099389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"292.521906ms","start":"2026-02-24T12:59:51.393863Z","end":"2026-02-24T12:59:51.686385Z","steps":["trace[1498099389] 'range keys from in-memory index tree' (duration: 292.356113ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:59:51.913974Z","caller":"traceutil/trace.go:171","msg":"trace[1287145058] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"223.20922ms","start":"2026-02-24T12:59:51.69074Z","end":"2026-02-24T12:59:51.913949Z","steps":["trace[1287145058] 'process raft request' (duration: 223.010015ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:59:51.914289Z","caller":"traceutil/trace.go:171","msg":"trace[205926880] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"221.815258ms","start":"2026-02-24T12:59:51.692444Z","end":"2026-02-24T12:59:51.914259Z","steps":["trace[205926880] 'process raft request' (duration: 221.674565ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:59:51.914291Z","caller":"traceutil/trace.go:171","msg":"trace[870425257] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"223.078927ms","start":"2026-02-24T12:59:51.691184Z","end":"2026-02-24T12:59:51.914263Z","steps":["trace[870425257] 'process raft request' (duration: 222.722739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:04.856112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.812142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:00:04.856193Z","caller":"traceutil/trace.go:171","msg":"trace[400753727] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:880; }","duration":"679.944154ms","start":"2026-02-24T13:00:04.176232Z","end":"2026-02-24T13:00:04.856176Z","steps":["trace[400753727] 'count revisions from in-memory index tree' (duration: 679.71704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:04.856219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:04.176203Z","time spent":"680.007366ms","remote":"127.0.0.1:46868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-02-24T13:00:05.590581Z","caller":"traceutil/trace.go:171","msg":"trace[444920803] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"215.206678ms","start":"2026-02-24T13:00:05.375354Z","end":"2026-02-24T13:00:05.590561Z","steps":["trace[444920803] 'process raft request' (duration: 215.103856ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:00:17.832301Z","caller":"traceutil/trace.go:171","msg":"trace[102296059] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"166.725485ms","start":"2026-02-24T13:00:17.665538Z","end":"2026-02-24T13:00:17.832264Z","steps":["trace[102296059] 'process raft request' (duration: 166.343117ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:00:22.073652Z","caller":"traceutil/trace.go:171","msg":"trace[1614609701] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"220.13636ms","start":"2026-02-24T13:00:21.853494Z","end":"2026-02-24T13:00:22.073631Z","steps":["trace[1614609701] 'process raft request' (duration: 219.961486ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:00:23.230066Z","caller":"traceutil/trace.go:171","msg":"trace[1116889676] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"127.551542ms","start":"2026-02-24T13:00:23.102493Z","end":"2026-02-24T13:00:23.230045Z","steps":["trace[1116889676] 'process raft request' (duration: 127.43638ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:00:24.472898Z","caller":"traceutil/trace.go:171","msg":"trace[231116856] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"389.020744ms","start":"2026-02-24T13:00:24.08385Z","end":"2026-02-24T13:00:24.47287Z","steps":["trace[231116856] 'read index received' (duration: 388.757798ms)","trace[231116856] 'applied index is now lower than readState.Index' (duration: 261.706µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:00:24.473009Z","caller":"traceutil/trace.go:171","msg":"trace[861380562] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"389.962364ms","start":"2026-02-24T13:00:24.083022Z","end":"2026-02-24T13:00:24.472984Z","steps":["trace[861380562] 'process raft request' (duration: 389.673588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:24.473159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:24.082993Z","time spent":"390.074057ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:00:24.473208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.349451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-24T13:00:24.473256Z","caller":"traceutil/trace.go:171","msg":"trace[365255110] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:953; }","duration":"389.422702ms","start":"2026-02-24T13:00:24.083818Z","end":"2026-02-24T13:00:24.473241Z","steps":["trace[365255110] 'agreement among raft nodes before linearized reading' (duration: 389.213697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:24.473295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:24.083807Z","time spent":"389.478064ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T13:00:24.473484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.994637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T13:00:24.473606Z","caller":"traceutil/trace.go:171","msg":"trace[1644473684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:953; }","duration":"387.044858ms","start":"2026-02-24T13:00:24.086468Z","end":"2026-02-24T13:00:24.473512Z","steps":["trace[1644473684] 'agreement among raft nodes before linearized reading' (duration: 386.931966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:24.473652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:24.086463Z","time spent":"387.176571ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T13:00:24.473849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.698183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-24T13:00:24.473796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.715666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-24T13:00:24.473996Z","caller":"traceutil/trace.go:171","msg":"trace[102581116] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:953; }","duration":"388.998283ms","start":"2026-02-24T13:00:24.084968Z","end":"2026-02-24T13:00:24.473966Z","steps":["trace[102581116] 'agreement among raft nodes before linearized reading' (duration: 388.415339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:24.474092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:24.084952Z","time spent":"389.127356ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T13:00:24.473908Z","caller":"traceutil/trace.go:171","msg":"trace[946584705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:953; }","duration":"387.775085ms","start":"2026-02-24T13:00:24.086116Z","end":"2026-02-24T13:00:24.473892Z","steps":["trace[946584705] 'agreement among raft nodes before linearized reading' (duration: 387.628171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:24.474368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:24.086111Z","time spent":"388.245446ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T13:00:25.33717Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":739888379922086977,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T13:00:25.456944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.610108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:00:25.457345Z","caller":"traceutil/trace.go:171","msg":"trace[1411175434] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"978.006858ms","start":"2026-02-24T13:00:24.47929Z","end":"2026-02-24T13:00:25.457297Z","steps":["trace[1411175434] 'process raft request' (duration: 869.58911ms)","trace[1411175434] 'compare' (duration: 107.188419ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:00:25.457529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:24.479261Z","time spent":"978.177252ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:00:25.555411Z","caller":"traceutil/trace.go:171","msg":"trace[213974388] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"1.075183499s","start":"2026-02-24T13:00:24.4802Z","end":"2026-02-24T13:00:25.555383Z","steps":["trace[213974388] 'process raft request' (duration: 1.075033874s)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:00:25.555379Z","caller":"traceutil/trace.go:171","msg":"trace[1742526979] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"1.07520421s","start":"2026-02-24T13:00:24.480127Z","end":"2026-02-24T13:00:25.555331Z","steps":["trace[1742526979] 'process raft request' (duration: 1.075067736s)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:00:25.5555Z","caller":"traceutil/trace.go:171","msg":"trace[1382270008] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:996; }","duration":"718.549183ms","start":"2026-02-24T13:00:24.836938Z","end":"2026-02-24T13:00:25.555487Z","steps":["trace[1382270008] 'read index received' (duration: 511.970701ms)","trace[1382270008] 'applied index is now lower than readState.Index' (duration: 206.576912ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:00:25.555576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:24.480191Z","time spent":"1.075292141s","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:00:25.555607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:24.480116Z","time spent":"1.075386684s","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:00:25.555411Z","caller":"traceutil/trace.go:171","msg":"trace[1029861906] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.075617359s","start":"2026-02-24T13:00:24.479767Z","end":"2026-02-24T13:00:25.555384Z","steps":["trace[1029861906] 'process raft request' (duration: 1.075227889s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:25.5558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:24.479738Z","time spent":"1.076012727s","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:00:25.628643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.656827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:00:25.62877Z","caller":"traceutil/trace.go:171","msg":"trace[1508408036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"791.863541ms","start":"2026-02-24T13:00:24.836873Z","end":"2026-02-24T13:00:25.628737Z","steps":["trace[1508408036] 'agreement among raft nodes before linearized reading' (duration: 718.657765ms)","trace[1508408036] 'range keys from in-memory index tree' (duration: 73.018462ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:00:25.628839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:24.83685Z","time spent":"791.977784ms","remote":"127.0.0.1:34724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T13:00:26.325224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.917608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:00:26.325372Z","caller":"traceutil/trace.go:171","msg":"trace[1886647209] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"221.868043ms","start":"2026-02-24T13:00:26.103479Z","end":"2026-02-24T13:00:26.325347Z","steps":["trace[1886647209] 'process raft request' (duration: 48.713129ms)","trace[1886647209] 'compare' (duration: 172.693973ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:00:26.402624Z","caller":"traceutil/trace.go:171","msg":"trace[1885852452] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"123.162007ms","start":"2026-02-24T13:00:26.27945Z","end":"2026-02-24T13:00:26.402612Z","steps":["trace[1885852452] 'process raft request' (duration: 122.975822ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:00:26.402583Z","caller":"traceutil/trace.go:171","msg":"trace[208469903] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"102.256412ms","start":"2026-02-24T13:00:26.300304Z","end":"2026-02-24T13:00:26.402561Z","steps":["trace[208469903] 'read index received' (duration: 102.208311ms)","trace[208469903] 'applied index is now lower than readState.Index' (duration: 46.971µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:00:26.403008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.653421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:00:26.403149Z","caller":"traceutil/trace.go:171","msg":"trace[1660881146] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:960; }","duration":"102.842375ms","start":"2026-02-24T13:00:26.30028Z","end":"2026-02-24T13:00:26.403123Z","steps":["trace[1660881146] 'agreement among raft nodes before linearized reading' (duration: 102.477407ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:00:26.496384Z","caller":"traceutil/trace.go:171","msg":"trace[899143910] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"165.276663ms","start":"2026-02-24T13:00:26.331085Z","end":"2026-02-24T13:00:26.496362Z","steps":["trace[899143910] 'process raft request' (duration: 118.0031ms)","trace[899143910] 'compare' (duration: 46.984666ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:00:26.496778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.932655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:471"} {"level":"info","ts":"2026-02-24T13:00:26.496834Z","caller":"traceutil/trace.go:171","msg":"trace[1522663298] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:962; }","duration":"165.037258ms","start":"2026-02-24T13:00:26.331783Z","end":"2026-02-24T13:00:26.496821Z","steps":["trace[1522663298] 'agreement among raft nodes before linearized reading' (duration: 164.655008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:27.470655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.8145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:00:27.470775Z","caller":"traceutil/trace.go:171","msg":"trace[216919482] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1005; }","duration":"959.245941ms","start":"2026-02-24T13:00:26.511514Z","end":"2026-02-24T13:00:27.470759Z","steps":["trace[216919482] 'read index received' (duration: 59.983912ms)","trace[216919482] 'applied index is now lower than readState.Index' (duration: 899.258649ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:00:27.470912Z","caller":"traceutil/trace.go:171","msg":"trace[1626858211] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"969.089884ms","start":"2026-02-24T13:00:26.50181Z","end":"2026-02-24T13:00:27.4709Z","steps":["trace[1626858211] 'process raft request' (duration: 98.963617ms)","trace[1626858211] 'compare' (duration: 869.718018ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:00:27.470972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:26.501778Z","time spent":"969.164546ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:00:27.470998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.872189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T13:00:27.471048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.529547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:00:27.471122Z","caller":"traceutil/trace.go:171","msg":"trace[142768430] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:964; }","duration":"959.65225ms","start":"2026-02-24T13:00:26.511454Z","end":"2026-02-24T13:00:27.471107Z","steps":["trace[142768430] 'agreement among raft nodes before linearized reading' (duration: 959.536097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:27.471165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:26.511439Z","time spent":"959.717191ms","remote":"127.0.0.1:46838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-24T13:00:27.471314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.042562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.85\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T13:00:27.471419Z","caller":"traceutil/trace.go:171","msg":"trace[348065569] range","detail":"{range_begin:/registry/masterleases/199.19.213.85; range_end:; response_count:1; response_revision:964; }","duration":"540.155044ms","start":"2026-02-24T13:00:26.931229Z","end":"2026-02-24T13:00:27.471384Z","steps":["trace[348065569] 'agreement among raft nodes before linearized reading' (duration: 539.875648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:27.471171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.7935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T13:00:27.47147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:26.931212Z","time spent":"540.246598ms","remote":"127.0.0.1:34738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.85\" "} {"level":"info","ts":"2026-02-24T13:00:27.471511Z","caller":"traceutil/trace.go:171","msg":"trace[1964987461] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:964; }","duration":"176.18557ms","start":"2026-02-24T13:00:27.295307Z","end":"2026-02-24T13:00:27.471493Z","steps":["trace[1964987461] 'agreement among raft nodes before linearized reading' (duration: 175.76763ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:00:27.471078Z","caller":"traceutil/trace.go:171","msg":"trace[85396756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"698.962791ms","start":"2026-02-24T13:00:26.772094Z","end":"2026-02-24T13:00:27.471057Z","steps":["trace[85396756] 'agreement among raft nodes before linearized reading' (duration: 698.829158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:27.471764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:26.772035Z","time spent":"699.714908ms","remote":"127.0.0.1:34724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T13:00:28.060575Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":739888379922086997,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T13:00:28.780332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.592711ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-24T13:00:28.780421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:27.473205Z","time spent":"1.307213492s","remote":"127.0.0.1:34738","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-24T13:00:28.878166Z","caller":"traceutil/trace.go:171","msg":"trace[850424996] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1007; }","duration":"1.318302645s","start":"2026-02-24T13:00:27.559845Z","end":"2026-02-24T13:00:28.878148Z","steps":["trace[850424996] 'read index received' (duration: 518.879392ms)","trace[850424996] 'applied index is now lower than readState.Index' (duration: 799.422463ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:00:28.878217Z","caller":"traceutil/trace.go:171","msg":"trace[1745664722] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"1.402677391s","start":"2026-02-24T13:00:27.475514Z","end":"2026-02-24T13:00:28.878191Z","steps":["trace[1745664722] 'process raft request' (duration: 1.402522797s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:28.878341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318483659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-24T13:00:28.878371Z","caller":"traceutil/trace.go:171","msg":"trace[240545703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:965; }","duration":"1.31855804s","start":"2026-02-24T13:00:27.559805Z","end":"2026-02-24T13:00:28.878363Z","steps":["trace[240545703] 'agreement among raft nodes before linearized reading' (duration: 1.318422757s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:28.87836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:27.475499Z","time spent":"1.402777142s","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:00:28.878392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:27.559786Z","time spent":"1.318600951s","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T13:00:29.078527Z","caller":"traceutil/trace.go:171","msg":"trace[1335573850] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"200.288657ms","start":"2026-02-24T13:00:28.878213Z","end":"2026-02-24T13:00:29.078502Z","steps":["trace[1335573850] 'read index received' (duration: 143.652371ms)","trace[1335573850] 'applied index is now lower than readState.Index' (duration: 56.635296ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:00:29.078657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.495683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:00:29.078679Z","caller":"traceutil/trace.go:171","msg":"trace[1869670415] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"293.391362ms","start":"2026-02-24T13:00:28.785255Z","end":"2026-02-24T13:00:29.078646Z","steps":["trace[1869670415] 'process raft request' (duration: 236.698684ms)","trace[1869670415] 'compare' (duration: 56.440432ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:00:29.078725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.518723595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-24T13:00:29.078735Z","caller":"traceutil/trace.go:171","msg":"trace[2080411290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"304.623466ms","start":"2026-02-24T13:00:28.774093Z","end":"2026-02-24T13:00:29.078717Z","steps":["trace[2080411290] 'agreement among raft nodes before linearized reading' (duration: 304.436252ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:00:29.078764Z","caller":"traceutil/trace.go:171","msg":"trace[106500397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:966; }","duration":"1.518796706s","start":"2026-02-24T13:00:27.559958Z","end":"2026-02-24T13:00:29.078754Z","steps":["trace[106500397] 'agreement among raft nodes before linearized reading' (duration: 1.518650963s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:29.078774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:28.774078Z","time spent":"304.688777ms","remote":"127.0.0.1:34724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T13:00:29.078799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:27.559951Z","time spent":"1.518841007s","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T13:00:29.078993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.837155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:00:29.079034Z","caller":"traceutil/trace.go:171","msg":"trace[864042358] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:966; }","duration":"287.924027ms","start":"2026-02-24T13:00:28.7911Z","end":"2026-02-24T13:00:29.079024Z","steps":["trace[864042358] 'agreement among raft nodes before linearized reading' (duration: 287.854925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:29.079072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.5176512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-24T13:00:29.079101Z","caller":"traceutil/trace.go:171","msg":"trace[1924819387] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"1.517698581s","start":"2026-02-24T13:00:27.561394Z","end":"2026-02-24T13:00:29.079092Z","steps":["trace[1924819387] 'agreement among raft nodes before linearized reading' (duration: 1.517616909s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:29.079112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.582072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-24T13:00:29.079136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:27.561385Z","time spent":"1.517744242s","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T13:00:29.079151Z","caller":"traceutil/trace.go:171","msg":"trace[1780077842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"502.658714ms","start":"2026-02-24T13:00:28.576481Z","end":"2026-02-24T13:00:29.079139Z","steps":["trace[1780077842] 'agreement among raft nodes before linearized reading' (duration: 502.49102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:29.079191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:28.576464Z","time spent":"502.712004ms","remote":"127.0.0.1:34920","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-02-24T13:00:29.079008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470420708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:00:29.079416Z","caller":"traceutil/trace.go:171","msg":"trace[780560623] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:966; }","duration":"1.470892969s","start":"2026-02-24T13:00:27.6085Z","end":"2026-02-24T13:00:29.079393Z","steps":["trace[780560623] 'agreement among raft nodes before linearized reading' (duration: 1.470203743s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:29.079589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:27.608482Z","time spent":"1.471090794s","remote":"127.0.0.1:46738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-02-24T13:00:29.115356Z","caller":"traceutil/trace.go:171","msg":"trace[1091473282] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"233.296987ms","start":"2026-02-24T13:00:28.882043Z","end":"2026-02-24T13:00:29.11534Z","steps":["trace[1091473282] 'process raft request' (duration: 233.200494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:30.091014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.786332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:00:30.091219Z","caller":"traceutil/trace.go:171","msg":"trace[1270762851] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"1.008693923s","start":"2026-02-24T13:00:29.082507Z","end":"2026-02-24T13:00:30.0912Z","steps":["trace[1270762851] 'process raft request' (duration: 425.641405ms)","trace[1270762851] 'compare' (duration: 582.466624ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:00:30.091316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:29.082492Z","time spent":"1.008770396s","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:00:30.091838Z","caller":"traceutil/trace.go:171","msg":"trace[272765115] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1011; }","duration":"976.219226ms","start":"2026-02-24T13:00:29.115605Z","end":"2026-02-24T13:00:30.091824Z","steps":["trace[272765115] 'read index received' (duration: 392.470812ms)","trace[272765115] 'applied index is now lower than readState.Index' (duration: 583.747624ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:00:30.091852Z","caller":"traceutil/trace.go:171","msg":"trace[2097099289] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"1.008777176s","start":"2026-02-24T13:00:29.083061Z","end":"2026-02-24T13:00:30.091838Z","steps":["trace[2097099289] 'process raft request' (duration: 1.008046399s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:30.091929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:29.083047Z","time spent":"1.008838587s","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:00:30.091963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011263242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-24T13:00:30.091996Z","caller":"traceutil/trace.go:171","msg":"trace[2081222546] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:970; }","duration":"1.011345974s","start":"2026-02-24T13:00:29.08064Z","end":"2026-02-24T13:00:30.091986Z","steps":["trace[2081222546] 'agreement among raft nodes before linearized reading' (duration: 1.011276272s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:30.092035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:29.080623Z","time spent":"1.011402235s","remote":"127.0.0.1:34856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-24T13:00:30.092058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.494581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:00:30.092087Z","caller":"traceutil/trace.go:171","msg":"trace[892220699] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:970; }","duration":"488.567563ms","start":"2026-02-24T13:00:29.603511Z","end":"2026-02-24T13:00:30.092078Z","steps":["trace[892220699] 'agreement among raft nodes before linearized reading' (duration: 488.508712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:30.092136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:29.60348Z","time spent":"488.638045ms","remote":"127.0.0.1:34866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-02-24T13:00:30.091887Z","caller":"traceutil/trace.go:171","msg":"trace[1953942599] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"1.008582421s","start":"2026-02-24T13:00:29.08328Z","end":"2026-02-24T13:00:30.091863Z","steps":["trace[1953942599] 'process raft request' (duration: 1.008486589s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:30.092969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:29.083271Z","time spent":"1.009662025s","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:00:31.038361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.198754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:00:31.038502Z","caller":"traceutil/trace.go:171","msg":"trace[1588282058] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:970; }","duration":"798.390598ms","start":"2026-02-24T13:00:30.240084Z","end":"2026-02-24T13:00:31.038474Z","steps":["trace[1588282058] 'count revisions from in-memory index tree' (duration: 798.060531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:31.038557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:30.24006Z","time spent":"798.47885ms","remote":"127.0.0.1:38582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-02-24T13:00:31.144632Z","caller":"traceutil/trace.go:171","msg":"trace[438921136] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"858.043403ms","start":"2026-02-24T13:00:30.286565Z","end":"2026-02-24T13:00:31.144608Z","steps":["trace[438921136] 'process raft request' (duration: 857.89178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:31.144878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:30.286515Z","time spent":"858.258858ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:00:31.144893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.623144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:00:31.144987Z","caller":"traceutil/trace.go:171","msg":"trace[799528134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"373.770498ms","start":"2026-02-24T13:00:30.771202Z","end":"2026-02-24T13:00:31.144973Z","steps":["trace[799528134] 'agreement among raft nodes before linearized reading' (duration: 373.626384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:31.145043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:30.771181Z","time spent":"373.850179ms","remote":"127.0.0.1:34724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T13:00:31.145358Z","caller":"traceutil/trace.go:171","msg":"trace[2043351900] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1015; }","duration":"373.494832ms","start":"2026-02-24T13:00:30.771262Z","end":"2026-02-24T13:00:31.144757Z","steps":["trace[2043351900] 'read index received' (duration: 373.460291ms)","trace[2043351900] 'applied index is now lower than readState.Index' (duration: 31.931µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:00:31.624856Z","caller":"traceutil/trace.go:171","msg":"trace[1555207948] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"479.186561ms","start":"2026-02-24T13:00:31.145641Z","end":"2026-02-24T13:00:31.624828Z","steps":["trace[1555207948] 'read index received' (duration: 446.936849ms)","trace[1555207948] 'applied index is now lower than readState.Index' (duration: 32.248552ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:00:31.625018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.960496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T13:00:31.625177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.186634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-24T13:00:31.625221Z","caller":"traceutil/trace.go:171","msg":"trace[280259306] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:972; }","duration":"690.192621ms","start":"2026-02-24T13:00:30.93501Z","end":"2026-02-24T13:00:31.625202Z","steps":["trace[280259306] 'agreement among raft nodes before linearized reading' (duration: 689.948346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:31.625025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.419769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T13:00:31.625289Z","caller":"traceutil/trace.go:171","msg":"trace[148666492] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:972; }","duration":"506.745057ms","start":"2026-02-24T13:00:31.118531Z","end":"2026-02-24T13:00:31.625276Z","steps":["trace[148666492] 'agreement among raft nodes before linearized reading' (duration: 506.333438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:31.625364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:31.118513Z","time spent":"506.837508ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T13:00:31.625342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:30.935002Z","time spent":"690.315674ms","remote":"127.0.0.1:46764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-02-24T13:00:31.625082Z","caller":"traceutil/trace.go:171","msg":"trace[871038001] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"586.20945ms","start":"2026-02-24T13:00:31.038855Z","end":"2026-02-24T13:00:31.625064Z","steps":["trace[871038001] 'process raft request' (duration: 553.710563ms)","trace[871038001] 'compare' (duration: 32.107638ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:00:31.625693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:31.038799Z","time spent":"586.722031ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:00:31.625229Z","caller":"traceutil/trace.go:171","msg":"trace[260208636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:972; }","duration":"743.279066ms","start":"2026-02-24T13:00:30.881936Z","end":"2026-02-24T13:00:31.625215Z","steps":["trace[260208636] 'agreement among raft nodes before linearized reading' (duration: 743.135253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:31.625882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:30.881916Z","time spent":"743.948213ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T13:00:32.045103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.545783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:00:32.045224Z","caller":"traceutil/trace.go:171","msg":"trace[1123575] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"420.271323ms","start":"2026-02-24T13:00:31.624933Z","end":"2026-02-24T13:00:32.045204Z","steps":["trace[1123575] 'read index received' (duration: 245.566746ms)","trace[1123575] 'applied index is now lower than readState.Index' (duration: 174.703207ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:00:32.045251Z","caller":"traceutil/trace.go:171","msg":"trace[520168588] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"636.027841ms","start":"2026-02-24T13:00:31.409196Z","end":"2026-02-24T13:00:32.045224Z","steps":["trace[520168588] 'process raft request' (duration: 461.264233ms)","trace[520168588] 'compare' (duration: 174.39219ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:00:32.045323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.112735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:00:32.045361Z","caller":"traceutil/trace.go:171","msg":"trace[1424248978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"899.181297ms","start":"2026-02-24T13:00:31.146167Z","end":"2026-02-24T13:00:32.045348Z","steps":["trace[1424248978] 'agreement among raft nodes before linearized reading' (duration: 899.116596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:32.045415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:31.146017Z","time spent":"899.386231ms","remote":"127.0.0.1:34710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T13:00:32.045405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:31.409175Z","time spent":"636.132214ms","remote":"127.0.0.1:34860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5381,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:00:32.117259Z","caller":"traceutil/trace.go:171","msg":"trace[1566879311] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"487.315305ms","start":"2026-02-24T13:00:31.629924Z","end":"2026-02-24T13:00:32.117239Z","steps":["trace[1566879311] 'process raft request' (duration: 487.181352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:32.11734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.405963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:00:32.117371Z","caller":"traceutil/trace.go:171","msg":"trace[2099064257] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:975; }","duration":"482.476854ms","start":"2026-02-24T13:00:31.634886Z","end":"2026-02-24T13:00:32.117363Z","steps":["trace[2099064257] 'agreement among raft nodes before linearized reading' (duration: 482.390932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:32.11739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:31.634873Z","time spent":"482.511366ms","remote":"127.0.0.1:38570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-24T13:00:32.117424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.918923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:00:32.117569Z","caller":"traceutil/trace.go:171","msg":"trace[1788215307] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:975; }","duration":"120.067017ms","start":"2026-02-24T13:00:31.997488Z","end":"2026-02-24T13:00:32.117555Z","steps":["trace[1788215307] 'agreement among raft nodes before linearized reading' (duration: 119.836242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:32.117527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.179518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:00:32.117676Z","caller":"traceutil/trace.go:171","msg":"trace[576430190] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:975; }","duration":"467.354372ms","start":"2026-02-24T13:00:31.650315Z","end":"2026-02-24T13:00:32.117669Z","steps":["trace[576430190] 'agreement among raft nodes before linearized reading' (duration: 467.186528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:32.117456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:31.6299Z","time spent":"487.425888ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:00:32.1177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:31.650303Z","time spent":"467.389823ms","remote":"127.0.0.1:38598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-02-24T13:00:32.117248Z","caller":"traceutil/trace.go:171","msg":"trace[133056360] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"486.848155ms","start":"2026-02-24T13:00:31.630388Z","end":"2026-02-24T13:00:32.117236Z","steps":["trace[133056360] 'process raft request' (duration: 486.801794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:32.117902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:31.630365Z","time spent":"487.474779ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:00:32.312496Z","caller":"traceutil/trace.go:171","msg":"trace[2107376786] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"189.492554ms","start":"2026-02-24T13:00:32.122952Z","end":"2026-02-24T13:00:32.312445Z","steps":["trace[2107376786] 'process raft request' (duration: 189.32514ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:00:32.313197Z","caller":"traceutil/trace.go:171","msg":"trace[31587447] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"191.419636ms","start":"2026-02-24T13:00:32.121742Z","end":"2026-02-24T13:00:32.313161Z","steps":["trace[31587447] 'process raft request' (duration: 123.966095ms)","trace[31587447] 'compare' (duration: 65.64929ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:00:32.313649Z","caller":"traceutil/trace.go:171","msg":"trace[2118833103] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"191.346005ms","start":"2026-02-24T13:00:32.122283Z","end":"2026-02-24T13:00:32.313629Z","steps":["trace[2118833103] 'process raft request' (duration: 189.881201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:32.732802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.439296ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T13:00:32.732903Z","caller":"traceutil/trace.go:171","msg":"trace[585286688] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"256.082865ms","start":"2026-02-24T13:00:32.476802Z","end":"2026-02-24T13:00:32.732884Z","steps":["trace[585286688] 'read index received' (duration: 42.161µs)","trace[585286688] 'applied index is now lower than readState.Index' (duration: 256.039424ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:00:32.733046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.238359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-24T13:00:32.733082Z","caller":"traceutil/trace.go:171","msg":"trace[1907617117] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:978; }","duration":"256.305461ms","start":"2026-02-24T13:00:32.476766Z","end":"2026-02-24T13:00:32.733072Z","steps":["trace[1907617117] 'agreement among raft nodes before linearized reading' (duration: 256.166198ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:00:40.310074Z","caller":"traceutil/trace.go:171","msg":"trace[1255924945] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"109.822443ms","start":"2026-02-24T13:00:40.200236Z","end":"2026-02-24T13:00:40.310059Z","steps":["trace[1255924945] 'read index received' (duration: 109.607538ms)","trace[1255924945] 'applied index is now lower than readState.Index' (duration: 213.915µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:00:40.310162Z","caller":"traceutil/trace.go:171","msg":"trace[1054062563] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"128.319454ms","start":"2026-02-24T13:00:40.181812Z","end":"2026-02-24T13:00:40.310131Z","steps":["trace[1054062563] 'process raft request' (duration: 128.116578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:40.310315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.06957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-24T13:00:40.310344Z","caller":"traceutil/trace.go:171","msg":"trace[1724631854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1011; }","duration":"110.158792ms","start":"2026-02-24T13:00:40.200176Z","end":"2026-02-24T13:00:40.310335Z","steps":["trace[1724631854] 'agreement among raft nodes before linearized reading' (duration: 109.948628ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:00:40.570951Z","caller":"traceutil/trace.go:171","msg":"trace[1774497296] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1057; }","duration":"228.320045ms","start":"2026-02-24T13:00:40.342613Z","end":"2026-02-24T13:00:40.570933Z","steps":["trace[1774497296] 'read index received' (duration: 228.212172ms)","trace[1774497296] 'applied index is now lower than readState.Index' (duration: 106.993µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:00:40.571122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.491718ms","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-02-24T13:00:40.571142Z","caller":"traceutil/trace.go:171","msg":"trace[1057245064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1012; }","duration":"228.56015ms","start":"2026-02-24T13:00:40.342574Z","end":"2026-02-24T13:00:40.571134Z","steps":["trace[1057245064] 'agreement among raft nodes before linearized reading' (duration: 228.453338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:40.571325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.748753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-24T13:00:40.571356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.918264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T13:00:40.571599Z","caller":"traceutil/trace.go:171","msg":"trace[1965004566] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1012; }","duration":"225.06679ms","start":"2026-02-24T13:00:40.346508Z","end":"2026-02-24T13:00:40.571575Z","steps":["trace[1965004566] 'agreement among raft nodes before linearized reading' (duration: 224.643811ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:00:40.571619Z","caller":"traceutil/trace.go:171","msg":"trace[1957483761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1012; }","duration":"224.218031ms","start":"2026-02-24T13:00:40.347383Z","end":"2026-02-24T13:00:40.571601Z","steps":["trace[1957483761] 'agreement among raft nodes before linearized reading' (duration: 223.791721ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:00:40.571101Z","caller":"traceutil/trace.go:171","msg":"trace[294717158] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"256.841862ms","start":"2026-02-24T13:00:40.314204Z","end":"2026-02-24T13:00:40.571046Z","steps":["trace[294717158] 'process raft request' (duration: 256.620497ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:00:41.713461Z","caller":"traceutil/trace.go:171","msg":"trace[1021650624] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"386.184069ms","start":"2026-02-24T13:00:41.327236Z","end":"2026-02-24T13:00:41.71342Z","steps":["trace[1021650624] 'process raft request' (duration: 386.034015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:00:41.713599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:00:41.327199Z","time spent":"386.327501ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:03:36.721233Z","caller":"traceutil/trace.go:171","msg":"trace[449643000] transaction","detail":"{read_only:false; response_revision:1765; number_of_response:1; }","duration":"119.274396ms","start":"2026-02-24T13:03:36.601933Z","end":"2026-02-24T13:03:36.721207Z","steps":["trace[449643000] 'process raft request' (duration: 119.101953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:37.392414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.448622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-24T13:03:37.392514Z","caller":"traceutil/trace.go:171","msg":"trace[481126066] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1771; }","duration":"137.612975ms","start":"2026-02-24T13:03:37.254875Z","end":"2026-02-24T13:03:37.392488Z","steps":["trace[481126066] 'range keys from in-memory index tree' (duration: 137.233727ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:37.774104Z","caller":"traceutil/trace.go:171","msg":"trace[113480503] transaction","detail":"{read_only:false; response_revision:1772; number_of_response:1; }","duration":"377.816315ms","start":"2026-02-24T13:03:37.396268Z","end":"2026-02-24T13:03:37.774084Z","steps":["trace[113480503] 'process raft request' (duration: 377.694072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:37.774237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:37.396249Z","time spent":"377.916288ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:03:37.779221Z","caller":"traceutil/trace.go:171","msg":"trace[883025325] transaction","detail":"{read_only:false; response_revision:1773; number_of_response:1; }","duration":"364.073032ms","start":"2026-02-24T13:03:37.415113Z","end":"2026-02-24T13:03:37.779186Z","steps":["trace[883025325] 'process raft request' (duration: 363.838917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:37.779486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.030805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T13:03:37.779559Z","caller":"traceutil/trace.go:171","msg":"trace[109034968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1774; }","duration":"362.158908ms","start":"2026-02-24T13:03:37.417384Z","end":"2026-02-24T13:03:37.779543Z","steps":["trace[109034968] 'agreement among raft nodes before linearized reading' (duration: 361.967254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:37.77961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:37.417357Z","time spent":"362.24194ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T13:03:37.779293Z","caller":"traceutil/trace.go:171","msg":"trace[908848794] linearizableReadLoop","detail":"{readStateIndex:1856; appliedIndex:1855; }","duration":"361.820351ms","start":"2026-02-24T13:03:37.417445Z","end":"2026-02-24T13:03:37.779266Z","steps":["trace[908848794] 'read index received' (duration: 356.891549ms)","trace[908848794] 'applied index is now lower than readState.Index' (duration: 4.927432ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:37.779512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:37.41509Z","time spent":"364.282097ms","remote":"127.0.0.1:34774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":720,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T13:03:37.779489Z","caller":"traceutil/trace.go:171","msg":"trace[1343604301] transaction","detail":"{read_only:false; response_revision:1774; number_of_response:1; }","duration":"286.474658ms","start":"2026-02-24T13:03:37.492993Z","end":"2026-02-24T13:03:37.779467Z","steps":["trace[1343604301] 'process raft request' (duration: 286.080729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:37.779835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.89431ms","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-02-24T13:03:37.780146Z","caller":"traceutil/trace.go:171","msg":"trace[1972840987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1774; }","duration":"141.232659ms","start":"2026-02-24T13:03:37.638884Z","end":"2026-02-24T13:03:37.780117Z","steps":["trace[1972840987] 'agreement among raft nodes before linearized reading' (duration: 140.858819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:40.040536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.731263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:03:40.040765Z","caller":"traceutil/trace.go:171","msg":"trace[1272125295] linearizableReadLoop","detail":"{readStateIndex:1869; appliedIndex:1866; }","duration":"110.472485ms","start":"2026-02-24T13:03:39.930276Z","end":"2026-02-24T13:03:40.040749Z","steps":["trace[1272125295] 'read index received' (duration: 48.871µs)","trace[1272125295] 'applied index is now lower than readState.Index' (duration: 110.422614ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:03:40.040887Z","caller":"traceutil/trace.go:171","msg":"trace[2001631628] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"202.97383ms","start":"2026-02-24T13:03:39.837883Z","end":"2026-02-24T13:03:40.040857Z","steps":["trace[2001631628] 'process raft request' (duration: 62.829397ms)","trace[2001631628] 'compare' (duration: 139.61246ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:03:40.040895Z","caller":"traceutil/trace.go:171","msg":"trace[1084876017] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"202.819086ms","start":"2026-02-24T13:03:39.838022Z","end":"2026-02-24T13:03:40.040841Z","steps":["trace[1084876017] 'process raft request' (duration: 202.625922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:40.040993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.709551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-24T13:03:40.041034Z","caller":"traceutil/trace.go:171","msg":"trace[382871228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1786; }","duration":"110.776532ms","start":"2026-02-24T13:03:39.930246Z","end":"2026-02-24T13:03:40.041022Z","steps":["trace[382871228] 'agreement among raft nodes before linearized reading' (duration: 110.641569ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:40.041025Z","caller":"traceutil/trace.go:171","msg":"trace[879700799] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"202.442778ms","start":"2026-02-24T13:03:39.83855Z","end":"2026-02-24T13:03:40.040993Z","steps":["trace[879700799] 'process raft request' (duration: 202.164102ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:40.246492Z","caller":"traceutil/trace.go:171","msg":"trace[2047192578] linearizableReadLoop","detail":"{readStateIndex:1870; appliedIndex:1869; }","duration":"119.310488ms","start":"2026-02-24T13:03:40.127155Z","end":"2026-02-24T13:03:40.246466Z","steps":["trace[2047192578] 'read index received' (duration: 61.792943ms)","trace[2047192578] 'applied index is now lower than readState.Index' (duration: 57.516165ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:40.246651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.468222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:03:40.246697Z","caller":"traceutil/trace.go:171","msg":"trace[200410338] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1787; }","duration":"119.550953ms","start":"2026-02-24T13:03:40.127124Z","end":"2026-02-24T13:03:40.246674Z","steps":["trace[200410338] 'agreement among raft nodes before linearized reading' (duration: 119.466981ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:40.246779Z","caller":"traceutil/trace.go:171","msg":"trace[41551246] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"201.005985ms","start":"2026-02-24T13:03:40.045747Z","end":"2026-02-24T13:03:40.246752Z","steps":["trace[41551246] 'process raft request' (duration: 143.151091ms)","trace[41551246] 'compare' (duration: 57.438594ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:03:42.491515Z","caller":"traceutil/trace.go:171","msg":"trace[698171749] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"237.127165ms","start":"2026-02-24T13:03:42.254362Z","end":"2026-02-24T13:03:42.49149Z","steps":["trace[698171749] 'process raft request' (duration: 236.974732ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:44.011136Z","caller":"traceutil/trace.go:171","msg":"trace[844019835] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"148.73368ms","start":"2026-02-24T13:03:43.862368Z","end":"2026-02-24T13:03:44.011102Z","steps":["trace[844019835] 'process raft request' (duration: 148.555206ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:44.307581Z","caller":"traceutil/trace.go:171","msg":"trace[1736790697] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"168.433839ms","start":"2026-02-24T13:03:44.139122Z","end":"2026-02-24T13:03:44.307556Z","steps":["trace[1736790697] 'process raft request' (duration: 168.325417ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:44.307595Z","caller":"traceutil/trace.go:171","msg":"trace[1143469199] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"168.721657ms","start":"2026-02-24T13:03:44.13883Z","end":"2026-02-24T13:03:44.307552Z","steps":["trace[1143469199] 'process raft request' (duration: 110.187523ms)","trace[1143469199] 'compare' (duration: 58.197207ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:45.487819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.014072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:03:45.487926Z","caller":"traceutil/trace.go:171","msg":"trace[850036330] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"238.457136ms","start":"2026-02-24T13:03:45.24945Z","end":"2026-02-24T13:03:45.487907Z","steps":["trace[850036330] 'process raft request' (duration: 73.22939ms)","trace[850036330] 'compare' (duration: 164.881478ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:03:46.521898Z","caller":"traceutil/trace.go:171","msg":"trace[1161193325] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"191.70377ms","start":"2026-02-24T13:03:46.330166Z","end":"2026-02-24T13:03:46.52187Z","steps":["trace[1161193325] 'process raft request' (duration: 191.575887ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:46.521942Z","caller":"traceutil/trace.go:171","msg":"trace[891197064] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"205.631887ms","start":"2026-02-24T13:03:46.316281Z","end":"2026-02-24T13:03:46.521913Z","steps":["trace[891197064] 'process raft request' (duration: 113.268551ms)","trace[891197064] 'compare' (duration: 91.885705ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:46.831019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.321676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-02-24T13:03:46.831098Z","caller":"traceutil/trace.go:171","msg":"trace[1014357229] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1813; }","duration":"207.450409ms","start":"2026-02-24T13:03:46.623628Z","end":"2026-02-24T13:03:46.831079Z","steps":["trace[1014357229] 'range keys from in-memory index tree' (duration: 207.0609ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:47.200107Z","caller":"traceutil/trace.go:171","msg":"trace[1050662302] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1898; }","duration":"131.963538ms","start":"2026-02-24T13:03:47.068116Z","end":"2026-02-24T13:03:47.20008Z","steps":["trace[1050662302] 'read index received' (duration: 73.441114ms)","trace[1050662302] 'applied index is now lower than readState.Index' (duration: 58.521514ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:03:47.200132Z","caller":"traceutil/trace.go:171","msg":"trace[1827310575] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"149.653141ms","start":"2026-02-24T13:03:47.050448Z","end":"2026-02-24T13:03:47.200102Z","steps":["trace[1827310575] 'process raft request' (duration: 91.116696ms)","trace[1827310575] 'compare' (duration: 58.397082ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:47.200319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.190293ms","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:533"} {"level":"info","ts":"2026-02-24T13:03:47.200355Z","caller":"traceutil/trace.go:171","msg":"trace[1512103032] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1814; }","duration":"132.304826ms","start":"2026-02-24T13:03:47.068039Z","end":"2026-02-24T13:03:47.200344Z","steps":["trace[1512103032] 'agreement among raft nodes before linearized reading' (duration: 132.131933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:47.691602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.637488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:03:47.691738Z","caller":"traceutil/trace.go:171","msg":"trace[275075067] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"483.906651ms","start":"2026-02-24T13:03:47.207816Z","end":"2026-02-24T13:03:47.691723Z","steps":["trace[275075067] 'process raft request' (duration: 117.089609ms)","trace[275075067] 'compare' (duration: 366.546466ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:47.691792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:47.207798Z","time spent":"483.969933ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:04:10.437701Z","caller":"traceutil/trace.go:171","msg":"trace[934169088] linearizableReadLoop","detail":"{readStateIndex:2013; appliedIndex:2012; }","duration":"111.819679ms","start":"2026-02-24T13:04:10.325841Z","end":"2026-02-24T13:04:10.437661Z","steps":["trace[934169088] 'read index received' (duration: 111.595744ms)","trace[934169088] 'applied index is now lower than readState.Index' (duration: 222.255µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:10.437771Z","caller":"traceutil/trace.go:171","msg":"trace[948932623] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"129.030581ms","start":"2026-02-24T13:04:10.30869Z","end":"2026-02-24T13:04:10.43772Z","steps":["trace[948932623] 'process raft request' (duration: 128.757475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:10.437909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.049955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-account-controller\" ","response":"range_response_count:1 size:677"} {"level":"info","ts":"2026-02-24T13:04:10.438004Z","caller":"traceutil/trace.go:171","msg":"trace[544056660] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-account-controller; range_end:; response_count:1; response_revision:1923; }","duration":"112.195277ms","start":"2026-02-24T13:04:10.325784Z","end":"2026-02-24T13:04:10.43798Z","steps":["trace[544056660] 'agreement among raft nodes before linearized reading' (duration: 112.024343ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:10.685835Z","caller":"traceutil/trace.go:171","msg":"trace[108971612] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"159.119058ms","start":"2026-02-24T13:04:10.526681Z","end":"2026-02-24T13:04:10.6858Z","steps":["trace[108971612] 'process raft request' (duration: 149.746004ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:26.246257Z","caller":"traceutil/trace.go:171","msg":"trace[993740666] linearizableReadLoop","detail":"{readStateIndex:2080; appliedIndex:2079; }","duration":"103.91262ms","start":"2026-02-24T13:04:26.142286Z","end":"2026-02-24T13:04:26.246198Z","steps":["trace[993740666] 'read index received' (duration: 48.554198ms)","trace[993740666] 'applied index is now lower than readState.Index' (duration: 55.356281ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:26.246358Z","caller":"traceutil/trace.go:171","msg":"trace[1654771270] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"104.093563ms","start":"2026-02-24T13:04:26.142188Z","end":"2026-02-24T13:04:26.246282Z","steps":["trace[1654771270] 'process raft request' (duration: 48.805723ms)","trace[1654771270] 'compare' (duration: 54.919722ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:26.246694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.366991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-02-24T13:04:26.246746Z","caller":"traceutil/trace.go:171","msg":"trace[1367617801] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:1987; }","duration":"104.491633ms","start":"2026-02-24T13:04:26.142236Z","end":"2026-02-24T13:04:26.246727Z","steps":["trace[1367617801] 'agreement among raft nodes before linearized reading' (duration: 104.230828ms)"],"step_count":1}