{"level":"warn","ts":"2026-03-23T05:22:14.623932Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-23T05:22:14.624034Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.228:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.228:2380","--initial-cluster=instance=https://199.19.213.228:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.228:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.228:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-23T05:22:14.624107Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-23T05:22:14.624117Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.228:2380"]} {"level":"info","ts":"2026-03-23T05:22:14.624141Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-23T05:22:14.624818Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"]} {"level":"info","ts":"2026-03-23T05:22:14.624891Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.228:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-23T05:22:14.627429Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.331165ms"} {"level":"info","ts":"2026-03-23T05:22:14.63398Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"db79120667ce701f","cluster-id":"793c581eb68f3646"} {"level":"info","ts":"2026-03-23T05:22:14.634077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=()"} {"level":"info","ts":"2026-03-23T05:22:14.634103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 0"} {"level":"info","ts":"2026-03-23T05:22:14.634121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft db79120667ce701f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-23T05:22:14.634131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 1"} {"level":"info","ts":"2026-03-23T05:22:14.634172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"} {"level":"warn","ts":"2026-03-23T05:22:14.643497Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-23T05:22:14.645061Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-23T05:22:14.646188Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-23T05:22:14.648329Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"db79120667ce701f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-23T05:22:14.648473Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-23T05:22:14.648589Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-23T05:22:14.648604Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-23T05:22:14.648855Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"db79120667ce701f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-23T05:22:14.652237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"} {"level":"info","ts":"2026-03-23T05:22:14.652495Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","added-peer-id":"db79120667ce701f","added-peer-peer-urls":["https://199.19.213.228:2380"]} {"level":"info","ts":"2026-03-23T05:22:14.652899Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-23T05:22:14.653083Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.228:2380"} {"level":"info","ts":"2026-03-23T05:22:14.653125Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.228:2380"} {"level":"info","ts":"2026-03-23T05:22:14.653113Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"db79120667ce701f","initial-advertise-peer-urls":["https://199.19.213.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-23T05:22:14.653157Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-23T05:22:15.534598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f is starting a new election at term 1"} {"level":"info","ts":"2026-03-23T05:22:15.534654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became pre-candidate at term 1"} {"level":"info","ts":"2026-03-23T05:22:15.534687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgPreVoteResp from db79120667ce701f at term 1"} {"level":"info","ts":"2026-03-23T05:22:15.534703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became candidate at term 2"} {"level":"info","ts":"2026-03-23T05:22:15.534713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgVoteResp from db79120667ce701f at term 2"} {"level":"info","ts":"2026-03-23T05:22:15.534726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became leader at term 2"} {"level":"info","ts":"2026-03-23T05:22:15.534745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: db79120667ce701f elected leader db79120667ce701f at term 2"} {"level":"info","ts":"2026-03-23T05:22:15.536056Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-23T05:22:15.536829Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-23T05:22:15.536824Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"db79120667ce701f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.228:2379]}","request-path":"/0/members/db79120667ce701f/attributes","cluster-id":"793c581eb68f3646","publish-timeout":"7s"} {"level":"info","ts":"2026-03-23T05:22:15.536876Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-23T05:22:15.537142Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-23T05:22:15.537167Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-23T05:22:15.537386Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","cluster-version":"3.5"} {"level":"info","ts":"2026-03-23T05:22:15.537471Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-23T05:22:15.537503Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-23T05:22:15.53975Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.228:2379"} {"level":"info","ts":"2026-03-23T05:22:15.539779Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-23T05:22:31.422176Z","caller":"traceutil/trace.go:171","msg":"trace[1302366763] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"222.455602ms","start":"2026-03-23T05:22:31.199702Z","end":"2026-03-23T05:22:31.422157Z","steps":["trace[1302366763] 'process raft request' (duration: 222.357769ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:22:31.801456Z","caller":"traceutil/trace.go:171","msg":"trace[1138816575] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"180.437558ms","start":"2026-03-23T05:22:31.620949Z","end":"2026-03-23T05:22:31.801387Z","steps":["trace[1138816575] 'process raft request' (duration: 180.182902ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:22:32.181387Z","caller":"traceutil/trace.go:171","msg":"trace[420916979] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"257.616241ms","start":"2026-03-23T05:22:31.923738Z","end":"2026-03-23T05:22:32.181354Z","steps":["trace[420916979] 'read index received' (duration: 257.353024ms)","trace[420916979] 'applied index is now lower than readState.Index' (duration: 261.797µs)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:22:32.181501Z","caller":"traceutil/trace.go:171","msg":"trace[1942625753] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"370.009424ms","start":"2026-03-23T05:22:31.811455Z","end":"2026-03-23T05:22:32.181464Z","steps":["trace[1942625753] 'process raft request' (duration: 369.696867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:22:32.181532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.777914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:22:32.181602Z","caller":"traceutil/trace.go:171","msg":"trace[1284234439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"257.890257ms","start":"2026-03-23T05:22:31.9237Z","end":"2026-03-23T05:22:32.18159Z","steps":["trace[1284234439] 'agreement among raft nodes before linearized reading' (duration: 257.780225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:22:32.182239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:22:31.811431Z","time spent":"370.152547ms","remote":"127.0.0.1:39186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:22:41.624509Z","caller":"traceutil/trace.go:171","msg":"trace[1011995190] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"160.319328ms","start":"2026-03-23T05:22:41.464133Z","end":"2026-03-23T05:22:41.624453Z","steps":["trace[1011995190] 'process raft request' (duration: 160.187234ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:22:45.200719Z","caller":"traceutil/trace.go:171","msg":"trace[205729405] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:477; }","duration":"113.731595ms","start":"2026-03-23T05:22:45.086961Z","end":"2026-03-23T05:22:45.200692Z","steps":["trace[205729405] 'read index received' (duration: 113.543771ms)","trace[205729405] 'applied index is now lower than readState.Index' (duration: 186.864µs)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:22:45.200854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.877589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-23T05:22:45.200888Z","caller":"traceutil/trace.go:171","msg":"trace[2034397672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:464; }","duration":"113.949511ms","start":"2026-03-23T05:22:45.086927Z","end":"2026-03-23T05:22:45.200877Z","steps":["trace[2034397672] 'agreement among raft nodes before linearized reading' (duration: 113.866919ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:22:45.200924Z","caller":"traceutil/trace.go:171","msg":"trace[873912602] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"149.469188ms","start":"2026-03-23T05:22:45.05143Z","end":"2026-03-23T05:22:45.200899Z","steps":["trace[873912602] 'process raft request' (duration: 149.12987ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:22:51.766551Z","caller":"traceutil/trace.go:171","msg":"trace[1587904701] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"107.364374ms","start":"2026-03-23T05:22:51.659159Z","end":"2026-03-23T05:22:51.766523Z","steps":["trace[1587904701] 'read index received' (duration: 107.125258ms)","trace[1587904701] 'applied index is now lower than readState.Index' (duration: 237.946µs)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:22:51.766616Z","caller":"traceutil/trace.go:171","msg":"trace[1065237589] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"195.56526ms","start":"2026-03-23T05:22:51.571009Z","end":"2026-03-23T05:22:51.766574Z","steps":["trace[1065237589] 'process raft request' (duration: 195.365245ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:22:51.766732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.560708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-23T05:22:51.766824Z","caller":"traceutil/trace.go:171","msg":"trace[215016625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:485; }","duration":"107.675971ms","start":"2026-03-23T05:22:51.659104Z","end":"2026-03-23T05:22:51.76678Z","steps":["trace[215016625] 'agreement among raft nodes before linearized reading' (duration: 107.536977ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:22:52.801127Z","caller":"traceutil/trace.go:171","msg":"trace[1214521251] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"856.413538ms","start":"2026-03-23T05:22:51.944689Z","end":"2026-03-23T05:22:52.801103Z","steps":["trace[1214521251] 'process raft request' (duration: 856.231344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:22:52.801275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:22:51.944671Z","time spent":"856.52653ms","remote":"127.0.0.1:39266","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-03-23T05:23:02.4741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.38391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-23T05:23:02.47421Z","caller":"traceutil/trace.go:171","msg":"trace[269402350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:591; }","duration":"341.541824ms","start":"2026-03-23T05:23:02.132645Z","end":"2026-03-23T05:23:02.474187Z","steps":["trace[269402350] 'range keys from in-memory index tree' (duration: 341.204616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:23:02.474273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:23:02.132627Z","time spent":"341.634467ms","remote":"127.0.0.1:39266","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-03-23T05:23:02.801795Z","caller":"traceutil/trace.go:171","msg":"trace[372211871] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"148.235089ms","start":"2026-03-23T05:23:02.653537Z","end":"2026-03-23T05:23:02.801772Z","steps":["trace[372211871] 'read index received' (duration: 148.127557ms)","trace[372211871] 'applied index is now lower than readState.Index' (duration: 106.492µs)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:23:02.801862Z","caller":"traceutil/trace.go:171","msg":"trace[1969521494] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"323.380861ms","start":"2026-03-23T05:23:02.478444Z","end":"2026-03-23T05:23:02.801825Z","steps":["trace[1969521494] 'process raft request' (duration: 323.167255ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:23:02.801968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.409293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:23:02.801998Z","caller":"traceutil/trace.go:171","msg":"trace[1673268440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"148.491355ms","start":"2026-03-23T05:23:02.653498Z","end":"2026-03-23T05:23:02.80199Z","steps":["trace[1673268440] 'agreement among raft nodes before linearized reading' (duration: 148.365202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:23:02.802019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:23:02.478413Z","time spent":"323.525714ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:23:10.976Z","caller":"traceutil/trace.go:171","msg":"trace[1232894174] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"124.840961ms","start":"2026-03-23T05:23:10.851126Z","end":"2026-03-23T05:23:10.975967Z","steps":["trace[1232894174] 'process raft request' (duration: 124.671597ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:23:12.149742Z","caller":"traceutil/trace.go:171","msg":"trace[756945905] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"149.292244ms","start":"2026-03-23T05:23:12.000421Z","end":"2026-03-23T05:23:12.149713Z","steps":["trace[756945905] 'process raft request' (duration: 149.1282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:23:13.947458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8079348987842916272,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-23T05:23:14.447833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8079348987842916272,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-23T05:23:14.65532Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.301292857s","expected-duration":"1s"} {"level":"info","ts":"2026-03-23T05:23:14.655631Z","caller":"traceutil/trace.go:171","msg":"trace[844079263] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.301661276s","start":"2026-03-23T05:23:13.353947Z","end":"2026-03-23T05:23:14.655608Z","steps":["trace[844079263] 'process raft request' (duration: 1.301517203s)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:23:14.655764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:23:13.353929Z","time spent":"1.301766979s","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:23:15.614644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.664237ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-23T05:23:15.614723Z","caller":"traceutil/trace.go:171","msg":"trace[568557341] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:700; }","duration":"2.168152254s","start":"2026-03-23T05:23:13.446557Z","end":"2026-03-23T05:23:15.614709Z","steps":["trace[568557341] 'read index received' (duration: 1.208948273s)","trace[568557341] 'applied index is now lower than readState.Index' (duration: 959.203041ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:23:15.615071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.968623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-23T05:23:15.615098Z","caller":"traceutil/trace.go:171","msg":"trace[1289611812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:681; }","duration":"623.070846ms","start":"2026-03-23T05:23:14.99202Z","end":"2026-03-23T05:23:15.615091Z","steps":["trace[1289611812] 'agreement among raft nodes before linearized reading' (duration: 622.942653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:23:15.615068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.168452841s","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-03-23T05:23:15.615124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:23:14.991999Z","time spent":"623.117347ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-23T05:23:15.615157Z","caller":"traceutil/trace.go:171","msg":"trace[739127852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:681; }","duration":"2.168622045s","start":"2026-03-23T05:23:13.446515Z","end":"2026-03-23T05:23:15.615137Z","steps":["trace[739127852] 'agreement among raft nodes before linearized reading' (duration: 2.168328858s)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:23:15.615181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.339724ms","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-03-23T05:23:15.615203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:23:13.446498Z","time spent":"2.168695476s","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-23T05:23:15.615222Z","caller":"traceutil/trace.go:171","msg":"trace[723517843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:681; }","duration":"626.410466ms","start":"2026-03-23T05:23:14.988798Z","end":"2026-03-23T05:23:15.615208Z","steps":["trace[723517843] 'agreement among raft nodes before linearized reading' (duration: 626.147899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:23:15.615256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:23:14.988779Z","time spent":"626.469427ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-23T05:23:15.615332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.628251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:23:15.615352Z","caller":"traceutil/trace.go:171","msg":"trace[757075843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"693.688012ms","start":"2026-03-23T05:23:14.921657Z","end":"2026-03-23T05:23:15.615345Z","steps":["trace[757075843] 'agreement among raft nodes before linearized reading' (duration: 693.659821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:23:15.61537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:23:14.921636Z","time spent":"693.731403ms","remote":"127.0.0.1:38980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-23T05:23:15.615095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46051481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-23T05:23:15.615452Z","caller":"traceutil/trace.go:171","msg":"trace[1995156791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:681; }","duration":"1.460888829s","start":"2026-03-23T05:23:14.154535Z","end":"2026-03-23T05:23:15.615423Z","steps":["trace[1995156791] 'agreement among raft nodes before linearized reading' (duration: 1.460396607s)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:23:15.615544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:23:14.15452Z","time spent":"1.460991611s","remote":"127.0.0.1:39266","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-03-23T05:23:15.830276Z","caller":"traceutil/trace.go:171","msg":"trace[682272860] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"211.765256ms","start":"2026-03-23T05:23:15.618485Z","end":"2026-03-23T05:23:15.83025Z","steps":["trace[682272860] 'process raft request' (duration: 211.593822ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:23:15.830524Z","caller":"traceutil/trace.go:171","msg":"trace[1032566182] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"211.982821ms","start":"2026-03-23T05:23:15.61853Z","end":"2026-03-23T05:23:15.830513Z","steps":["trace[1032566182] 'process raft request' (duration: 211.684824ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:23:15.830637Z","caller":"traceutil/trace.go:171","msg":"trace[1551565538] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"210.359912ms","start":"2026-03-23T05:23:15.620256Z","end":"2026-03-23T05:23:15.830616Z","steps":["trace[1551565538] 'process raft request' (duration: 210.209319ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:23:38.671676Z","caller":"traceutil/trace.go:171","msg":"trace[1563543972] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"158.665964ms","start":"2026-03-23T05:23:38.512981Z","end":"2026-03-23T05:23:38.671647Z","steps":["trace[1563543972] 'read index received' (duration: 158.416518ms)","trace[1563543972] 'applied index is now lower than readState.Index' (duration: 248.646µs)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:23:38.671729Z","caller":"traceutil/trace.go:171","msg":"trace[1516898931] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"176.860023ms","start":"2026-03-23T05:23:38.494843Z","end":"2026-03-23T05:23:38.671703Z","steps":["trace[1516898931] 'process raft request' (duration: 176.612157ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:23:38.671947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.940781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.228\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-23T05:23:38.672038Z","caller":"traceutil/trace.go:171","msg":"trace[919508804] range","detail":"{range_begin:/registry/masterleases/199.19.213.228; range_end:; response_count:1; response_revision:754; }","duration":"159.069804ms","start":"2026-03-23T05:23:38.512945Z","end":"2026-03-23T05:23:38.672015Z","steps":["trace[919508804] 'agreement among raft nodes before linearized reading' (duration: 158.815078ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:23:53.374702Z","caller":"traceutil/trace.go:171","msg":"trace[958826740] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"322.716068ms","start":"2026-03-23T05:23:53.05196Z","end":"2026-03-23T05:23:53.374676Z","steps":["trace[958826740] 'process raft request' (duration: 322.569935ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:23:53.374865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:23:53.051946Z","time spent":"322.840321ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:23:59.555812Z","caller":"traceutil/trace.go:171","msg":"trace[1221804462] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"115.680238ms","start":"2026-03-23T05:23:59.440113Z","end":"2026-03-23T05:23:59.555793Z","steps":["trace[1221804462] 'process raft request' (duration: 115.508514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:08.708684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.300182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-23T05:24:08.708741Z","caller":"traceutil/trace.go:171","msg":"trace[1069962733] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:893; }","duration":"174.367454ms","start":"2026-03-23T05:24:08.534363Z","end":"2026-03-23T05:24:08.70873Z","steps":["trace[1069962733] 'range keys from in-memory index tree' (duration: 174.241791ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:11.041418Z","caller":"traceutil/trace.go:171","msg":"trace[1918481257] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"112.114512ms","start":"2026-03-23T05:24:10.929285Z","end":"2026-03-23T05:24:11.0414Z","steps":["trace[1918481257] 'process raft request' (duration: 111.947008ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:11.041687Z","caller":"traceutil/trace.go:171","msg":"trace[72669433] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"108.254639ms","start":"2026-03-23T05:24:10.933422Z","end":"2026-03-23T05:24:11.041677Z","steps":["trace[72669433] 'process raft request' (duration: 107.920601ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:11.944608Z","caller":"traceutil/trace.go:171","msg":"trace[1837641040] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"339.964805ms","start":"2026-03-23T05:24:11.604596Z","end":"2026-03-23T05:24:11.944561Z","steps":["trace[1837641040] 'process raft request' (duration: 339.72846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:11.944757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:24:11.604575Z","time spent":"340.095258ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:24:12.339816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.589957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-23T05:24:12.339869Z","caller":"traceutil/trace.go:171","msg":"trace[1284579463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:920; }","duration":"103.685119ms","start":"2026-03-23T05:24:12.236172Z","end":"2026-03-23T05:24:12.339857Z","steps":["trace[1284579463] 'range keys from in-memory index tree' (duration: 103.453414ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:12.340126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.749985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-23T05:24:12.340183Z","caller":"traceutil/trace.go:171","msg":"trace[1490503879] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:920; }","duration":"138.836277ms","start":"2026-03-23T05:24:12.201328Z","end":"2026-03-23T05:24:12.340165Z","steps":["trace[1490503879] 'range keys from in-memory index tree' (duration: 138.224233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:12.623216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.651637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:24:12.623438Z","caller":"traceutil/trace.go:171","msg":"trace[1990724559] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"275.836379ms","start":"2026-03-23T05:24:12.347586Z","end":"2026-03-23T05:24:12.623422Z","steps":["trace[1990724559] 'process raft request' (duration: 275.795318ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:12.623601Z","caller":"traceutil/trace.go:171","msg":"trace[1968629799] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"277.500499ms","start":"2026-03-23T05:24:12.346075Z","end":"2026-03-23T05:24:12.623576Z","steps":["trace[1968629799] 'process raft request' (duration: 176.406993ms)","trace[1968629799] 'compare' (duration: 100.465201ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:24:12.623653Z","caller":"traceutil/trace.go:171","msg":"trace[1923189860] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"277.156891ms","start":"2026-03-23T05:24:12.346452Z","end":"2026-03-23T05:24:12.623609Z","steps":["trace[1923189860] 'process raft request' (duration: 276.874124ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:13.293615Z","caller":"traceutil/trace.go:171","msg":"trace[76331541] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"184.531959ms","start":"2026-03-23T05:24:13.109065Z","end":"2026-03-23T05:24:13.293597Z","steps":["trace[76331541] 'process raft request' (duration: 184.431926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:13.906436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.46037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-23T05:24:13.906523Z","caller":"traceutil/trace.go:171","msg":"trace[1939115555] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:926; }","duration":"197.591453ms","start":"2026-03-23T05:24:13.708908Z","end":"2026-03-23T05:24:13.906499Z","steps":["trace[1939115555] 'count revisions from in-memory index tree' (duration: 197.324407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:14.151856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.633623ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-23T05:24:14.151959Z","caller":"traceutil/trace.go:171","msg":"trace[1799236641] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"229.747238ms","start":"2026-03-23T05:24:13.922193Z","end":"2026-03-23T05:24:14.15194Z","steps":["trace[1799236641] 'read index received' (duration: 58.062µs)","trace[1799236641] 'applied index is now lower than readState.Index' (duration: 229.687396ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:24:14.152075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.883461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:24:14.152109Z","caller":"traceutil/trace.go:171","msg":"trace[1806376199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"229.967023ms","start":"2026-03-23T05:24:13.922132Z","end":"2026-03-23T05:24:14.152099Z","steps":["trace[1806376199] 'agreement among raft nodes before linearized reading' (duration: 229.888581ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:14.179865Z","caller":"traceutil/trace.go:171","msg":"trace[1024718522] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"123.871986ms","start":"2026-03-23T05:24:14.055968Z","end":"2026-03-23T05:24:14.179833Z","steps":["trace[1024718522] 'process raft request' (duration: 123.676241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:14.180015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.861236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-23T05:24:14.18006Z","caller":"traceutil/trace.go:171","msg":"trace[1884031651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"231.111031ms","start":"2026-03-23T05:24:13.948937Z","end":"2026-03-23T05:24:14.180048Z","steps":["trace[1884031651] 'agreement among raft nodes before linearized reading' (duration: 230.884205ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:14.311491Z","caller":"traceutil/trace.go:171","msg":"trace[581637471] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"115.911184ms","start":"2026-03-23T05:24:14.195548Z","end":"2026-03-23T05:24:14.311459Z","steps":["trace[581637471] 'process raft request' (duration: 94.506828ms)","trace[581637471] 'compare' (duration: 21.110339ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:24:14.540367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.185444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:24:14.540623Z","caller":"traceutil/trace.go:171","msg":"trace[499958051] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"183.619277ms","start":"2026-03-23T05:24:14.356964Z","end":"2026-03-23T05:24:14.540584Z","steps":["trace[499958051] 'process raft request' (duration: 76.130075ms)","trace[499958051] 'compare' (duration: 106.910988ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:24:14.540707Z","caller":"traceutil/trace.go:171","msg":"trace[755022257] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"179.490716ms","start":"2026-03-23T05:24:14.361192Z","end":"2026-03-23T05:24:14.540682Z","steps":["trace[755022257] 'process raft request' (duration: 179.333352ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:14.540847Z","caller":"traceutil/trace.go:171","msg":"trace[367080361] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:965; }","duration":"147.606928ms","start":"2026-03-23T05:24:14.393216Z","end":"2026-03-23T05:24:14.540823Z","steps":["trace[367080361] 'read index received' (duration: 39.905702ms)","trace[367080361] 'applied index is now lower than readState.Index' (duration: 107.699846ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:24:14.541004Z","caller":"traceutil/trace.go:171","msg":"trace[1470591696] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"156.686287ms","start":"2026-03-23T05:24:14.384289Z","end":"2026-03-23T05:24:14.540975Z","steps":["trace[1470591696] 'process raft request' (duration: 156.338209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:14.541058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.827973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-23T05:24:14.541233Z","caller":"traceutil/trace.go:171","msg":"trace[2061073604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:932; }","duration":"148.030349ms","start":"2026-03-23T05:24:14.393171Z","end":"2026-03-23T05:24:14.541202Z","steps":["trace[2061073604] 'agreement among raft nodes before linearized reading' (duration: 147.725701ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:14.706734Z","caller":"traceutil/trace.go:171","msg":"trace[347466289] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"159.067354ms","start":"2026-03-23T05:24:14.54763Z","end":"2026-03-23T05:24:14.706698Z","steps":["trace[347466289] 'process raft request' (duration: 99.912708ms)","trace[347466289] 'compare' (duration: 58.991272ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:24:16.474496Z","caller":"traceutil/trace.go:171","msg":"trace[20450318] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"154.096575ms","start":"2026-03-23T05:24:16.320381Z","end":"2026-03-23T05:24:16.474478Z","steps":["trace[20450318] 'process raft request' (duration: 153.979762ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:17.071575Z","caller":"traceutil/trace.go:171","msg":"trace[1304218757] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"292.514622ms","start":"2026-03-23T05:24:16.779042Z","end":"2026-03-23T05:24:17.071556Z","steps":["trace[1304218757] 'process raft request' (duration: 292.402879ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:17.467625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.079176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:24:17.468043Z","caller":"traceutil/trace.go:171","msg":"trace[2040335115] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"688.316432ms","start":"2026-03-23T05:24:16.779701Z","end":"2026-03-23T05:24:17.468017Z","steps":["trace[2040335115] 'process raft request' (duration: 688.074287ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:17.4682Z","caller":"traceutil/trace.go:171","msg":"trace[951302034] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"688.973758ms","start":"2026-03-23T05:24:16.779204Z","end":"2026-03-23T05:24:17.468178Z","steps":["trace[951302034] 'process raft request' (duration: 429.205416ms)","trace[951302034] 'compare' (duration: 258.897391ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:24:17.468176Z","caller":"traceutil/trace.go:171","msg":"trace[1763845527] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"688.366353ms","start":"2026-03-23T05:24:16.779773Z","end":"2026-03-23T05:24:17.468139Z","steps":["trace[1763845527] 'process raft request' (duration: 688.074406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:17.468302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:24:16.779167Z","time spent":"689.098411ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:24:17.468215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:24:16.779688Z","time spent":"688.423915ms","remote":"127.0.0.1:39266","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-03-23T05:24:17.468616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:24:16.779762Z","time spent":"688.688961ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:24:24.113001Z","caller":"traceutil/trace.go:171","msg":"trace[1465255660] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"612.384702ms","start":"2026-03-23T05:24:23.500593Z","end":"2026-03-23T05:24:24.112978Z","steps":["trace[1465255660] 'process raft request' (duration: 609.407461ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:24.113056Z","caller":"traceutil/trace.go:171","msg":"trace[2131613861] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"534.622048ms","start":"2026-03-23T05:24:23.578399Z","end":"2026-03-23T05:24:24.113021Z","steps":["trace[2131613861] 'process raft request' (duration: 534.359661ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:24.113076Z","caller":"traceutil/trace.go:171","msg":"trace[561274937] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"609.748639ms","start":"2026-03-23T05:24:23.503276Z","end":"2026-03-23T05:24:24.113025Z","steps":["trace[561274937] 'process raft request' (duration: 609.3837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:24.113157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:24:23.500577Z","time spent":"612.486455ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:24:24.113328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:24:23.503254Z","time spent":"609.933793ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:24:24.113297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:24:23.578374Z","time spent":"534.795252ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:24:24.114775Z","caller":"traceutil/trace.go:171","msg":"trace[83048055] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1027; }","duration":"189.104838ms","start":"2026-03-23T05:24:23.925647Z","end":"2026-03-23T05:24:24.114752Z","steps":["trace[83048055] 'read index received' (duration: 184.368004ms)","trace[83048055] 'applied index is now lower than readState.Index' (duration: 4.735814ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:24:24.114861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.2064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:24:24.114948Z","caller":"traceutil/trace.go:171","msg":"trace[1909673485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:993; }","duration":"189.334394ms","start":"2026-03-23T05:24:23.925599Z","end":"2026-03-23T05:24:24.114933Z","steps":["trace[1909673485] 'agreement among raft nodes before linearized reading' (duration: 189.224341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:33.760069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.228294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:26:33.760247Z","caller":"traceutil/trace.go:171","msg":"trace[137575714] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"192.495465ms","start":"2026-03-23T05:26:33.567724Z","end":"2026-03-23T05:26:33.76022Z","steps":["trace[137575714] 'process raft request' (duration: 65.901712ms)","trace[137575714] 'compare' (duration: 126.04505ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:26:34.658753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.07516ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-23T05:26:34.658873Z","caller":"traceutil/trace.go:171","msg":"trace[650099456] linearizableReadLoop","detail":"{readStateIndex:1586; appliedIndex:1585; }","duration":"691.225423ms","start":"2026-03-23T05:26:33.967626Z","end":"2026-03-23T05:26:34.658852Z","steps":["trace[650099456] 'read index received' (duration: 113.021321ms)","trace[650099456] 'applied index is now lower than readState.Index' (duration: 578.202602ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:26:34.659251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.615582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:26:34.659309Z","caller":"traceutil/trace.go:171","msg":"trace[1824216336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1522; }","duration":"691.718445ms","start":"2026-03-23T05:26:33.967576Z","end":"2026-03-23T05:26:34.659295Z","steps":["trace[1824216336] 'agreement among raft nodes before linearized reading' (duration: 691.622962ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:34.659269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.803966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-23T05:26:34.65935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:26:33.967555Z","time spent":"691.786076ms","remote":"127.0.0.1:38968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-23T05:26:34.659252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.731492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-23T05:26:34.659399Z","caller":"traceutil/trace.go:171","msg":"trace[1598063472] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1522; }","duration":"596.923247ms","start":"2026-03-23T05:26:34.062457Z","end":"2026-03-23T05:26:34.65938Z","steps":["trace[1598063472] 'agreement among raft nodes before linearized reading' (duration: 596.662801ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:34.659396Z","caller":"traceutil/trace.go:171","msg":"trace[1485706432] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1522; }","duration":"201.99158ms","start":"2026-03-23T05:26:34.457372Z","end":"2026-03-23T05:26:34.659363Z","steps":["trace[1485706432] 'agreement among raft nodes before linearized reading' (duration: 201.762325ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:34.659437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:26:34.062425Z","time spent":"597.002798ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-23T05:26:34.659484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.681062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-23T05:26:34.659584Z","caller":"traceutil/trace.go:171","msg":"trace[1763191235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1522; }","duration":"257.821975ms","start":"2026-03-23T05:26:34.401737Z","end":"2026-03-23T05:26:34.659559Z","steps":["trace[1763191235] 'agreement among raft nodes before linearized reading' (duration: 257.507117ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:34.993157Z","caller":"traceutil/trace.go:171","msg":"trace[334750076] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"329.382562ms","start":"2026-03-23T05:26:34.663743Z","end":"2026-03-23T05:26:34.993125Z","steps":["trace[334750076] 'process raft request' (duration: 329.165547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:34.99332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:26:34.663726Z","time spent":"329.511305ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:26:35.155131Z","caller":"traceutil/trace.go:171","msg":"trace[684506058] linearizableReadLoop","detail":"{readStateIndex:1590; appliedIndex:1588; }","duration":"143.520004ms","start":"2026-03-23T05:26:35.011592Z","end":"2026-03-23T05:26:35.155112Z","steps":["trace[684506058] 'read index received' (duration: 62.457012ms)","trace[684506058] 'applied index is now lower than readState.Index' (duration: 81.062432ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:35.155186Z","caller":"traceutil/trace.go:171","msg":"trace[279596829] transaction","detail":"{read_only:false; response_revision:1525; number_of_response:1; }","duration":"155.794405ms","start":"2026-03-23T05:26:34.999361Z","end":"2026-03-23T05:26:35.155156Z","steps":["trace[279596829] 'process raft request' (duration: 74.533078ms)","trace[279596829] 'compare' (duration: 81.106994ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:35.155219Z","caller":"traceutil/trace.go:171","msg":"trace[1022367087] transaction","detail":"{read_only:false; response_revision:1526; number_of_response:1; }","duration":"154.256468ms","start":"2026-03-23T05:26:35.000955Z","end":"2026-03-23T05:26:35.155212Z","steps":["trace[1022367087] 'process raft request' (duration: 154.134366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:35.155321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.709719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-23T05:26:35.155351Z","caller":"traceutil/trace.go:171","msg":"trace[555966521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1526; }","duration":"143.778621ms","start":"2026-03-23T05:26:35.011567Z","end":"2026-03-23T05:26:35.155345Z","steps":["trace[555966521] 'agreement among raft nodes before linearized reading' (duration: 143.670748ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:35.874612Z","caller":"traceutil/trace.go:171","msg":"trace[1382623307] transaction","detail":"{read_only:false; response_revision:1530; number_of_response:1; }","duration":"106.107657ms","start":"2026-03-23T05:26:35.768469Z","end":"2026-03-23T05:26:35.874577Z","steps":["trace[1382623307] 'process raft request' (duration: 105.966794ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:36.650425Z","caller":"traceutil/trace.go:171","msg":"trace[467926168] transaction","detail":"{read_only:false; response_revision:1532; number_of_response:1; }","duration":"115.245923ms","start":"2026-03-23T05:26:36.535147Z","end":"2026-03-23T05:26:36.650393Z","steps":["trace[467926168] 'process raft request' (duration: 22.507254ms)","trace[467926168] 'compare' (duration: 92.636997ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:26:36.977792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.413613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:26:36.978008Z","caller":"traceutil/trace.go:171","msg":"trace[1919771779] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"240.695969ms","start":"2026-03-23T05:26:36.737291Z","end":"2026-03-23T05:26:36.977987Z","steps":["trace[1919771779] 'process raft request' (duration: 82.967938ms)","trace[1919771779] 'compare' (duration: 157.287ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:36.978028Z","caller":"traceutil/trace.go:171","msg":"trace[1385012999] linearizableReadLoop","detail":"{readStateIndex:1597; appliedIndex:1596; }","duration":"108.511144ms","start":"2026-03-23T05:26:36.869471Z","end":"2026-03-23T05:26:36.977982Z","steps":["trace[1385012999] 'read index received' (duration: 70.512µs)","trace[1385012999] 'applied index is now lower than readState.Index' (duration: 108.439142ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:26:36.978187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.679058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:26:36.978266Z","caller":"traceutil/trace.go:171","msg":"trace[1020845116] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1533; }","duration":"108.78189ms","start":"2026-03-23T05:26:36.869436Z","end":"2026-03-23T05:26:36.978218Z","steps":["trace[1020845116] 'agreement among raft nodes before linearized reading' (duration: 108.679328ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:37.287729Z","caller":"traceutil/trace.go:171","msg":"trace[2087706168] transaction","detail":"{read_only:false; response_revision:1535; number_of_response:1; }","duration":"124.318849ms","start":"2026-03-23T05:26:37.163381Z","end":"2026-03-23T05:26:37.2877Z","steps":["trace[2087706168] 'process raft request' (duration: 123.809946ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:39.141145Z","caller":"traceutil/trace.go:171","msg":"trace[1880731906] linearizableReadLoop","detail":"{readStateIndex:1606; appliedIndex:1605; }","duration":"105.902591ms","start":"2026-03-23T05:26:39.035218Z","end":"2026-03-23T05:26:39.141121Z","steps":["trace[1880731906] 'read index received' (duration: 105.655106ms)","trace[1880731906] 'applied index is now lower than readState.Index' (duration: 246.115µs)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:26:39.141599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.352502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-23T05:26:39.141661Z","caller":"traceutil/trace.go:171","msg":"trace[2081110103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1541; }","duration":"106.466225ms","start":"2026-03-23T05:26:39.03518Z","end":"2026-03-23T05:26:39.141646Z","steps":["trace[2081110103] 'agreement among raft nodes before linearized reading' (duration: 106.038755ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:39.141978Z","caller":"traceutil/trace.go:171","msg":"trace[1680068619] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"156.259666ms","start":"2026-03-23T05:26:38.985706Z","end":"2026-03-23T05:26:39.141966Z","steps":["trace[1680068619] 'process raft request' (duration: 155.225281ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:39.397581Z","caller":"traceutil/trace.go:171","msg":"trace[101950402] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"100.946354ms","start":"2026-03-23T05:26:39.296592Z","end":"2026-03-23T05:26:39.397538Z","steps":["trace[101950402] 'process raft request' (duration: 100.668718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:39.668325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.551969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:26:39.668429Z","caller":"traceutil/trace.go:171","msg":"trace[2062858707] linearizableReadLoop","detail":"{readStateIndex:1609; appliedIndex:1608; }","duration":"221.139865ms","start":"2026-03-23T05:26:39.447272Z","end":"2026-03-23T05:26:39.668412Z","steps":["trace[2062858707] 'read index received' (duration: 65.461903ms)","trace[2062858707] 'applied index is now lower than readState.Index' (duration: 155.676952ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:39.668543Z","caller":"traceutil/trace.go:171","msg":"trace[1403904000] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"266.723245ms","start":"2026-03-23T05:26:39.401808Z","end":"2026-03-23T05:26:39.668531Z","steps":["trace[1403904000] 'process raft request' (duration: 110.874189ms)","trace[1403904000] 'compare' (duration: 155.330114ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:26:39.668937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.654868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:26:39.668981Z","caller":"traceutil/trace.go:171","msg":"trace[838502906] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1544; }","duration":"221.741189ms","start":"2026-03-23T05:26:39.447228Z","end":"2026-03-23T05:26:39.668969Z","steps":["trace[838502906] 'agreement among raft nodes before linearized reading' (duration: 221.671157ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:41.854049Z","caller":"traceutil/trace.go:171","msg":"trace[1612410328] transaction","detail":"{read_only:false; response_revision:1553; number_of_response:1; }","duration":"113.575313ms","start":"2026-03-23T05:26:41.740445Z","end":"2026-03-23T05:26:41.854021Z","steps":["trace[1612410328] 'process raft request' (duration: 113.399179ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:41.916608Z","caller":"traceutil/trace.go:171","msg":"trace[2100934061] transaction","detail":"{read_only:false; response_revision:1554; number_of_response:1; }","duration":"175.277387ms","start":"2026-03-23T05:26:41.741308Z","end":"2026-03-23T05:26:41.916585Z","steps":["trace[2100934061] 'process raft request' (duration: 175.162675ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:43.387303Z","caller":"traceutil/trace.go:171","msg":"trace[1167865926] linearizableReadLoop","detail":"{readStateIndex:1623; appliedIndex:1622; }","duration":"124.158395ms","start":"2026-03-23T05:26:43.263116Z","end":"2026-03-23T05:26:43.387275Z","steps":["trace[1167865926] 'read index received' (duration: 123.984291ms)","trace[1167865926] 'applied index is now lower than readState.Index' (duration: 172.854µs)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:43.387571Z","caller":"traceutil/trace.go:171","msg":"trace[1168221908] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"191.393269ms","start":"2026-03-23T05:26:43.196145Z","end":"2026-03-23T05:26:43.387538Z","steps":["trace[1168221908] 'process raft request' (duration: 190.958599ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:43.388539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.412554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-23T05:26:43.388633Z","caller":"traceutil/trace.go:171","msg":"trace[1093652874] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1558; }","duration":"125.524367ms","start":"2026-03-23T05:26:43.263053Z","end":"2026-03-23T05:26:43.388578Z","steps":["trace[1093652874] 'agreement among raft nodes before linearized reading' (duration: 124.332259ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:43.855477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.393429ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-23T05:26:44.213665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.596866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:26:44.213745Z","caller":"traceutil/trace.go:171","msg":"trace[378218098] linearizableReadLoop","detail":"{readStateIndex:1628; appliedIndex:1627; }","duration":"129.626805ms","start":"2026-03-23T05:26:44.084105Z","end":"2026-03-23T05:26:44.213732Z","steps":["trace[378218098] 'read index received' (duration: 30.241µs)","trace[378218098] 'applied index is now lower than readState.Index' (duration: 129.595864ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:26:44.213826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.724327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-23T05:26:44.213844Z","caller":"traceutil/trace.go:171","msg":"trace[1355891230] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1562; }","duration":"129.743107ms","start":"2026-03-23T05:26:44.084096Z","end":"2026-03-23T05:26:44.213839Z","steps":["trace[1355891230] 'agreement among raft nodes before linearized reading' (duration: 129.665235ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:44.213877Z","caller":"traceutil/trace.go:171","msg":"trace[124492349] transaction","detail":"{read_only:false; response_revision:1562; number_of_response:1; }","duration":"286.685869ms","start":"2026-03-23T05:26:43.927163Z","end":"2026-03-23T05:26:44.213849Z","steps":["trace[124492349] 'process raft request' (duration: 80.836797ms)","trace[124492349] 'compare' (duration: 205.522185ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:45.396345Z","caller":"traceutil/trace.go:171","msg":"trace[359316966] linearizableReadLoop","detail":"{readStateIndex:1630; appliedIndex:1629; }","duration":"119.552025ms","start":"2026-03-23T05:26:45.276773Z","end":"2026-03-23T05:26:45.396325Z","steps":["trace[359316966] 'read index received' (duration: 119.381131ms)","trace[359316966] 'applied index is now lower than readState.Index' (duration: 170.064µs)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:45.396787Z","caller":"traceutil/trace.go:171","msg":"trace[133470156] transaction","detail":"{read_only:false; response_revision:1564; number_of_response:1; }","duration":"155.20775ms","start":"2026-03-23T05:26:45.24154Z","end":"2026-03-23T05:26:45.396747Z","steps":["trace[133470156] 'process raft request' (duration: 154.667938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:45.396958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.17544ms","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:508"} {"level":"info","ts":"2026-03-23T05:26:45.397004Z","caller":"traceutil/trace.go:171","msg":"trace[510682389] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1564; }","duration":"120.270072ms","start":"2026-03-23T05:26:45.276719Z","end":"2026-03-23T05:26:45.396989Z","steps":["trace[510682389] 'agreement among raft nodes before linearized reading' (duration: 120.16056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:45.599545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.09273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:26:45.599712Z","caller":"traceutil/trace.go:171","msg":"trace[2057607910] transaction","detail":"{read_only:false; response_revision:1567; number_of_response:1; }","duration":"150.11711ms","start":"2026-03-23T05:26:45.449562Z","end":"2026-03-23T05:26:45.599679Z","steps":["trace[2057607910] 'process raft request' (duration: 34.808556ms)","trace[2057607910] 'compare' (duration: 114.968496ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:46.347757Z","caller":"traceutil/trace.go:171","msg":"trace[429173318] transaction","detail":"{read_only:false; response_revision:1570; number_of_response:1; }","duration":"125.841654ms","start":"2026-03-23T05:26:46.221887Z","end":"2026-03-23T05:26:46.347729Z","steps":["trace[429173318] 'process raft request' (duration: 125.667ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:46.455651Z","caller":"traceutil/trace.go:171","msg":"trace[907379310] transaction","detail":"{read_only:false; response_revision:1571; number_of_response:1; }","duration":"103.615478ms","start":"2026-03-23T05:26:46.35199Z","end":"2026-03-23T05:26:46.455606Z","steps":["trace[907379310] 'process raft request' (duration: 60.68326ms)","trace[907379310] 'compare' (duration: 42.709533ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:47.778191Z","caller":"traceutil/trace.go:171","msg":"trace[2027659135] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"107.229363ms","start":"2026-03-23T05:26:47.670927Z","end":"2026-03-23T05:26:47.778157Z","steps":["trace[2027659135] 'process raft request' (duration: 38.35881ms)","trace[2027659135] 'compare' (duration: 68.75036ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:49.618202Z","caller":"traceutil/trace.go:171","msg":"trace[1673246763] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"127.837113ms","start":"2026-03-23T05:26:49.490313Z","end":"2026-03-23T05:26:49.61815Z","steps":["trace[1673246763] 'process raft request' (duration: 127.684189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:49.977446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.921247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:26:49.977678Z","caller":"traceutil/trace.go:171","msg":"trace[373103681] transaction","detail":"{read_only:false; response_revision:1584; number_of_response:1; }","duration":"190.987046ms","start":"2026-03-23T05:26:49.78666Z","end":"2026-03-23T05:26:49.977647Z","steps":["trace[373103681] 'process raft request' (duration: 78.768342ms)","trace[373103681] 'compare' (duration: 111.799914ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:50.727488Z","caller":"traceutil/trace.go:171","msg":"trace[1442289494] transaction","detail":"{read_only:false; response_revision:1587; number_of_response:1; }","duration":"255.43548ms","start":"2026-03-23T05:26:50.472027Z","end":"2026-03-23T05:26:50.727463Z","steps":["trace[1442289494] 'process raft request' (duration: 255.314327ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:50.91848Z","caller":"traceutil/trace.go:171","msg":"trace[1681127875] linearizableReadLoop","detail":"{readStateIndex:1655; appliedIndex:1653; }","duration":"424.916598ms","start":"2026-03-23T05:26:50.493531Z","end":"2026-03-23T05:26:50.918447Z","steps":["trace[1681127875] 'read index received' (duration: 233.813549ms)","trace[1681127875] 'applied index is now lower than readState.Index' (duration: 191.102009ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:50.918839Z","caller":"traceutil/trace.go:171","msg":"trace[848115866] transaction","detail":"{read_only:false; response_revision:1588; number_of_response:1; }","duration":"446.614341ms","start":"2026-03-23T05:26:50.472203Z","end":"2026-03-23T05:26:50.918817Z","steps":["trace[848115866] 'process raft request' (duration: 356.548181ms)","trace[848115866] 'compare' (duration: 89.607489ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:26:50.919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:26:50.472182Z","time spent":"446.710253ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:26:50.919487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.487271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363686"} {"level":"warn","ts":"2026-03-23T05:26:50.919576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.040394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-23T05:26:50.919631Z","caller":"traceutil/trace.go:171","msg":"trace[163248413] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1588; }","duration":"222.622224ms","start":"2026-03-23T05:26:50.696928Z","end":"2026-03-23T05:26:50.91955Z","steps":["trace[163248413] 'agreement among raft nodes before linearized reading' (duration: 222.097082ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:50.919639Z","caller":"traceutil/trace.go:171","msg":"trace[293716230] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1588; }","duration":"426.132087ms","start":"2026-03-23T05:26:50.493489Z","end":"2026-03-23T05:26:50.919621Z","steps":["trace[293716230] 'agreement among raft nodes before linearized reading' (duration: 425.959963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:50.919687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:26:50.49347Z","time spent":"426.205388ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-23T05:26:51.578496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.48793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:26:51.578609Z","caller":"traceutil/trace.go:171","msg":"trace[1462744780] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1589; }","duration":"260.696655ms","start":"2026-03-23T05:26:51.317891Z","end":"2026-03-23T05:26:51.578588Z","steps":["trace[1462744780] 'count revisions from in-memory index tree' (duration: 260.348646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:52.105103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.149603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:26:52.105236Z","caller":"traceutil/trace.go:171","msg":"trace[548692451] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"341.500246ms","start":"2026-03-23T05:26:51.76372Z","end":"2026-03-23T05:26:52.10522Z","steps":["trace[548692451] 'process raft request' (duration: 97.130237ms)","trace[548692451] 'compare' (duration: 244.034561ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:52.105248Z","caller":"traceutil/trace.go:171","msg":"trace[1013355094] linearizableReadLoop","detail":"{readStateIndex:1659; appliedIndex:1658; }","duration":"181.4299ms","start":"2026-03-23T05:26:51.923777Z","end":"2026-03-23T05:26:52.105207Z","steps":["trace[1013355094] 'read index received' (duration: 30.481µs)","trace[1013355094] 'applied index is now lower than readState.Index' (duration: 181.397409ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:26:52.105301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:26:51.763699Z","time spent":"341.569617ms","remote":"127.0.0.1:39266","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-03-23T05:26:52.105387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.593634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:26:52.10546Z","caller":"traceutil/trace.go:171","msg":"trace[691522378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1592; }","duration":"181.700776ms","start":"2026-03-23T05:26:51.923743Z","end":"2026-03-23T05:26:52.105444Z","steps":["trace[691522378] 'agreement among raft nodes before linearized reading' (duration: 181.548192ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:52.105483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.072773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-23T05:26:52.105539Z","caller":"traceutil/trace.go:171","msg":"trace[1150612382] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1592; }","duration":"124.149556ms","start":"2026-03-23T05:26:51.981371Z","end":"2026-03-23T05:26:52.105521Z","steps":["trace[1150612382] 'agreement among raft nodes before linearized reading' (duration: 123.983852ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:52.320853Z","caller":"traceutil/trace.go:171","msg":"trace[1567916398] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"211.740926ms","start":"2026-03-23T05:26:52.109077Z","end":"2026-03-23T05:26:52.320817Z","steps":["trace[1567916398] 'process raft request' (duration: 140.795969ms)","trace[1567916398] 'compare' (duration: 70.604469ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:52.320937Z","caller":"traceutil/trace.go:171","msg":"trace[1788250309] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"210.189039ms","start":"2026-03-23T05:26:52.110727Z","end":"2026-03-23T05:26:52.320916Z","steps":["trace[1788250309] 'process raft request' (duration: 210.040586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:53.256104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.160413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:26:53.256236Z","caller":"traceutil/trace.go:171","msg":"trace[671164975] transaction","detail":"{read_only:false; response_revision:1597; number_of_response:1; }","duration":"229.452045ms","start":"2026-03-23T05:26:53.026759Z","end":"2026-03-23T05:26:53.256211Z","steps":["trace[671164975] 'process raft request' (duration: 18.081147ms)","trace[671164975] 'compare' (duration: 211.01511ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:53.535789Z","caller":"traceutil/trace.go:171","msg":"trace[2146760923] transaction","detail":"{read_only:false; response_revision:1598; number_of_response:1; }","duration":"270.541197ms","start":"2026-03-23T05:26:53.265214Z","end":"2026-03-23T05:26:53.535756Z","steps":["trace[2146760923] 'process raft request' (duration: 202.113489ms)","trace[2146760923] 'compare' (duration: 68.09366ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:26:53.847312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.973956ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-23T05:26:53.847413Z","caller":"traceutil/trace.go:171","msg":"trace[822295993] linearizableReadLoop","detail":"{readStateIndex:1666; appliedIndex:1665; }","duration":"151.310118ms","start":"2026-03-23T05:26:53.696088Z","end":"2026-03-23T05:26:53.847398Z","steps":["trace[822295993] 'read index received' (duration: 45.097296ms)","trace[822295993] 'applied index is now lower than readState.Index' (duration: 106.211672ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:26:53.847564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.469451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-23T05:26:53.847588Z","caller":"traceutil/trace.go:171","msg":"trace[1430734607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1598; }","duration":"151.505142ms","start":"2026-03-23T05:26:53.696077Z","end":"2026-03-23T05:26:53.847582Z","steps":["trace[1430734607] 'agreement among raft nodes before linearized reading' (duration: 151.357189ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:54.470815Z","caller":"traceutil/trace.go:171","msg":"trace[463285631] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"141.835554ms","start":"2026-03-23T05:26:54.328955Z","end":"2026-03-23T05:26:54.47079Z","steps":["trace[463285631] 'process raft request' (duration: 141.66733ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:54.513658Z","caller":"traceutil/trace.go:171","msg":"trace[1994364985] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"184.636836ms","start":"2026-03-23T05:26:54.328975Z","end":"2026-03-23T05:26:54.513612Z","steps":["trace[1994364985] 'process raft request' (duration: 184.517803ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:55.405333Z","caller":"traceutil/trace.go:171","msg":"trace[1224344797] linearizableReadLoop","detail":"{readStateIndex:1672; appliedIndex:1671; }","duration":"299.39138ms","start":"2026-03-23T05:26:55.105891Z","end":"2026-03-23T05:26:55.405282Z","steps":["trace[1224344797] 'read index received' (duration: 299.032881ms)","trace[1224344797] 'applied index is now lower than readState.Index' (duration: 356.558µs)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:55.405448Z","caller":"traceutil/trace.go:171","msg":"trace[1836724204] transaction","detail":"{read_only:false; response_revision:1604; number_of_response:1; }","duration":"349.359611ms","start":"2026-03-23T05:26:55.056038Z","end":"2026-03-23T05:26:55.405397Z","steps":["trace[1836724204] 'process raft request' (duration: 348.998073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:55.405525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.592704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:26:55.40558Z","caller":"traceutil/trace.go:171","msg":"trace[2001781801] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1604; }","duration":"299.720587ms","start":"2026-03-23T05:26:55.105841Z","end":"2026-03-23T05:26:55.405562Z","steps":["trace[2001781801] 'agreement among raft nodes before linearized reading' (duration: 299.575344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:55.405741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:26:55.056001Z","time spent":"349.598197ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:26:55.738284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.015469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:26:55.739073Z","caller":"traceutil/trace.go:171","msg":"trace[738302738] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"263.093321ms","start":"2026-03-23T05:26:55.475609Z","end":"2026-03-23T05:26:55.738702Z","steps":["trace[738302738] 'process raft request' (duration: 43.55327ms)","trace[738302738] 'compare' (duration: 218.872705ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:56.059068Z","caller":"traceutil/trace.go:171","msg":"trace[2051220567] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"109.07489ms","start":"2026-03-23T05:26:55.949966Z","end":"2026-03-23T05:26:56.059041Z","steps":["trace[2051220567] 'process raft request' (duration: 108.928036ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:56.578974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.083201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-23T05:26:56.579053Z","caller":"traceutil/trace.go:171","msg":"trace[1298183880] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1608; }","duration":"104.45707ms","start":"2026-03-23T05:26:56.474576Z","end":"2026-03-23T05:26:56.579033Z","steps":["trace[1298183880] 'range keys from in-memory index tree' (duration: 103.855796ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:56.905004Z","caller":"traceutil/trace.go:171","msg":"trace[1202981349] linearizableReadLoop","detail":"{readStateIndex:1678; appliedIndex:1677; }","duration":"158.507408ms","start":"2026-03-23T05:26:56.746466Z","end":"2026-03-23T05:26:56.904974Z","steps":["trace[1202981349] 'read index received' (duration: 158.294553ms)","trace[1202981349] 'applied index is now lower than readState.Index' (duration: 212.025µs)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:56.905075Z","caller":"traceutil/trace.go:171","msg":"trace[1778645795] transaction","detail":"{read_only:false; response_revision:1610; number_of_response:1; }","duration":"321.999024ms","start":"2026-03-23T05:26:56.583039Z","end":"2026-03-23T05:26:56.905038Z","steps":["trace[1778645795] 'process raft request' (duration: 321.792459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:56.905226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.748744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"warn","ts":"2026-03-23T05:26:56.905254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:26:56.583013Z","time spent":"322.141598ms","remote":"127.0.0.1:39266","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-03-23T05:26:56.90527Z","caller":"traceutil/trace.go:171","msg":"trace[1483487590] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1610; }","duration":"158.854536ms","start":"2026-03-23T05:26:56.746402Z","end":"2026-03-23T05:26:56.905257Z","steps":["trace[1483487590] 'agreement among raft nodes before linearized reading' (duration: 158.688872ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:57.085358Z","caller":"traceutil/trace.go:171","msg":"trace[2041023839] linearizableReadLoop","detail":"{readStateIndex:1679; appliedIndex:1678; }","duration":"163.409585ms","start":"2026-03-23T05:26:56.92192Z","end":"2026-03-23T05:26:57.085329Z","steps":["trace[2041023839] 'read index received' (duration: 100.843115ms)","trace[2041023839] 'applied index is now lower than readState.Index' (duration: 62.56541ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:26:57.085523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.571108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:26:57.085613Z","caller":"traceutil/trace.go:171","msg":"trace[1443332697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1611; }","duration":"163.682801ms","start":"2026-03-23T05:26:56.921914Z","end":"2026-03-23T05:26:57.085596Z","steps":["trace[1443332697] 'agreement among raft nodes before linearized reading' (duration: 163.514487ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:57.08608Z","caller":"traceutil/trace.go:171","msg":"trace[1119155541] transaction","detail":"{read_only:false; response_revision:1611; number_of_response:1; }","duration":"176.796791ms","start":"2026-03-23T05:26:56.909268Z","end":"2026-03-23T05:26:57.086065Z","steps":["trace[1119155541] 'process raft request' (duration: 113.541485ms)","trace[1119155541] 'compare' (duration: 62.416956ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:26:57.335101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.975809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:26:57.335183Z","caller":"traceutil/trace.go:171","msg":"trace[972680301] transaction","detail":"{read_only:false; response_revision:1612; number_of_response:1; }","duration":"244.139803ms","start":"2026-03-23T05:26:57.091029Z","end":"2026-03-23T05:26:57.335169Z","steps":["trace[972680301] 'process raft request' (duration: 96.030721ms)","trace[972680301] 'compare' (duration: 147.841876ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:26:57.598578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.46226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:26:57.598813Z","caller":"traceutil/trace.go:171","msg":"trace[1130297460] transaction","detail":"{read_only:false; response_revision:1614; number_of_response:1; }","duration":"150.863277ms","start":"2026-03-23T05:26:57.447931Z","end":"2026-03-23T05:26:57.598794Z","steps":["trace[1130297460] 'process raft request' (duration: 150.799005ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:57.59889Z","caller":"traceutil/trace.go:171","msg":"trace[1032435757] transaction","detail":"{read_only:false; response_revision:1613; number_of_response:1; }","duration":"184.447041ms","start":"2026-03-23T05:26:57.414415Z","end":"2026-03-23T05:26:57.598862Z","steps":["trace[1032435757] 'process raft request' (duration: 35.608562ms)","trace[1032435757] 'compare' (duration: 148.325147ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:58.391092Z","caller":"traceutil/trace.go:171","msg":"trace[438354068] transaction","detail":"{read_only:false; response_revision:1616; number_of_response:1; }","duration":"122.844655ms","start":"2026-03-23T05:26:58.268174Z","end":"2026-03-23T05:26:58.391019Z","steps":["trace[438354068] 'process raft request' (duration: 122.599009ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:59.746289Z","caller":"traceutil/trace.go:171","msg":"trace[642637933] transaction","detail":"{read_only:false; response_revision:1622; number_of_response:1; }","duration":"137.995753ms","start":"2026-03-23T05:26:59.608238Z","end":"2026-03-23T05:26:59.746234Z","steps":["trace[642637933] 'process raft request' (duration: 137.697766ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:27:00.922914Z","caller":"traceutil/trace.go:171","msg":"trace[1878547084] transaction","detail":"{read_only:false; response_revision:1626; number_of_response:1; }","duration":"141.879335ms","start":"2026-03-23T05:27:00.780981Z","end":"2026-03-23T05:27:00.922861Z","steps":["trace[1878547084] 'process raft request' (duration: 104.298127ms)","trace[1878547084] 'compare' (duration: 37.259831ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:27:00.923139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.561789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-23T05:27:00.923203Z","caller":"traceutil/trace.go:171","msg":"trace[1836184637] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1626; }","duration":"136.664401ms","start":"2026-03-23T05:27:00.786524Z","end":"2026-03-23T05:27:00.923188Z","steps":["trace[1836184637] 'agreement among raft nodes before linearized reading' (duration: 136.517337ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:27:00.922884Z","caller":"traceutil/trace.go:171","msg":"trace[1716983343] linearizableReadLoop","detail":"{readStateIndex:1695; appliedIndex:1694; }","duration":"136.261001ms","start":"2026-03-23T05:27:00.786565Z","end":"2026-03-23T05:27:00.922826Z","steps":["trace[1716983343] 'read index received' (duration: 98.619012ms)","trace[1716983343] 'applied index is now lower than readState.Index' (duration: 37.639249ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:27:01.20296Z","caller":"traceutil/trace.go:171","msg":"trace[703711005] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"211.84907ms","start":"2026-03-23T05:27:00.991085Z","end":"2026-03-23T05:27:01.202934Z","steps":["trace[703711005] 'process raft request' (duration: 183.704064ms)","trace[703711005] 'compare' (duration: 28.044863ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:27:03.87788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.316984ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-23T05:27:04.24086Z","caller":"traceutil/trace.go:171","msg":"trace[307755649] transaction","detail":"{read_only:false; response_revision:1638; number_of_response:1; }","duration":"358.369684ms","start":"2026-03-23T05:27:03.882465Z","end":"2026-03-23T05:27:04.240835Z","steps":["trace[307755649] 'process raft request' (duration: 358.23813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:27:04.241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:27:03.882447Z","time spent":"358.482637ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:27:04.350664Z","caller":"traceutil/trace.go:171","msg":"trace[540335297] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"468.052268ms","start":"2026-03-23T05:27:03.882566Z","end":"2026-03-23T05:27:04.350618Z","steps":["trace[540335297] 'process raft request' (duration: 467.684519ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:27:04.35076Z","caller":"traceutil/trace.go:171","msg":"trace[2007686934] linearizableReadLoop","detail":"{readStateIndex:1709; appliedIndex:1707; }","duration":"177.484807ms","start":"2026-03-23T05:27:04.173033Z","end":"2026-03-23T05:27:04.350518Z","steps":["trace[2007686934] 'read index received' (duration: 67.782983ms)","trace[2007686934] 'applied index is now lower than readState.Index' (duration: 109.700734ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:27:04.350782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.735723ms","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-03-23T05:27:04.35087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:27:03.882539Z","time spent":"468.215572ms","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:27:04.350846Z","caller":"traceutil/trace.go:171","msg":"trace[1098620679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1639; }","duration":"177.850306ms","start":"2026-03-23T05:27:04.17298Z","end":"2026-03-23T05:27:04.35083Z","steps":["trace[1098620679] 'agreement among raft nodes before linearized reading' (duration: 177.667571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:27:05.028358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.985933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:27:05.028462Z","caller":"traceutil/trace.go:171","msg":"trace[1275706594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1642; }","duration":"105.130155ms","start":"2026-03-23T05:27:04.923306Z","end":"2026-03-23T05:27:05.028437Z","steps":["trace[1275706594] 'range keys from in-memory index tree' (duration: 104.90559ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:27:31.608804Z","caller":"traceutil/trace.go:171","msg":"trace[2045048196] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"171.876904ms","start":"2026-03-23T05:27:31.436906Z","end":"2026-03-23T05:27:31.608782Z","steps":["trace[2045048196] 'process raft request' (duration: 171.753451ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:27:38.030956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.563627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:27:38.03104Z","caller":"traceutil/trace.go:171","msg":"trace[2014310315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1808; }","duration":"108.69486ms","start":"2026-03-23T05:27:37.922327Z","end":"2026-03-23T05:27:38.031022Z","steps":["trace[2014310315] 'range keys from in-memory index tree' (duration: 108.490146ms)"],"step_count":1}