{"level":"warn","ts":"2026-02-23T14:51:12.801079Z","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-23T14:51:12.801172Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.131: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.204.45.131:2380","--initial-cluster=instance=https://199.204.45.131:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.131:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.131: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-23T14:51:12.8016Z","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-23T14:51:12.801631Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.131:2380"]} {"level":"info","ts":"2026-02-23T14:51:12.801694Z","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-23T14:51:12.803011Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.131:2379"]} {"level":"info","ts":"2026-02-23T14:51:12.803167Z","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.204.45.131:2380"],"listen-peer-urls":["https://199.204.45.131:2380"],"advertise-client-urls":["https://199.204.45.131:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.131:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.131: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-23T14:51:12.80651Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.079517ms"} {"level":"info","ts":"2026-02-23T14:51:12.812994Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2fdf33955ed9ec6c","cluster-id":"e3dd6b4ed8cb907a"} {"level":"info","ts":"2026-02-23T14:51:12.813125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c switched to configuration voters=()"} {"level":"info","ts":"2026-02-23T14:51:12.813175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c became follower at term 0"} {"level":"info","ts":"2026-02-23T14:51:12.813236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2fdf33955ed9ec6c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-23T14:51:12.813244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c became follower at term 1"} {"level":"info","ts":"2026-02-23T14:51:12.813367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c switched to configuration voters=(3449532556223573100)"} {"level":"warn","ts":"2026-02-23T14:51:12.817712Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-23T14:51:12.818975Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-23T14:51:12.820033Z","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-23T14:51:12.821959Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2fdf33955ed9ec6c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-23T14:51:12.825917Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2fdf33955ed9ec6c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-23T14:51:12.826105Z","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-23T14:51:12.826179Z","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-23T14:51:12.826192Z","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-23T14:51:12.832904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c switched to configuration voters=(3449532556223573100)"} {"level":"info","ts":"2026-02-23T14:51:12.833067Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e3dd6b4ed8cb907a","local-member-id":"2fdf33955ed9ec6c","added-peer-id":"2fdf33955ed9ec6c","added-peer-peer-urls":["https://199.204.45.131:2380"]} {"level":"info","ts":"2026-02-23T14:51:12.833384Z","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-23T14:51:12.833547Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.131:2380"} {"level":"info","ts":"2026-02-23T14:51:12.833605Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.131:2380"} {"level":"info","ts":"2026-02-23T14:51:12.833687Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2fdf33955ed9ec6c","initial-advertise-peer-urls":["https://199.204.45.131:2380"],"listen-peer-urls":["https://199.204.45.131:2380"],"advertise-client-urls":["https://199.204.45.131:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.131:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-23T14:51:12.833728Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-23T14:51:13.115105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c is starting a new election at term 1"} {"level":"info","ts":"2026-02-23T14:51:13.115156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-23T14:51:13.115179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c received MsgPreVoteResp from 2fdf33955ed9ec6c at term 1"} {"level":"info","ts":"2026-02-23T14:51:13.115205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c became candidate at term 2"} {"level":"info","ts":"2026-02-23T14:51:13.115223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c received MsgVoteResp from 2fdf33955ed9ec6c at term 2"} {"level":"info","ts":"2026-02-23T14:51:13.115244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c became leader at term 2"} {"level":"info","ts":"2026-02-23T14:51:13.115262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2fdf33955ed9ec6c elected leader 2fdf33955ed9ec6c at term 2"} {"level":"info","ts":"2026-02-23T14:51:13.11744Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T14:51:13.119765Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-23T14:51:13.11976Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2fdf33955ed9ec6c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.131:2379]}","request-path":"/0/members/2fdf33955ed9ec6c/attributes","cluster-id":"e3dd6b4ed8cb907a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-23T14:51:13.119924Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-23T14:51:13.120646Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-23T14:51:13.12069Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-23T14:51:13.122129Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e3dd6b4ed8cb907a","local-member-id":"2fdf33955ed9ec6c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T14:51:13.122228Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T14:51:13.12227Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T14:51:13.12277Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-23T14:51:13.124765Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.131:2379"} {"level":"warn","ts":"2026-02-23T14:51:17.799904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.419422ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-23T14:51:18.061423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.955171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T14:51:18.061538Z","caller":"traceutil/trace.go:171","msg":"trace[1557264531] linearizableReadLoop","detail":"{readStateIndex:226; appliedIndex:224; }","duration":"549.167869ms","start":"2026-02-23T14:51:17.512354Z","end":"2026-02-23T14:51:18.061522Z","steps":["trace[1557264531] 'read index received' (duration: 150.042675ms)","trace[1557264531] 'applied index is now lower than readState.Index' (duration: 399.124034ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T14:51:18.062335Z","caller":"traceutil/trace.go:171","msg":"trace[1597190435] transaction","detail":"{read_only:false; response_revision:220; number_of_response:1; }","duration":"555.21401ms","start":"2026-02-23T14:51:17.507107Z","end":"2026-02-23T14:51:18.062321Z","steps":["trace[1597190435] 'process raft request' (duration: 402.800102ms)","trace[1597190435] 'compare' (duration: 150.812918ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T14:51:18.062437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:51:17.507091Z","time spent":"555.297602ms","remote":"127.0.0.1:42902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T14:51:18.062477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.066135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T14:51:18.062578Z","caller":"traceutil/trace.go:171","msg":"trace[1356246626] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:0; response_revision:220; }","duration":"550.230078ms","start":"2026-02-23T14:51:17.512326Z","end":"2026-02-23T14:51:18.062556Z","steps":["trace[1356246626] 'agreement among raft nodes before linearized reading' (duration: 550.039725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:51:18.062621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:51:17.512316Z","time spent":"550.295559ms","remote":"127.0.0.1:42840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-02-23T14:51:18.154592Z","caller":"traceutil/trace.go:171","msg":"trace[1700703055] transaction","detail":"{read_only:false; number_of_response:0; response_revision:220; }","duration":"414.203132ms","start":"2026-02-23T14:51:17.740363Z","end":"2026-02-23T14:51:18.154566Z","steps":["trace[1700703055] 'process raft request' (duration: 414.009568ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:51:18.154645Z","caller":"traceutil/trace.go:171","msg":"trace[378731757] transaction","detail":"{read_only:false; number_of_response:0; response_revision:220; }","duration":"400.634752ms","start":"2026-02-23T14:51:17.753984Z","end":"2026-02-23T14:51:18.154618Z","steps":["trace[378731757] 'process raft request' (duration: 400.469229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:51:18.154773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:51:17.753969Z","time spent":"400.736143ms","remote":"127.0.0.1:42826","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-23T14:51:18.154805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:51:17.740341Z","time spent":"414.321104ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-23T14:51:18.154859Z","caller":"traceutil/trace.go:171","msg":"trace[1594920776] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"400.750414ms","start":"2026-02-23T14:51:17.754062Z","end":"2026-02-23T14:51:18.154812Z","steps":["trace[1594920776] 'process raft request' (duration: 400.432098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:51:18.154965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:51:17.754054Z","time spent":"400.866566ms","remote":"127.0.0.1:42826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5476,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-23T14:51:18.155067Z","caller":"traceutil/trace.go:171","msg":"trace[150074517] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"400.734044ms","start":"2026-02-23T14:51:17.754316Z","end":"2026-02-23T14:51:18.15505Z","steps":["trace[150074517] 'process raft request' (duration: 400.56441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:51:18.155144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:51:17.754306Z","time spent":"400.802585ms","remote":"127.0.0.1:42826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3943,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-23T14:51:18.155315Z","caller":"traceutil/trace.go:171","msg":"trace[410032663] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"400.896666ms","start":"2026-02-23T14:51:17.754398Z","end":"2026-02-23T14:51:18.155294Z","steps":["trace[410032663] 'process raft request' (duration: 400.59314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:51:18.155398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:51:17.754381Z","time spent":"400.977598ms","remote":"127.0.0.1:42826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5860,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-23T14:51:18.155579Z","caller":"traceutil/trace.go:171","msg":"trace[435451246] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"400.564501ms","start":"2026-02-23T14:51:17.754985Z","end":"2026-02-23T14:51:18.155549Z","steps":["trace[435451246] 'process raft request' (duration: 400.035481ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:51:18.155607Z","caller":"traceutil/trace.go:171","msg":"trace[270785649] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"354.333209ms","start":"2026-02-23T14:51:17.801261Z","end":"2026-02-23T14:51:18.155594Z","steps":["trace[270785649] 'process raft request' (duration: 354.259077ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:51:18.155623Z","caller":"traceutil/trace.go:171","msg":"trace[1596451399] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"354.38998ms","start":"2026-02-23T14:51:17.801173Z","end":"2026-02-23T14:51:18.155563Z","steps":["trace[1596451399] 'process raft request' (duration: 354.257368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:51:18.15567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:51:17.75498Z","time spent":"400.650892ms","remote":"127.0.0.1:42826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2883,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-23T14:51:18.15568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:51:17.801238Z","time spent":"354.41092ms","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-23T14:51:18.155697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.314706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-23T14:51:18.155744Z","caller":"traceutil/trace.go:171","msg":"trace[360634676] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:226; }","duration":"514.398798ms","start":"2026-02-23T14:51:17.641335Z","end":"2026-02-23T14:51:18.155734Z","steps":["trace[360634676] 'agreement among raft nodes before linearized reading' (duration: 514.258806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:51:18.155754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:51:17.801163Z","time spent":"354.528032ms","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-23T14:51:18.155794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.735628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T14:51:18.155814Z","caller":"traceutil/trace.go:171","msg":"trace[1169373555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:226; }","duration":"416.785849ms","start":"2026-02-23T14:51:17.739025Z","end":"2026-02-23T14:51:18.155811Z","steps":["trace[1169373555] 'agreement among raft nodes before linearized reading' (duration: 416.756969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:51:18.155827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:51:17.739007Z","time spent":"416.81788ms","remote":"127.0.0.1:42902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-23T14:51:18.155769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:51:17.641321Z","time spent":"514.442789ms","remote":"127.0.0.1:43042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-23T14:51:18.155889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.122883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T14:51:18.155937Z","caller":"traceutil/trace.go:171","msg":"trace[14800684] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:226; }","duration":"514.202055ms","start":"2026-02-23T14:51:17.641719Z","end":"2026-02-23T14:51:18.155921Z","steps":["trace[14800684] 'agreement among raft nodes before linearized reading' (duration: 514.107613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:51:18.155966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:51:17.641701Z","time spent":"514.258595ms","remote":"127.0.0.1:42902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-02-23T14:51:18.156281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.537798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T14:51:18.156321Z","caller":"traceutil/trace.go:171","msg":"trace[687229024] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:226; }","duration":"598.610789ms","start":"2026-02-23T14:51:17.557691Z","end":"2026-02-23T14:51:18.156301Z","steps":["trace[687229024] 'agreement among raft nodes before linearized reading' (duration: 598.567159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:51:18.156347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:51:17.557666Z","time spent":"598.674011ms","remote":"127.0.0.1:43102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"info","ts":"2026-02-23T14:51:47.876264Z","caller":"traceutil/trace.go:171","msg":"trace[1268152418] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"128.724522ms","start":"2026-02-23T14:51:47.747507Z","end":"2026-02-23T14:51:47.876231Z","steps":["trace[1268152418] 'process raft request' (duration: 128.571269ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:51:48.496161Z","caller":"traceutil/trace.go:171","msg":"trace[1715798689] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:502; }","duration":"172.532806ms","start":"2026-02-23T14:51:48.323594Z","end":"2026-02-23T14:51:48.496127Z","steps":["trace[1715798689] 'read index received' (duration: 172.367853ms)","trace[1715798689] 'applied index is now lower than readState.Index' (duration: 164.083µs)"],"step_count":2} {"level":"warn","ts":"2026-02-23T14:51:48.496341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.728931ms","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-23T14:51:48.496383Z","caller":"traceutil/trace.go:171","msg":"trace[883692259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:485; }","duration":"172.830902ms","start":"2026-02-23T14:51:48.32354Z","end":"2026-02-23T14:51:48.496371Z","steps":["trace[883692259] 'agreement among raft nodes before linearized reading' (duration: 172.691409ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:51:48.496479Z","caller":"traceutil/trace.go:171","msg":"trace[779079563] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"301.908351ms","start":"2026-02-23T14:51:48.194537Z","end":"2026-02-23T14:51:48.496446Z","steps":["trace[779079563] 'process raft request' (duration: 301.425542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:51:48.496661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:51:48.194519Z","time spent":"302.035944ms","remote":"127.0.0.1:42902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T14:51:48.71415Z","caller":"traceutil/trace.go:171","msg":"trace[2012527362] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"213.303482ms","start":"2026-02-23T14:51:48.500814Z","end":"2026-02-23T14:51:48.714118Z","steps":["trace[2012527362] 'process raft request' (duration: 136.566072ms)","trace[2012527362] 'compare' (duration: 76.613987ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T14:51:48.715852Z","caller":"traceutil/trace.go:171","msg":"trace[1485664328] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"212.68469ms","start":"2026-02-23T14:51:48.50314Z","end":"2026-02-23T14:51:48.715825Z","steps":["trace[1485664328] 'process raft request' (duration: 212.518377ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:52:08.995191Z","caller":"traceutil/trace.go:171","msg":"trace[534793800] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"254.428706ms","start":"2026-02-23T14:52:08.740738Z","end":"2026-02-23T14:52:08.995167Z","steps":["trace[534793800] 'read index received' (duration: 254.244193ms)","trace[534793800] 'applied index is now lower than readState.Index' (duration: 183.663µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T14:52:08.995316Z","caller":"traceutil/trace.go:171","msg":"trace[1211417791] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"395.964615ms","start":"2026-02-23T14:52:08.599342Z","end":"2026-02-23T14:52:08.995307Z","steps":["trace[1211417791] 'process raft request' (duration: 395.672809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:52:08.995417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:52:08.599323Z","time spent":"396.022257ms","remote":"127.0.0.1:42902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T14:52:08.995476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.283965ms","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-23T14:52:08.99553Z","caller":"traceutil/trace.go:171","msg":"trace[996637750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:675; }","duration":"196.358656ms","start":"2026-02-23T14:52:08.799157Z","end":"2026-02-23T14:52:08.995516Z","steps":["trace[996637750] 'agreement among raft nodes before linearized reading' (duration: 196.226753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:52:08.995635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.897955ms","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-23T14:52:08.995651Z","caller":"traceutil/trace.go:171","msg":"trace[1745306680] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:675; }","duration":"254.947636ms","start":"2026-02-23T14:52:08.740698Z","end":"2026-02-23T14:52:08.995646Z","steps":["trace[1745306680] 'agreement among raft nodes before linearized reading' (duration: 254.901145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:52:08.995762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.207977ms","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-23T14:52:08.995832Z","caller":"traceutil/trace.go:171","msg":"trace[901169303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:675; }","duration":"191.292109ms","start":"2026-02-23T14:52:08.804525Z","end":"2026-02-23T14:52:08.995817Z","steps":["trace[901169303] 'agreement among raft nodes before linearized reading' (duration: 191.146626ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:52:09.113098Z","caller":"traceutil/trace.go:171","msg":"trace[1406008351] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"114.09132ms","start":"2026-02-23T14:52:08.998986Z","end":"2026-02-23T14:52:09.113078Z","steps":["trace[1406008351] 'process raft request' (duration: 113.963457ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:52:09.137369Z","caller":"traceutil/trace.go:171","msg":"trace[1907224163] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"137.836928ms","start":"2026-02-23T14:52:08.999518Z","end":"2026-02-23T14:52:09.137355Z","steps":["trace[1907224163] 'process raft request' (duration: 137.505401ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:52:09.137523Z","caller":"traceutil/trace.go:171","msg":"trace[1423080029] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"137.949079ms","start":"2026-02-23T14:52:08.999551Z","end":"2026-02-23T14:52:09.1375Z","steps":["trace[1423080029] 'process raft request' (duration: 137.612093ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:52:31.470417Z","caller":"traceutil/trace.go:171","msg":"trace[1444022130] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"213.623779ms","start":"2026-02-23T14:52:31.256741Z","end":"2026-02-23T14:52:31.470365Z","steps":["trace[1444022130] 'process raft request' (duration: 181.526573ms)","trace[1444022130] 'compare' (duration: 31.920473ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T14:52:31.470478Z","caller":"traceutil/trace.go:171","msg":"trace[254077263] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"149.55429ms","start":"2026-02-23T14:52:31.320894Z","end":"2026-02-23T14:52:31.470448Z","steps":["trace[254077263] 'read index received' (duration: 117.494685ms)","trace[254077263] 'applied index is now lower than readState.Index' (duration: 32.057365ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T14:52:31.661433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.116558ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-23T14:52:31.661511Z","caller":"traceutil/trace.go:171","msg":"trace[2071265760] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"190.945625ms","start":"2026-02-23T14:52:31.470552Z","end":"2026-02-23T14:52:31.661498Z","steps":["trace[2071265760] 'read index received' (duration: 55.231µs)","trace[2071265760] 'applied index is now lower than readState.Index' (duration: 190.889544ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T14:52:31.661551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.636628ms","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-23T14:52:31.66161Z","caller":"traceutil/trace.go:171","msg":"trace[910085146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:744; }","duration":"340.739839ms","start":"2026-02-23T14:52:31.320856Z","end":"2026-02-23T14:52:31.661596Z","steps":["trace[910085146] 'agreement among raft nodes before linearized reading' (duration: 149.736233ms)","trace[910085146] 'range keys from in-memory index tree' (duration: 190.804223ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T14:52:31.661624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.125353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-23T14:52:31.661625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.263157ms","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-23T14:52:31.661636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:52:31.320846Z","time spent":"340.78604ms","remote":"127.0.0.1:42902","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-23T14:52:31.661656Z","caller":"traceutil/trace.go:171","msg":"trace[114819357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:744; }","duration":"337.195844ms","start":"2026-02-23T14:52:31.324447Z","end":"2026-02-23T14:52:31.661642Z","steps":["trace[114819357] 'agreement among raft nodes before linearized reading' (duration: 337.089632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:52:31.661683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:52:31.324427Z","time spent":"337.249586ms","remote":"127.0.0.1:42902","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-23T14:52:31.661656Z","caller":"traceutil/trace.go:171","msg":"trace[1822820825] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:744; }","duration":"336.322518ms","start":"2026-02-23T14:52:31.325326Z","end":"2026-02-23T14:52:31.661648Z","steps":["trace[1822820825] 'agreement among raft nodes before linearized reading' (duration: 336.232836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:52:31.661744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:52:31.325314Z","time spent":"336.42263ms","remote":"127.0.0.1:42902","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":"info","ts":"2026-02-23T14:52:43.9037Z","caller":"traceutil/trace.go:171","msg":"trace[1895997333] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:861; }","duration":"123.071868ms","start":"2026-02-23T14:52:43.780602Z","end":"2026-02-23T14:52:43.903674Z","steps":["trace[1895997333] 'read index received' (duration: 122.889655ms)","trace[1895997333] 'applied index is now lower than readState.Index' (duration: 181.063µs)"],"step_count":2} {"level":"warn","ts":"2026-02-23T14:52:43.903887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.276693ms","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-23T14:52:43.903932Z","caller":"traceutil/trace.go:171","msg":"trace[712981707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:829; }","duration":"123.395785ms","start":"2026-02-23T14:52:43.780529Z","end":"2026-02-23T14:52:43.903925Z","steps":["trace[712981707] 'agreement among raft nodes before linearized reading' (duration: 123.259872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:52:43.904031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.357484ms","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-23T14:52:43.904093Z","caller":"traceutil/trace.go:171","msg":"trace[1627900706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:829; }","duration":"123.473436ms","start":"2026-02-23T14:52:43.780604Z","end":"2026-02-23T14:52:43.904078Z","steps":["trace[1627900706] 'agreement among raft nodes before linearized reading' (duration: 123.280722ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:52:43.903764Z","caller":"traceutil/trace.go:171","msg":"trace[917388504] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"140.952359ms","start":"2026-02-23T14:52:43.76279Z","end":"2026-02-23T14:52:43.903742Z","steps":["trace[917388504] 'process raft request' (duration: 140.726754ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:52:45.549543Z","caller":"traceutil/trace.go:171","msg":"trace[768374325] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"270.763983ms","start":"2026-02-23T14:52:45.278741Z","end":"2026-02-23T14:52:45.549505Z","steps":["trace[768374325] 'process raft request' (duration: 270.520469ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:52:45.736559Z","caller":"traceutil/trace.go:171","msg":"trace[226427194] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"182.538416ms","start":"2026-02-23T14:52:45.553979Z","end":"2026-02-23T14:52:45.736517Z","steps":["trace[226427194] 'process raft request' (duration: 182.149189ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:53:04.009575Z","caller":"traceutil/trace.go:171","msg":"trace[1885925034] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"169.848792ms","start":"2026-02-23T14:53:03.839705Z","end":"2026-02-23T14:53:04.009554Z","steps":["trace[1885925034] 'read index received' (duration: 169.688569ms)","trace[1885925034] 'applied index is now lower than readState.Index' (duration: 159.293µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T14:53:04.010086Z","caller":"traceutil/trace.go:171","msg":"trace[1174264930] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"203.473682ms","start":"2026-02-23T14:53:03.806606Z","end":"2026-02-23T14:53:04.010079Z","steps":["trace[1174264930] 'process raft request' (duration: 202.80892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:53:04.010457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.703718ms","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-23T14:53:04.014036Z","caller":"traceutil/trace.go:171","msg":"trace[1201374222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:898; }","duration":"174.353795ms","start":"2026-02-23T14:53:03.839657Z","end":"2026-02-23T14:53:04.014011Z","steps":["trace[1201374222] 'agreement among raft nodes before linearized reading' (duration: 170.589995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:53:04.206005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.115992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T14:53:04.206297Z","caller":"traceutil/trace.go:171","msg":"trace[242297550] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:935; }","duration":"180.453308ms","start":"2026-02-23T14:53:04.025825Z","end":"2026-02-23T14:53:04.206279Z","steps":["trace[242297550] 'read index received' (duration: 6.968929ms)","trace[242297550] 'applied index is now lower than readState.Index' (duration: 173.483379ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T14:53:04.20657Z","caller":"traceutil/trace.go:171","msg":"trace[966185529] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"186.167753ms","start":"2026-02-23T14:53:04.020381Z","end":"2026-02-23T14:53:04.206549Z","steps":["trace[966185529] 'process raft request' (duration: 185.853737ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:53:04.206735Z","caller":"traceutil/trace.go:171","msg":"trace[1690714896] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"189.416284ms","start":"2026-02-23T14:53:04.017278Z","end":"2026-02-23T14:53:04.206695Z","steps":["trace[1690714896] 'process raft request' (duration: 15.519337ms)","trace[1690714896] 'compare' (duration: 172.99531ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T14:53:04.206763Z","caller":"traceutil/trace.go:171","msg":"trace[1515542976] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"187.714362ms","start":"2026-02-23T14:53:04.01898Z","end":"2026-02-23T14:53:04.206694Z","steps":["trace[1515542976] 'process raft request' (duration: 187.199412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:53:04.206822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.970217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-kdz7w\" ","response":"range_response_count:1 size:10928"} {"level":"info","ts":"2026-02-23T14:53:04.207057Z","caller":"traceutil/trace.go:171","msg":"trace[568517091] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-kdz7w; range_end:; response_count:1; response_revision:901; }","duration":"181.235933ms","start":"2026-02-23T14:53:04.025801Z","end":"2026-02-23T14:53:04.207037Z","steps":["trace[568517091] 'agreement among raft nodes before linearized reading' (duration: 180.813085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:53:04.20756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.155257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-23T14:53:04.207651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.815964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T14:53:04.207696Z","caller":"traceutil/trace.go:171","msg":"trace[286460556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"110.869935ms","start":"2026-02-23T14:53:04.096813Z","end":"2026-02-23T14:53:04.207682Z","steps":["trace[286460556] 'agreement among raft nodes before linearized reading' (duration: 110.818924ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:53:04.207655Z","caller":"traceutil/trace.go:171","msg":"trace[876943951] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:901; }","duration":"164.31212ms","start":"2026-02-23T14:53:04.043324Z","end":"2026-02-23T14:53:04.207636Z","steps":["trace[876943951] 'agreement among raft nodes before linearized reading' (duration: 164.059815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:53:04.207602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.887455ms","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-23T14:53:04.208017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.947509ms","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-23T14:53:04.208059Z","caller":"traceutil/trace.go:171","msg":"trace[1728084223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:901; }","duration":"161.00104ms","start":"2026-02-23T14:53:04.047047Z","end":"2026-02-23T14:53:04.208048Z","steps":["trace[1728084223] 'agreement among raft nodes before linearized reading' (duration: 160.904908ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:53:04.20803Z","caller":"traceutil/trace.go:171","msg":"trace[1843973002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:901; }","duration":"143.375764ms","start":"2026-02-23T14:53:04.064636Z","end":"2026-02-23T14:53:04.208011Z","steps":["trace[1843973002] 'agreement among raft nodes before linearized reading' (duration: 142.791883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:53:04.63072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.79243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T14:53:04.630958Z","caller":"traceutil/trace.go:171","msg":"trace[1280611524] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:938; }","duration":"282.952548ms","start":"2026-02-23T14:53:04.347989Z","end":"2026-02-23T14:53:04.630941Z","steps":["trace[1280611524] 'read index received' (duration: 10.115877ms)","trace[1280611524] 'applied index is now lower than readState.Index' (duration: 272.835561ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T14:53:04.630971Z","caller":"traceutil/trace.go:171","msg":"trace[348673107] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"414.380922ms","start":"2026-02-23T14:53:04.21657Z","end":"2026-02-23T14:53:04.630951Z","steps":["trace[348673107] 'process raft request' (duration: 414.250339ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:53:04.630972Z","caller":"traceutil/trace.go:171","msg":"trace[953559289] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"415.094855ms","start":"2026-02-23T14:53:04.215791Z","end":"2026-02-23T14:53:04.630886Z","steps":["trace[953559289] 'process raft request' (duration: 142.04184ms)","trace[953559289] 'compare' (duration: 272.674498ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T14:53:04.63107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:53:04.21656Z","time spent":"414.453534ms","remote":"127.0.0.1:42902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T14:53:04.631072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.087451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T14:53:04.631105Z","caller":"traceutil/trace.go:171","msg":"trace[1405728066] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:904; }","duration":"283.150462ms","start":"2026-02-23T14:53:04.347945Z","end":"2026-02-23T14:53:04.631095Z","steps":["trace[1405728066] 'agreement among raft nodes before linearized reading' (duration: 283.05406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:53:04.631165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:53:04.215776Z","time spent":"415.285839ms","remote":"127.0.0.1:42902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T14:53:04.631274Z","caller":"traceutil/trace.go:171","msg":"trace[651023250] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"410.980529ms","start":"2026-02-23T14:53:04.220288Z","end":"2026-02-23T14:53:04.631269Z","steps":["trace[651023250] 'process raft request' (duration: 410.604662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T14:53:04.63131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T14:53:04.220277Z","time spent":"411.01532ms","remote":"127.0.0.1:42826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11176,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T14:53:06.371253Z","caller":"traceutil/trace.go:171","msg":"trace[1700150899] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"158.164227ms","start":"2026-02-23T14:53:06.213065Z","end":"2026-02-23T14:53:06.371229Z","steps":["trace[1700150899] 'read index received' (duration: 119.923392ms)","trace[1700150899] 'applied index is now lower than readState.Index' (duration: 38.239905ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T14:53:06.371338Z","caller":"traceutil/trace.go:171","msg":"trace[553452351] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"202.627356ms","start":"2026-02-23T14:53:06.168646Z","end":"2026-02-23T14:53:06.371273Z","steps":["trace[553452351] 'process raft request' (duration: 164.243368ms)","trace[553452351] 'compare' (duration: 38.212695ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T14:53:06.371542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.404292ms","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-23T14:53:06.371565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.473383ms","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-23T14:53:06.371601Z","caller":"traceutil/trace.go:171","msg":"trace[226129734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:910; }","duration":"157.542986ms","start":"2026-02-23T14:53:06.214043Z","end":"2026-02-23T14:53:06.371586Z","steps":["trace[226129734] 'agreement among raft nodes before linearized reading' (duration: 157.348232ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:53:06.371655Z","caller":"traceutil/trace.go:171","msg":"trace[1160020280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:910; }","duration":"158.622576ms","start":"2026-02-23T14:53:06.213012Z","end":"2026-02-23T14:53:06.371634Z","steps":["trace[1160020280] 'agreement among raft nodes before linearized reading' (duration: 158.31171ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T14:53:13.859533Z","caller":"traceutil/trace.go:171","msg":"trace[1367151885] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"111.020797ms","start":"2026-02-23T14:53:13.748489Z","end":"2026-02-23T14:53:13.85951Z","steps":["trace[1367151885] 'process raft request' (duration: 110.863274ms)"],"step_count":1}