{"level":"warn","ts":"2026-02-19T22:51:10.884098Z","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-19T22:51:10.884181Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70: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-19T22:51:10.884247Z","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-19T22:51:10.88426Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-19T22:51:10.884276Z","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-19T22:51:10.884912Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-02-19T22:51:10.884988Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70: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-19T22:51:10.889595Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.403278ms"} {"level":"info","ts":"2026-02-19T22:51:10.899476Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-02-19T22:51:10.899601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T22:51:10.899689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-02-19T22:51:10.899779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T22:51:10.899802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-02-19T22:51:10.899909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-02-19T22:51:10.90528Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T22:51:10.907714Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T22:51:10.908806Z","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-19T22:51:10.910326Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T22:51:10.91055Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T22:51:10.910634Z","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-19T22:51:10.91072Z","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-19T22:51:10.910733Z","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-19T22:51:10.911566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-02-19T22:51:10.912204Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-19T22:51:10.917456Z","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-19T22:51:10.917576Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-19T22:51:10.917604Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-19T22:51:10.917669Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T22:51:10.917706Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T22:51:11.100789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T22:51:11.100853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T22:51:11.100895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-02-19T22:51:11.10092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-02-19T22:51:11.100932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-19T22:51:11.100963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-02-19T22:51:11.100978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-19T22:51:11.101727Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T22:51:11.103203Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T22:51:11.103293Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T22:51:11.103318Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T22:51:11.10335Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T22:51:11.103417Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T22:51:11.103381Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T22:51:11.103548Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T22:51:11.103591Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T22:51:11.106469Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-19T22:51:11.108129Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-02-19T22:51:37.499386Z","caller":"traceutil/trace.go:171","msg":"trace[1796139028] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:447; }","duration":"371.365023ms","start":"2026-02-19T22:51:37.127993Z","end":"2026-02-19T22:51:37.499358Z","steps":["trace[1796139028] 'read index received' (duration: 371.23645ms)","trace[1796139028] 'applied index is now lower than readState.Index' (duration: 127.133µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T22:51:37.499442Z","caller":"traceutil/trace.go:171","msg":"trace[1391469850] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"438.532061ms","start":"2026-02-19T22:51:37.060873Z","end":"2026-02-19T22:51:37.499405Z","steps":["trace[1391469850] 'process raft request' (duration: 438.326286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:51:37.499691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.643069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T22:51:37.49971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.709522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"info","ts":"2026-02-19T22:51:37.499794Z","caller":"traceutil/trace.go:171","msg":"trace[2082334965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"307.797063ms","start":"2026-02-19T22:51:37.191978Z","end":"2026-02-19T22:51:37.499775Z","steps":["trace[2082334965] 'agreement among raft nodes before linearized reading' (duration: 307.590498ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:51:37.499834Z","caller":"traceutil/trace.go:171","msg":"trace[1092532351] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:430; }","duration":"371.858635ms","start":"2026-02-19T22:51:37.127954Z","end":"2026-02-19T22:51:37.499812Z","steps":["trace[1092532351] 'agreement among raft nodes before linearized reading' (duration: 371.565838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:51:37.499895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:51:37.127939Z","time spent":"371.942078ms","remote":"127.0.0.1:38818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12695,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-02-19T22:51:37.499852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:51:37.191959Z","time spent":"307.878125ms","remote":"127.0.0.1:38754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T22:51:37.500166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:51:37.060857Z","time spent":"438.682065ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T22:51:38.581088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.243436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T22:51:38.581181Z","caller":"traceutil/trace.go:171","msg":"trace[722563602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:432; }","duration":"387.39695ms","start":"2026-02-19T22:51:38.193763Z","end":"2026-02-19T22:51:38.58116Z","steps":["trace[722563602] 'range keys from in-memory index tree' (duration: 387.116933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:51:38.581223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:51:38.193742Z","time spent":"387.473481ms","remote":"127.0.0.1:38754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T22:51:38.581222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.339455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T22:51:38.581331Z","caller":"traceutil/trace.go:171","msg":"trace[1263919116] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:432; }","duration":"457.488398ms","start":"2026-02-19T22:51:38.123819Z","end":"2026-02-19T22:51:38.581307Z","steps":["trace[1263919116] 'range keys from in-memory index tree' (duration: 457.262402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:51:38.581387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:51:38.123802Z","time spent":"457.5717ms","remote":"127.0.0.1:38910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"info","ts":"2026-02-19T22:51:41.271001Z","caller":"traceutil/trace.go:171","msg":"trace[508639413] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"140.466454ms","start":"2026-02-19T22:51:41.130504Z","end":"2026-02-19T22:51:41.27097Z","steps":["trace[508639413] 'process raft request' (duration: 140.268718ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:51:47.938565Z","caller":"traceutil/trace.go:171","msg":"trace[18343938] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"399.323978ms","start":"2026-02-19T22:51:47.539219Z","end":"2026-02-19T22:51:47.938543Z","steps":["trace[18343938] 'read index received' (duration: 399.021911ms)","trace[18343938] 'applied index is now lower than readState.Index' (duration: 301.157µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T22:51:47.938626Z","caller":"traceutil/trace.go:171","msg":"trace[529944271] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"578.735768ms","start":"2026-02-19T22:51:47.359859Z","end":"2026-02-19T22:51:47.938595Z","steps":["trace[529944271] 'process raft request' (duration: 578.547004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:51:47.938831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.576204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-19T22:51:47.938832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:51:47.359842Z","time spent":"578.897512ms","remote":"127.0.0.1:38900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T22:51:47.938877Z","caller":"traceutil/trace.go:171","msg":"trace[581611701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:491; }","duration":"399.695257ms","start":"2026-02-19T22:51:47.539169Z","end":"2026-02-19T22:51:47.938864Z","steps":["trace[581611701] 'agreement among raft nodes before linearized reading' (duration: 399.491602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:51:47.93888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.653204ms","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-19T22:51:47.938935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:51:47.539145Z","time spent":"399.781178ms","remote":"127.0.0.1:38966","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-19T22:51:47.938984Z","caller":"traceutil/trace.go:171","msg":"trace[1283330450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:491; }","duration":"372.803498ms","start":"2026-02-19T22:51:47.566158Z","end":"2026-02-19T22:51:47.938961Z","steps":["trace[1283330450] 'agreement among raft nodes before linearized reading' (duration: 372.602663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:51:47.939041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:51:47.566145Z","time spent":"372.88629ms","remote":"127.0.0.1:38966","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-19T22:51:48.483144Z","caller":"traceutil/trace.go:171","msg":"trace[359668324] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"539.709719ms","start":"2026-02-19T22:51:47.943342Z","end":"2026-02-19T22:51:48.483052Z","steps":["trace[359668324] 'process raft request' (duration: 539.518864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:51:48.483584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:51:47.943319Z","time spent":"540.024917ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T22:51:48.511277Z","caller":"traceutil/trace.go:171","msg":"trace[1113386567] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:511; }","duration":"408.57465ms","start":"2026-02-19T22:51:48.102679Z","end":"2026-02-19T22:51:48.511254Z","steps":["trace[1113386567] 'read index received' (duration: 380.215277ms)","trace[1113386567] 'applied index is now lower than readState.Index' (duration: 28.358543ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T22:51:48.511417Z","caller":"traceutil/trace.go:171","msg":"trace[1695443697] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"567.99937ms","start":"2026-02-19T22:51:47.943393Z","end":"2026-02-19T22:51:48.511393Z","steps":["trace[1695443697] 'process raft request' (duration: 567.747404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:51:48.511507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.813646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-19T22:51:48.511542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:51:47.943365Z","time spent":"568.106853ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T22:51:48.511579Z","caller":"traceutil/trace.go:171","msg":"trace[872306977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:493; }","duration":"408.937559ms","start":"2026-02-19T22:51:48.10263Z","end":"2026-02-19T22:51:48.511568Z","steps":["trace[872306977] 'agreement among raft nodes before linearized reading' (duration: 408.713174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:51:48.511658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:51:48.102615Z","time spent":"408.99392ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-19T22:51:49.668565Z","caller":"traceutil/trace.go:171","msg":"trace[908321828] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"318.819899ms","start":"2026-02-19T22:51:49.349721Z","end":"2026-02-19T22:51:49.668541Z","steps":["trace[908321828] 'process raft request' (duration: 318.677405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:51:49.668735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:51:49.3497Z","time spent":"318.941291ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T22:52:02.959755Z","caller":"traceutil/trace.go:171","msg":"trace[57659959] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"314.33292ms","start":"2026-02-19T22:52:02.645399Z","end":"2026-02-19T22:52:02.959732Z","steps":["trace[57659959] 'process raft request' (duration: 314.175686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:52:02.959901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:52:02.645349Z","time spent":"314.474334ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T22:52:02.960046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.866414ms","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-19T22:52:02.960096Z","caller":"traceutil/trace.go:171","msg":"trace[408205165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"292.927985ms","start":"2026-02-19T22:52:02.667154Z","end":"2026-02-19T22:52:02.960082Z","steps":["trace[408205165] 'agreement among raft nodes before linearized reading' (duration: 292.763891ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:52:02.960201Z","caller":"traceutil/trace.go:171","msg":"trace[1220496742] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"292.532106ms","start":"2026-02-19T22:52:02.667169Z","end":"2026-02-19T22:52:02.959701Z","steps":["trace[1220496742] 'read index received' (duration: 292.347142ms)","trace[1220496742] 'applied index is now lower than readState.Index' (duration: 184.124µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T22:52:09.509707Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132863728761271,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T22:52:09.623172Z","caller":"traceutil/trace.go:171","msg":"trace[2055280497] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"613.807553ms","start":"2026-02-19T22:52:09.009339Z","end":"2026-02-19T22:52:09.623146Z","steps":["trace[2055280497] 'read index received' (duration: 613.595018ms)","trace[2055280497] 'applied index is now lower than readState.Index' (duration: 211.605µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T22:52:09.623231Z","caller":"traceutil/trace.go:171","msg":"trace[1420207313] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"639.092912ms","start":"2026-02-19T22:52:08.98411Z","end":"2026-02-19T22:52:09.623203Z","steps":["trace[1420207313] 'process raft request' (duration: 638.885787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:52:09.623375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:52:08.984094Z","time spent":"639.203604ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T22:52:09.62342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.620779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T22:52:09.62344Z","caller":"traceutil/trace.go:171","msg":"trace[1258456429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"432.678601ms","start":"2026-02-19T22:52:09.190754Z","end":"2026-02-19T22:52:09.623433Z","steps":["trace[1258456429] 'agreement among raft nodes before linearized reading' (duration: 432.65274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:52:09.623383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.035509ms","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-19T22:52:09.623463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:52:09.190732Z","time spent":"432.726951ms","remote":"127.0.0.1:38754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T22:52:09.623503Z","caller":"traceutil/trace.go:171","msg":"trace[67841540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:681; }","duration":"614.198033ms","start":"2026-02-19T22:52:09.009279Z","end":"2026-02-19T22:52:09.623477Z","steps":["trace[67841540] 'agreement among raft nodes before linearized reading' (duration: 614.001388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:52:09.623578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:52:09.009257Z","time spent":"614.296024ms","remote":"127.0.0.1:38966","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-19T22:52:09.623632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.666309ms","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-19T22:52:09.623662Z","caller":"traceutil/trace.go:171","msg":"trace[1040066519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:681; }","duration":"231.721421ms","start":"2026-02-19T22:52:09.391923Z","end":"2026-02-19T22:52:09.623645Z","steps":["trace[1040066519] 'agreement among raft nodes before linearized reading' (duration: 231.669298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:52:10.084661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.943069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T22:52:10.085011Z","caller":"traceutil/trace.go:171","msg":"trace[1346361632] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:709; }","duration":"308.127791ms","start":"2026-02-19T22:52:09.776867Z","end":"2026-02-19T22:52:10.084995Z","steps":["trace[1346361632] 'read index received' (duration: 28.583568ms)","trace[1346361632] 'applied index is now lower than readState.Index' (duration: 279.543343ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T22:52:10.085171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.306856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-19T22:52:10.085204Z","caller":"traceutil/trace.go:171","msg":"trace[944002212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"308.382178ms","start":"2026-02-19T22:52:09.776812Z","end":"2026-02-19T22:52:10.085194Z","steps":["trace[944002212] 'agreement among raft nodes before linearized reading' (duration: 308.236275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:52:10.085243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:52:09.776788Z","time spent":"308.447579ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-19T22:52:10.085414Z","caller":"traceutil/trace.go:171","msg":"trace[1962079450] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"458.231486ms","start":"2026-02-19T22:52:09.627124Z","end":"2026-02-19T22:52:10.085356Z","steps":["trace[1962079450] 'process raft request' (duration: 178.333644ms)","trace[1962079450] 'compare' (duration: 278.793196ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T22:52:10.085526Z","caller":"traceutil/trace.go:171","msg":"trace[1782010928] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"457.681143ms","start":"2026-02-19T22:52:09.627833Z","end":"2026-02-19T22:52:10.085514Z","steps":["trace[1782010928] 'process raft request' (duration: 457.106969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:52:10.085584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:52:09.627823Z","time spent":"457.728874ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T22:52:10.085684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:52:09.627109Z","time spent":"458.372899ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T22:52:10.30385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.669349ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-19T22:52:10.30428Z","caller":"traceutil/trace.go:171","msg":"trace[726354672] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:711; }","duration":"111.900274ms","start":"2026-02-19T22:52:10.192349Z","end":"2026-02-19T22:52:10.304249Z","steps":["trace[726354672] 'read index received' (duration: 7.435838ms)","trace[726354672] 'applied index is now lower than readState.Index' (duration: 104.463516ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T22:52:10.304404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.048379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T22:52:10.304439Z","caller":"traceutil/trace.go:171","msg":"trace[443595358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:684; }","duration":"112.137842ms","start":"2026-02-19T22:52:10.192292Z","end":"2026-02-19T22:52:10.30443Z","steps":["trace[443595358] 'agreement among raft nodes before linearized reading' (duration: 112.037869ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:52:10.304467Z","caller":"traceutil/trace.go:171","msg":"trace[415483108] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"214.508377ms","start":"2026-02-19T22:52:10.089925Z","end":"2026-02-19T22:52:10.304433Z","steps":["trace[415483108] 'process raft request' (duration: 214.076816ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:52:11.199888Z","caller":"traceutil/trace.go:171","msg":"trace[447011348] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"520.826714ms","start":"2026-02-19T22:52:10.679032Z","end":"2026-02-19T22:52:11.199859Z","steps":["trace[447011348] 'process raft request' (duration: 520.607759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:52:11.200042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:52:10.679023Z","time spent":"520.935947ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T22:52:12.236236Z","caller":"traceutil/trace.go:171","msg":"trace[1071020013] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"142.332558ms","start":"2026-02-19T22:52:12.093869Z","end":"2026-02-19T22:52:12.236202Z","steps":["trace[1071020013] 'process raft request' (duration: 141.97967ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:52:12.247968Z","caller":"traceutil/trace.go:171","msg":"trace[933919244] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"153.478145ms","start":"2026-02-19T22:52:12.094467Z","end":"2026-02-19T22:52:12.247946Z","steps":["trace[933919244] 'process raft request' (duration: 150.665727ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:52:32.220559Z","caller":"traceutil/trace.go:171","msg":"trace[2016306821] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"404.574241ms","start":"2026-02-19T22:52:31.815964Z","end":"2026-02-19T22:52:32.220538Z","steps":["trace[2016306821] 'process raft request' (duration: 404.172001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:52:32.220692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:52:31.815947Z","time spent":"404.681023ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T22:52:32.815256Z","caller":"traceutil/trace.go:171","msg":"trace[1781567046] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"412.348991ms","start":"2026-02-19T22:52:32.402873Z","end":"2026-02-19T22:52:32.815222Z","steps":["trace[1781567046] 'process raft request' (duration: 412.063814ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:52:32.815521Z","caller":"traceutil/trace.go:171","msg":"trace[1762508732] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"412.155847ms","start":"2026-02-19T22:52:32.40332Z","end":"2026-02-19T22:52:32.815476Z","steps":["trace[1762508732] 'process raft request' (duration: 411.766397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:52:32.815701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:52:32.402865Z","time spent":"412.478764ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T22:52:32.815735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:52:32.403314Z","time spent":"412.336102ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T22:52:34.726888Z","caller":"traceutil/trace.go:171","msg":"trace[1856757286] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"215.482151ms","start":"2026-02-19T22:52:34.51138Z","end":"2026-02-19T22:52:34.726862Z","steps":["trace[1856757286] 'read index received' (duration: 215.300546ms)","trace[1856757286] 'applied index is now lower than readState.Index' (duration: 180.485µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T22:52:34.726982Z","caller":"traceutil/trace.go:171","msg":"trace[560960208] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"221.971713ms","start":"2026-02-19T22:52:34.504978Z","end":"2026-02-19T22:52:34.72695Z","steps":["trace[560960208] 'process raft request' (duration: 221.730886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:52:34.727138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.731797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-19T22:52:34.727287Z","caller":"traceutil/trace.go:171","msg":"trace[1435064982] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:754; }","duration":"215.88118ms","start":"2026-02-19T22:52:34.511346Z","end":"2026-02-19T22:52:34.727227Z","steps":["trace[1435064982] 'agreement among raft nodes before linearized reading' (duration: 215.608534ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:52:35.007765Z","caller":"traceutil/trace.go:171","msg":"trace[745309130] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"156.512273ms","start":"2026-02-19T22:52:34.851234Z","end":"2026-02-19T22:52:35.007746Z","steps":["trace[745309130] 'process raft request' (duration: 156.40431ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:52:35.008097Z","caller":"traceutil/trace.go:171","msg":"trace[1997734017] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:790; }","duration":"156.421861ms","start":"2026-02-19T22:52:34.851657Z","end":"2026-02-19T22:52:35.008079Z","steps":["trace[1997734017] 'read index received' (duration: 156.418531ms)","trace[1997734017] 'applied index is now lower than readState.Index' (duration: 2.67µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T22:52:35.008227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.557054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-19T22:52:35.008247Z","caller":"traceutil/trace.go:171","msg":"trace[666331362] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:756; }","duration":"156.610546ms","start":"2026-02-19T22:52:34.85163Z","end":"2026-02-19T22:52:35.008241Z","steps":["trace[666331362] 'agreement among raft nodes before linearized reading' (duration: 156.490763ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:52:35.06426Z","caller":"traceutil/trace.go:171","msg":"trace[849510264] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"211.897223ms","start":"2026-02-19T22:52:34.852332Z","end":"2026-02-19T22:52:35.064229Z","steps":["trace[849510264] 'process raft request' (duration: 211.639007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:52:48.01938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.393494ms","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-19T22:52:48.019489Z","caller":"traceutil/trace.go:171","msg":"trace[472355392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:842; }","duration":"642.548208ms","start":"2026-02-19T22:52:47.37691Z","end":"2026-02-19T22:52:48.019458Z","steps":["trace[472355392] 'range keys from in-memory index tree' (duration: 642.208489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:52:48.019534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:52:47.376892Z","time spent":"642.63264ms","remote":"127.0.0.1:38966","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-19T22:52:48.019561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.721823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T22:52:48.019661Z","caller":"traceutil/trace.go:171","msg":"trace[265975728] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"291.885862ms","start":"2026-02-19T22:52:47.727758Z","end":"2026-02-19T22:52:48.019644Z","steps":["trace[265975728] 'process raft request' (duration: 119.007275ms)","trace[265975728] 'compare' (duration: 172.615951ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T22:52:48.190697Z","caller":"traceutil/trace.go:171","msg":"trace[107123185] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"166.683274ms","start":"2026-02-19T22:52:48.023984Z","end":"2026-02-19T22:52:48.190667Z","steps":["trace[107123185] 'process raft request' (duration: 119.942538ms)","trace[107123185] 'compare' (duration: 46.623544ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T22:53:00.554189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.300775ms","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-19T22:53:00.554392Z","caller":"traceutil/trace.go:171","msg":"trace[718638669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:877; }","duration":"143.554861ms","start":"2026-02-19T22:53:00.410814Z","end":"2026-02-19T22:53:00.554369Z","steps":["trace[718638669] 'range keys from in-memory index tree' (duration: 143.04965ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:53:01.522285Z","caller":"traceutil/trace.go:171","msg":"trace[1111868299] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"348.328779ms","start":"2026-02-19T22:53:01.173901Z","end":"2026-02-19T22:53:01.52223Z","steps":["trace[1111868299] 'read index received' (duration: 348.147314ms)","trace[1111868299] 'applied index is now lower than readState.Index' (duration: 180.065µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T22:53:01.52238Z","caller":"traceutil/trace.go:171","msg":"trace[1860296815] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"348.586085ms","start":"2026-02-19T22:53:01.173759Z","end":"2026-02-19T22:53:01.522345Z","steps":["trace[1860296815] 'process raft request' (duration: 348.306138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:53:01.522525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.601825ms","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-19T22:53:01.522543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:53:01.17375Z","time spent":"348.711378ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T22:53:01.52256Z","caller":"traceutil/trace.go:171","msg":"trace[1257129458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:880; }","duration":"348.694228ms","start":"2026-02-19T22:53:01.173857Z","end":"2026-02-19T22:53:01.522551Z","steps":["trace[1257129458] 'agreement among raft nodes before linearized reading' (duration: 348.529894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:53:01.522549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.438565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T22:53:01.522598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:53:01.173835Z","time spent":"348.755389ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-19T22:53:01.522622Z","caller":"traceutil/trace.go:171","msg":"trace[325289407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:880; }","duration":"330.520307ms","start":"2026-02-19T22:53:01.192082Z","end":"2026-02-19T22:53:01.522602Z","steps":["trace[325289407] 'agreement among raft nodes before linearized reading' (duration: 330.399684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:53:01.522662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:53:01.192039Z","time spent":"330.615089ms","remote":"127.0.0.1:38754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T22:53:04.917265Z","caller":"traceutil/trace.go:171","msg":"trace[1793071796] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"205.171907ms","start":"2026-02-19T22:53:04.71207Z","end":"2026-02-19T22:53:04.917242Z","steps":["trace[1793071796] 'process raft request' (duration: 204.750765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:53:05.186553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.142141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T22:53:05.186631Z","caller":"traceutil/trace.go:171","msg":"trace[1363639615] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"264.970686ms","start":"2026-02-19T22:53:04.921646Z","end":"2026-02-19T22:53:05.186617Z","steps":["trace[1363639615] 'process raft request' (duration: 119.704332ms)","trace[1363639615] 'compare' (duration: 145.052469ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T22:53:05.739995Z","caller":"traceutil/trace.go:171","msg":"trace[173999076] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"120.837399ms","start":"2026-02-19T22:53:05.619126Z","end":"2026-02-19T22:53:05.739963Z","steps":["trace[173999076] 'process raft request' (duration: 120.633374ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:53:05.742841Z","caller":"traceutil/trace.go:171","msg":"trace[1068040428] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"123.502076ms","start":"2026-02-19T22:53:05.61932Z","end":"2026-02-19T22:53:05.742822Z","steps":["trace[1068040428] 'process raft request' (duration: 123.416804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:53:06.754823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:53:06.193344Z","time spent":"561.44223ms","remote":"127.0.0.1:38330","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-19T22:53:06.755691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.218102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T22:53:06.755766Z","caller":"traceutil/trace.go:171","msg":"trace[606708432] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"500.525995ms","start":"2026-02-19T22:53:06.255225Z","end":"2026-02-19T22:53:06.755751Z","steps":["trace[606708432] 'read index received' (duration: 138.105467ms)","trace[606708432] 'applied index is now lower than readState.Index' (duration: 362.419328ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T22:53:06.755837Z","caller":"traceutil/trace.go:171","msg":"trace[492804531] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"778.182364ms","start":"2026-02-19T22:53:05.977626Z","end":"2026-02-19T22:53:06.755808Z","steps":["trace[492804531] 'process raft request' (duration: 415.778127ms)","trace[492804531] 'compare' (duration: 361.744721ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T22:53:06.755952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:53:05.977605Z","time spent":"778.301166ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T22:53:06.755976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.74713ms","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-19T22:53:06.75602Z","caller":"traceutil/trace.go:171","msg":"trace[2055135006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:913; }","duration":"500.812562ms","start":"2026-02-19T22:53:06.255185Z","end":"2026-02-19T22:53:06.755997Z","steps":["trace[2055135006] 'agreement among raft nodes before linearized reading' (duration: 500.639207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:53:06.756075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:53:06.255165Z","time spent":"500.902363ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-19T22:53:07.186293Z","caller":"traceutil/trace.go:171","msg":"trace[1508552981] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"264.700919ms","start":"2026-02-19T22:53:06.921573Z","end":"2026-02-19T22:53:07.186274Z","steps":["trace[1508552981] 'read index received' (duration: 264.508194ms)","trace[1508552981] 'applied index is now lower than readState.Index' (duration: 191.735µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T22:53:07.186431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.841783ms","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-19T22:53:07.186452Z","caller":"traceutil/trace.go:171","msg":"trace[1454260366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:914; }","duration":"264.913435ms","start":"2026-02-19T22:53:06.921533Z","end":"2026-02-19T22:53:07.186446Z","steps":["trace[1454260366] 'agreement among raft nodes before linearized reading' (duration: 264.811532ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:53:07.186539Z","caller":"traceutil/trace.go:171","msg":"trace[522490270] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"426.508853ms","start":"2026-02-19T22:53:06.759999Z","end":"2026-02-19T22:53:07.186508Z","steps":["trace[522490270] 'process raft request' (duration: 426.152554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:53:07.186709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:53:06.75998Z","time spent":"426.641086ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T22:53:07.328111Z","caller":"traceutil/trace.go:171","msg":"trace[1758965672] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:956; }","duration":"135.81349ms","start":"2026-02-19T22:53:07.192276Z","end":"2026-02-19T22:53:07.328089Z","steps":["trace[1758965672] 'read index received' (duration: 78.4146ms)","trace[1758965672] 'applied index is now lower than readState.Index' (duration: 57.39783ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T22:53:07.328229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.933723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T22:53:07.32826Z","caller":"traceutil/trace.go:171","msg":"trace[1955688469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"135.978864ms","start":"2026-02-19T22:53:07.192272Z","end":"2026-02-19T22:53:07.328251Z","steps":["trace[1955688469] 'agreement among raft nodes before linearized reading' (duration: 135.896332ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:53:07.328299Z","caller":"traceutil/trace.go:171","msg":"trace[717562309] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"136.24958ms","start":"2026-02-19T22:53:07.19203Z","end":"2026-02-19T22:53:07.328279Z","steps":["trace[717562309] 'process raft request' (duration: 117.060025ms)","trace[717562309] 'compare' (duration: 18.932489ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T22:53:07.948154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.573083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T22:53:07.948235Z","caller":"traceutil/trace.go:171","msg":"trace[1607526065] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:919; }","duration":"120.702166ms","start":"2026-02-19T22:53:07.827516Z","end":"2026-02-19T22:53:07.948218Z","steps":["trace[1607526065] 'count revisions from in-memory index tree' (duration: 120.46266ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:53:09.064486Z","caller":"traceutil/trace.go:171","msg":"trace[472574969] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"677.300057ms","start":"2026-02-19T22:53:08.387162Z","end":"2026-02-19T22:53:09.064462Z","steps":["trace[472574969] 'process raft request' (duration: 677.168954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:53:09.064683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:53:08.387134Z","time spent":"677.475702ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T22:53:09.566041Z","caller":"traceutil/trace.go:171","msg":"trace[677462225] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:961; }","duration":"634.269613ms","start":"2026-02-19T22:53:08.931743Z","end":"2026-02-19T22:53:09.566012Z","steps":["trace[677462225] 'read index received' (duration: 132.653702ms)","trace[677462225] 'applied index is now lower than readState.Index' (duration: 501.614731ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T22:53:09.566183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.426166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T22:53:09.566173Z","caller":"traceutil/trace.go:171","msg":"trace[728000792] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"1.104068377s","start":"2026-02-19T22:53:08.462071Z","end":"2026-02-19T22:53:09.566139Z","steps":["trace[728000792] 'process raft request' (duration: 1.101014181s)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:53:09.566216Z","caller":"traceutil/trace.go:171","msg":"trace[570504667] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:921; }","duration":"634.501878ms","start":"2026-02-19T22:53:08.931705Z","end":"2026-02-19T22:53:09.566207Z","steps":["trace[570504667] 'agreement among raft nodes before linearized reading' (duration: 634.428087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:53:09.566297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:53:08.931689Z","time spent":"634.559319ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-19T22:53:09.566348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:53:08.462048Z","time spent":"1.104200339s","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T22:53:09.796039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.646852ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-19T22:53:09.796261Z","caller":"traceutil/trace.go:171","msg":"trace[480662316] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"225.289943ms","start":"2026-02-19T22:53:09.570952Z","end":"2026-02-19T22:53:09.796242Z","steps":["trace[480662316] 'process raft request' (duration: 225.189241ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:53:09.796311Z","caller":"traceutil/trace.go:171","msg":"trace[1064295671] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"230.162604ms","start":"2026-02-19T22:53:09.566109Z","end":"2026-02-19T22:53:09.796271Z","steps":["trace[1064295671] 'read index received' (duration: 78.382179ms)","trace[1064295671] 'applied index is now lower than readState.Index' (duration: 151.776005ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T22:53:09.796623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.28296ms","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-19T22:53:09.796663Z","caller":"traceutil/trace.go:171","msg":"trace[1052241767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:922; }","duration":"604.371693ms","start":"2026-02-19T22:53:09.192281Z","end":"2026-02-19T22:53:09.796653Z","steps":["trace[1052241767] 'agreement among raft nodes before linearized reading' (duration: 604.133547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:53:09.796701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:53:09.192267Z","time spent":"604.425183ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T22:53:09.796718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.465685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T22:53:09.796747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.911513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-19T22:53:09.79676Z","caller":"traceutil/trace.go:171","msg":"trace[330036421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"604.535106ms","start":"2026-02-19T22:53:09.192213Z","end":"2026-02-19T22:53:09.796748Z","steps":["trace[330036421] 'agreement among raft nodes before linearized reading' (duration: 604.445914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:53:09.796795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:53:09.192177Z","time spent":"604.611558ms","remote":"127.0.0.1:38748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T22:53:09.796797Z","caller":"traceutil/trace.go:171","msg":"trace[360196587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:922; }","duration":"464.986455ms","start":"2026-02-19T22:53:09.331794Z","end":"2026-02-19T22:53:09.79678Z","steps":["trace[360196587] 'agreement among raft nodes before linearized reading' (duration: 464.857631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:53:09.796837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:53:09.331779Z","time spent":"465.047646ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-19T22:53:09.796616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.406965ms","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-19T22:53:09.796964Z","caller":"traceutil/trace.go:171","msg":"trace[751435033] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:922; }","duration":"522.805224ms","start":"2026-02-19T22:53:09.274147Z","end":"2026-02-19T22:53:09.796952Z","steps":["trace[751435033] 'agreement among raft nodes before linearized reading' (duration: 522.24439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:53:09.797185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:53:09.274132Z","time spent":"523.02463ms","remote":"127.0.0.1:38966","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-19T22:53:09.844368Z","caller":"traceutil/trace.go:171","msg":"trace[866303024] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"190.3638ms","start":"2026-02-19T22:53:09.653975Z","end":"2026-02-19T22:53:09.844339Z","steps":["trace[866303024] 'process raft request' (duration: 190.229617ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:53:09.989876Z","caller":"traceutil/trace.go:171","msg":"trace[173613583] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"187.296004ms","start":"2026-02-19T22:53:09.802532Z","end":"2026-02-19T22:53:09.989828Z","steps":["trace[173613583] 'process raft request' (duration: 187.211692ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:53:09.989884Z","caller":"traceutil/trace.go:171","msg":"trace[964706643] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"188.604316ms","start":"2026-02-19T22:53:09.80125Z","end":"2026-02-19T22:53:09.989854Z","steps":["trace[964706643] 'process raft request' (duration: 188.337819ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:53:09.990191Z","caller":"traceutil/trace.go:171","msg":"trace[1384940882] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:966; }","duration":"145.854798ms","start":"2026-02-19T22:53:09.844312Z","end":"2026-02-19T22:53:09.990167Z","steps":["trace[1384940882] 'read index received' (duration: 145.306085ms)","trace[1384940882] 'applied index is now lower than readState.Index' (duration: 547.723µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T22:53:09.990232Z","caller":"traceutil/trace.go:171","msg":"trace[881617146] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"187.024767ms","start":"2026-02-19T22:53:09.803159Z","end":"2026-02-19T22:53:09.990184Z","steps":["trace[881617146] 'process raft request' (duration: 186.906604ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:53:09.990442Z","caller":"traceutil/trace.go:171","msg":"trace[203502010] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"187.544749ms","start":"2026-02-19T22:53:09.802883Z","end":"2026-02-19T22:53:09.990427Z","steps":["trace[203502010] 'process raft request' (duration: 186.908433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:53:09.990444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.392825ms","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-19T22:53:09.990481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.193124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-19T22:53:09.990529Z","caller":"traceutil/trace.go:171","msg":"trace[1165654411] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:927; }","duration":"190.284448ms","start":"2026-02-19T22:53:09.800232Z","end":"2026-02-19T22:53:09.990516Z","steps":["trace[1165654411] 'agreement among raft nodes before linearized reading' (duration: 190.109553ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:53:09.990527Z","caller":"traceutil/trace.go:171","msg":"trace[1636784992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"176.501287ms","start":"2026-02-19T22:53:09.814004Z","end":"2026-02-19T22:53:09.990506Z","steps":["trace[1636784992] 'agreement among raft nodes before linearized reading' (duration: 176.305322ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:53:09.999205Z","caller":"traceutil/trace.go:171","msg":"trace[997231615] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"151.359314ms","start":"2026-02-19T22:53:09.847829Z","end":"2026-02-19T22:53:09.999188Z","steps":["trace[997231615] 'process raft request' (duration: 151.305533ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:53:09.999441Z","caller":"traceutil/trace.go:171","msg":"trace[1306960333] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"152.271748ms","start":"2026-02-19T22:53:09.847158Z","end":"2026-02-19T22:53:09.999429Z","steps":["trace[1306960333] 'process raft request' (duration: 151.883479ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:53:10.166841Z","caller":"traceutil/trace.go:171","msg":"trace[1878242345] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"171.199186ms","start":"2026-02-19T22:53:09.995625Z","end":"2026-02-19T22:53:10.166824Z","steps":["trace[1878242345] 'process raft request' (duration: 171.152795ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:53:10.16702Z","caller":"traceutil/trace.go:171","msg":"trace[1715257437] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"172.226331ms","start":"2026-02-19T22:53:09.994767Z","end":"2026-02-19T22:53:10.166993Z","steps":["trace[1715257437] 'process raft request' (duration: 108.208198ms)","trace[1715257437] 'compare' (duration: 63.718756ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T22:53:12.278826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.765922ms","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-19T22:53:12.278888Z","caller":"traceutil/trace.go:171","msg":"trace[1534199241] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:935; }","duration":"106.882744ms","start":"2026-02-19T22:53:12.171993Z","end":"2026-02-19T22:53:12.278876Z","steps":["trace[1534199241] 'range keys from in-memory index tree' (duration: 106.623837ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T22:56:14.898799Z","caller":"traceutil/trace.go:171","msg":"trace[1773331231] transaction","detail":"{read_only:false; response_revision:1729; number_of_response:1; }","duration":"135.184506ms","start":"2026-02-19T22:56:14.763582Z","end":"2026-02-19T22:56:14.898767Z","steps":["trace[1773331231] 'process raft request' (duration: 134.93512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:56:20.243125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.740756ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-19T22:56:20.243227Z","caller":"traceutil/trace.go:171","msg":"trace[544422396] linearizableReadLoop","detail":"{readStateIndex:1838; appliedIndex:1837; }","duration":"340.246315ms","start":"2026-02-19T22:56:19.902962Z","end":"2026-02-19T22:56:20.243208Z","steps":["trace[544422396] 'read index received' (duration: 55.781µs)","trace[544422396] 'applied index is now lower than readState.Index' (duration: 340.189174ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T22:56:20.24354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.380244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T22:56:20.243595Z","caller":"traceutil/trace.go:171","msg":"trace[996730312] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1756; }","duration":"242.446305ms","start":"2026-02-19T22:56:20.001135Z","end":"2026-02-19T22:56:20.243581Z","steps":["trace[996730312] 'agreement among raft nodes before linearized reading' (duration: 242.349243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:56:20.243606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.623746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-19T22:56:20.243716Z","caller":"traceutil/trace.go:171","msg":"trace[822052709] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1756; }","duration":"340.741519ms","start":"2026-02-19T22:56:19.902947Z","end":"2026-02-19T22:56:20.243688Z","steps":["trace[822052709] 'agreement among raft nodes before linearized reading' (duration: 340.477052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T22:56:20.243776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T22:56:19.902885Z","time spent":"340.875702ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "}