{"level":"warn","ts":"2026-04-08T22:51:01.008336Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T22:51:01.008409Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-04-08T22:51:01.008465Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T22:51:01.008472Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-08T22:51:01.008488Z","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-04-08T22:51:01.009095Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-04-08T22:51:01.009182Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-04-08T22:51:01.014388Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.986307ms"} {"level":"info","ts":"2026-04-08T22:51:01.048388Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-04-08T22:51:01.048543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T22:51:01.04859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-04-08T22:51:01.048617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T22:51:01.048641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-04-08T22:51:01.048712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-04-08T22:51:01.067399Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T22:51:01.069751Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T22:51:01.07413Z","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-04-08T22:51:01.077992Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T22:51:01.07914Z","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-04-08T22:51:01.079224Z","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-04-08T22:51:01.079234Z","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-04-08T22:51:01.079134Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T22:51:01.079983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-04-08T22:51:01.082423Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-08T22:51:01.088193Z","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-04-08T22:51:01.088566Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T22:51:01.088623Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T22:51:01.089299Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-08T22:51:01.089357Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-08T22:51:01.649991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T22:51:01.650077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T22:51:01.650112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-04-08T22:51:01.650134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-04-08T22:51:01.650146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-08T22:51:01.650163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-04-08T22:51:01.650184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-08T22:51:01.651404Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:51:01.652305Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T22:51:01.65235Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T22:51:01.653525Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T22:51:01.653631Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T22:51:01.655027Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-04-08T22:51:01.656591Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T22:51:01.657277Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T22:51:01.657672Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:51:01.657744Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:51:01.657771Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:51:04.175236Z","caller":"traceutil/trace.go:171","msg":"trace[144748816] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"147.783312ms","start":"2026-04-08T22:51:04.027422Z","end":"2026-04-08T22:51:04.175205Z","steps":["trace[144748816] 'read index received' (duration: 138.31198ms)","trace[144748816] 'applied index is now lower than readState.Index' (duration: 9.469852ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:04.175342Z","caller":"traceutil/trace.go:171","msg":"trace[257303280] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"148.615615ms","start":"2026-04-08T22:51:04.02668Z","end":"2026-04-08T22:51:04.175296Z","steps":["trace[257303280] 'process raft request' (duration: 139.195099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:04.175641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.131057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-08T22:51:04.175648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.24398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T22:51:04.175816Z","caller":"traceutil/trace.go:171","msg":"trace[105220087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"110.508047ms","start":"2026-04-08T22:51:04.065292Z","end":"2026-04-08T22:51:04.1758Z","steps":["trace[105220087] 'agreement among raft nodes before linearized reading' (duration: 110.257681ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:04.175791Z","caller":"traceutil/trace.go:171","msg":"trace[567243555] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:72; }","duration":"148.304425ms","start":"2026-04-08T22:51:04.02741Z","end":"2026-04-08T22:51:04.175714Z","steps":["trace[567243555] 'agreement among raft nodes before linearized reading' (duration: 148.013965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:05.018794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.566005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:51:05.018936Z","caller":"traceutil/trace.go:171","msg":"trace[1328045758] transaction","detail":"{read_only:false; response_revision:183; number_of_response:1; }","duration":"125.988749ms","start":"2026-04-08T22:51:04.892928Z","end":"2026-04-08T22:51:05.018917Z","steps":["trace[1328045758] 'process raft request' (duration: 19.608113ms)","trace[1328045758] 'compare' (duration: 105.402596ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:10.101222Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.030654453s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-08T22:51:10.910924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.444618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:51:10.911014Z","caller":"traceutil/trace.go:171","msg":"trace[1695746202] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:293; }","duration":"1.294145853s","start":"2026-04-08T22:51:09.616854Z","end":"2026-04-08T22:51:10.911Z","steps":["trace[1695746202] 'read index received' (duration: 484.522988ms)","trace[1695746202] 'applied index is now lower than readState.Index' (duration: 809.621775ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:10.911039Z","caller":"traceutil/trace.go:171","msg":"trace[1184869323] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"1.84056781s","start":"2026-04-08T22:51:09.070445Z","end":"2026-04-08T22:51:10.911013Z","steps":["trace[1184869323] 'process raft request' (duration: 1.030968116s)","trace[1184869323] 'compare' (duration: 809.303603ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:10.911128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:09.070428Z","time spent":"1.84066187s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T22:51:10.911348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294476528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-08T22:51:10.911406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.694598ms","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-04-08T22:51:10.911456Z","caller":"traceutil/trace.go:171","msg":"trace[6286342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:285; }","duration":"1.294633034s","start":"2026-04-08T22:51:09.616803Z","end":"2026-04-08T22:51:10.911436Z","steps":["trace[6286342] 'agreement among raft nodes before linearized reading' (duration: 1.294355454s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:10.911363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.152817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T22:51:10.911536Z","caller":"traceutil/trace.go:171","msg":"trace[944289129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:285; }","duration":"426.789828ms","start":"2026-04-08T22:51:10.484657Z","end":"2026-04-08T22:51:10.911447Z","steps":["trace[944289129] 'agreement among raft nodes before linearized reading' (duration: 426.673246ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:10.911585Z","caller":"traceutil/trace.go:171","msg":"trace[1792981853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:285; }","duration":"713.421255ms","start":"2026-04-08T22:51:10.198143Z","end":"2026-04-08T22:51:10.911565Z","steps":["trace[1792981853] 'agreement among raft nodes before linearized reading' (duration: 713.125894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:10.911766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:10.198118Z","time spent":"713.634778ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T22:51:10.911506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:09.616781Z","time spent":"1.294715584s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T22:51:10.911625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:10.484636Z","time spent":"426.977368ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T22:51:10.91136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.837148ms","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-04-08T22:51:10.912068Z","caller":"traceutil/trace.go:171","msg":"trace[917884759] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:285; }","duration":"227.596087ms","start":"2026-04-08T22:51:10.684458Z","end":"2026-04-08T22:51:10.912054Z","steps":["trace[917884759] 'agreement among raft nodes before linearized reading' (duration: 226.813876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:11.41915Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310560671152805,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T22:51:13.257905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.47600718s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:51:13.258017Z","caller":"traceutil/trace.go:171","msg":"trace[455500812] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"2.342406573s","start":"2026-04-08T22:51:10.915592Z","end":"2026-04-08T22:51:13.257999Z","steps":["trace[455500812] 'process raft request' (duration: 866.043285ms)","trace[455500812] 'compare' (duration: 1.47427777s)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:13.258078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:10.915578Z","time spent":"2.342470941s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:51:13.269462Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.487743152s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T22:51:13.269963Z","caller":"traceutil/trace.go:171","msg":"trace[1202919154] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"2.353901449s","start":"2026-04-08T22:51:10.916017Z","end":"2026-04-08T22:51:13.269918Z","steps":["trace[1202919154] 'process raft request' (duration: 2.353645073s)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:13.270015Z","caller":"traceutil/trace.go:171","msg":"trace[1244766683] linearizableReadLoop","detail":"{readStateIndex:298; appliedIndex:294; }","duration":"2.351422281s","start":"2026-04-08T22:51:10.918444Z","end":"2026-04-08T22:51:13.269866Z","steps":["trace[1244766683] 'read index received' (duration: 863.294519ms)","trace[1244766683] 'applied index is now lower than readState.Index' (duration: 1.488126152s)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:13.270315Z","caller":"traceutil/trace.go:171","msg":"trace[1418620899] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"2.354023862s","start":"2026-04-08T22:51:10.916092Z","end":"2026-04-08T22:51:13.270116Z","steps":["trace[1418620899] 'process raft request' (duration: 2.353695598s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:13.270348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:10.916004Z","time spent":"2.354047325s","remote":"127.0.0.1:43776","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-04-08T22:51:13.270429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:10.916068Z","time spent":"2.354281609s","remote":"127.0.0.1:43776","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-04-08T22:51:13.270581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.352125755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-08T22:51:13.270635Z","caller":"traceutil/trace.go:171","msg":"trace[993795393] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:289; }","duration":"2.352188851s","start":"2026-04-08T22:51:10.918426Z","end":"2026-04-08T22:51:13.270615Z","steps":["trace[993795393] 'agreement among raft nodes before linearized reading' (duration: 2.352087181s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:13.270672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:10.918418Z","time spent":"2.352247078s","remote":"127.0.0.1:43610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-08T22:51:13.270594Z","caller":"traceutil/trace.go:171","msg":"trace[1726674174] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"2.352014324s","start":"2026-04-08T22:51:10.918451Z","end":"2026-04-08T22:51:13.270466Z","steps":["trace[1726674174] 'process raft request' (duration: 2.351373776s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:13.270985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:10.918434Z","time spent":"2.352401274s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:51:13.302889Z","caller":"traceutil/trace.go:171","msg":"trace[1721103913] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"189.229291ms","start":"2026-04-08T22:51:13.113606Z","end":"2026-04-08T22:51:13.302835Z","steps":["trace[1721103913] 'process raft request' (duration: 188.964603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:13.303356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.726473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:13.303557Z","caller":"traceutil/trace.go:171","msg":"trace[51440737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:290; }","duration":"366.026263ms","start":"2026-04-08T22:51:12.937513Z","end":"2026-04-08T22:51:13.303539Z","steps":["trace[51440737] 'agreement among raft nodes before linearized reading' (duration: 365.219609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:13.303606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:12.937497Z","time spent":"366.098201ms","remote":"127.0.0.1:43512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T22:51:15.44683Z","caller":"traceutil/trace.go:171","msg":"trace[878154083] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"166.572146ms","start":"2026-04-08T22:51:15.280232Z","end":"2026-04-08T22:51:15.446805Z","steps":["trace[878154083] 'process raft request' (duration: 166.061542ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:15.447336Z","caller":"traceutil/trace.go:171","msg":"trace[1628204527] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"166.203877ms","start":"2026-04-08T22:51:15.281117Z","end":"2026-04-08T22:51:15.447321Z","steps":["trace[1628204527] 'process raft request' (duration: 165.992584ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:15.447479Z","caller":"traceutil/trace.go:171","msg":"trace[1074466987] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"166.209067ms","start":"2026-04-08T22:51:15.281239Z","end":"2026-04-08T22:51:15.447448Z","steps":["trace[1074466987] 'process raft request' (duration: 165.983183ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:17.537464Z","caller":"traceutil/trace.go:171","msg":"trace[277975137] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:371; }","duration":"256.381891ms","start":"2026-04-08T22:51:17.281059Z","end":"2026-04-08T22:51:17.537441Z","steps":["trace[277975137] 'read index received' (duration: 256.226885ms)","trace[277975137] 'applied index is now lower than readState.Index' (duration: 154.176µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:17.537579Z","caller":"traceutil/trace.go:171","msg":"trace[2055874585] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"263.603594ms","start":"2026-04-08T22:51:17.273966Z","end":"2026-04-08T22:51:17.537569Z","steps":["trace[2055874585] 'process raft request' (duration: 263.340107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:17.537813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.74612ms","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-04-08T22:51:17.537863Z","caller":"traceutil/trace.go:171","msg":"trace[1848720043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:361; }","duration":"256.848731ms","start":"2026-04-08T22:51:17.281005Z","end":"2026-04-08T22:51:17.537854Z","steps":["trace[1848720043] 'agreement among raft nodes before linearized reading' (duration: 256.758292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:17.538032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.828105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:17.538103Z","caller":"traceutil/trace.go:171","msg":"trace[1702338226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:361; }","duration":"176.905144ms","start":"2026-04-08T22:51:17.361177Z","end":"2026-04-08T22:51:17.538083Z","steps":["trace[1702338226] 'agreement among raft nodes before linearized reading' (duration: 176.625874ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:17.741361Z","caller":"traceutil/trace.go:171","msg":"trace[424498664] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"197.944907ms","start":"2026-04-08T22:51:17.543389Z","end":"2026-04-08T22:51:17.741334Z","steps":["trace[424498664] 'process raft request' (duration: 153.318911ms)","trace[424498664] 'compare' (duration: 44.505553ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:17.743804Z","caller":"traceutil/trace.go:171","msg":"trace[1239109827] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"200.279044ms","start":"2026-04-08T22:51:17.543504Z","end":"2026-04-08T22:51:17.743783Z","steps":["trace[1239109827] 'process raft request' (duration: 200.202165ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:17.744161Z","caller":"traceutil/trace.go:171","msg":"trace[1717576439] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"200.731302ms","start":"2026-04-08T22:51:17.543407Z","end":"2026-04-08T22:51:17.744138Z","steps":["trace[1717576439] 'process raft request' (duration: 200.183134ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:17.744216Z","caller":"traceutil/trace.go:171","msg":"trace[1555659604] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"200.664094ms","start":"2026-04-08T22:51:17.543528Z","end":"2026-04-08T22:51:17.744192Z","steps":["trace[1555659604] 'process raft request' (duration: 200.218997ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:17.744364Z","caller":"traceutil/trace.go:171","msg":"trace[162185490] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"193.574854ms","start":"2026-04-08T22:51:17.550743Z","end":"2026-04-08T22:51:17.744318Z","steps":["trace[162185490] 'process raft request' (duration: 193.25151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:21.440641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.761334ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-08T22:51:21.440767Z","caller":"traceutil/trace.go:171","msg":"trace[427188075] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:383; }","duration":"186.617102ms","start":"2026-04-08T22:51:21.254132Z","end":"2026-04-08T22:51:21.440749Z","steps":["trace[427188075] 'read index received' (duration: 82.878µs)","trace[427188075] 'applied index is now lower than readState.Index' (duration: 186.532714ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:21.440961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.794132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-04-08T22:51:21.441146Z","caller":"traceutil/trace.go:171","msg":"trace[271386344] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:372; }","duration":"187.026507ms","start":"2026-04-08T22:51:21.254093Z","end":"2026-04-08T22:51:21.441119Z","steps":["trace[271386344] 'agreement among raft nodes before linearized reading' (duration: 186.712424ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:21.58751Z","caller":"traceutil/trace.go:171","msg":"trace[817113145] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"134.098266ms","start":"2026-04-08T22:51:21.453379Z","end":"2026-04-08T22:51:21.587478Z","steps":["trace[817113145] 'process raft request' (duration: 133.918857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.024417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.308479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:51:22.02451Z","caller":"traceutil/trace.go:171","msg":"trace[1229926708] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"386.354096ms","start":"2026-04-08T22:51:21.638141Z","end":"2026-04-08T22:51:22.024495Z","steps":["trace[1229926708] 'process raft request' (duration: 81.902824ms)","trace[1229926708] 'compare' (duration: 304.084494ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:22.02457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:21.638114Z","time spent":"386.437956ms","remote":"127.0.0.1:43582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:51:22.053349Z","caller":"traceutil/trace.go:171","msg":"trace[430693934] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:387; }","duration":"297.639074ms","start":"2026-04-08T22:51:21.755688Z","end":"2026-04-08T22:51:22.053327Z","steps":["trace[430693934] 'read index received' (duration: 297.516601ms)","trace[430693934] 'applied index is now lower than readState.Index' (duration: 121.673µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:22.053439Z","caller":"traceutil/trace.go:171","msg":"trace[1439000174] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"413.428342ms","start":"2026-04-08T22:51:21.639986Z","end":"2026-04-08T22:51:22.053414Z","steps":["trace[1439000174] 'process raft request' (duration: 413.295968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.053484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.78452ms","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-04-08T22:51:22.053524Z","caller":"traceutil/trace.go:171","msg":"trace[1005438860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:377; }","duration":"297.876069ms","start":"2026-04-08T22:51:21.755626Z","end":"2026-04-08T22:51:22.053502Z","steps":["trace[1005438860] 'agreement among raft nodes before linearized reading' (duration: 297.807441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.053553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:21.639931Z","time spent":"413.560915ms","remote":"127.0.0.1:43942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:51:22.053693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.064016ms","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-04-08T22:51:22.05377Z","caller":"traceutil/trace.go:171","msg":"trace[1706952676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"296.160416ms","start":"2026-04-08T22:51:21.757592Z","end":"2026-04-08T22:51:22.053753Z","steps":["trace[1706952676] 'agreement among raft nodes before linearized reading' (duration: 296.032193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.053734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.652218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:51:22.053941Z","caller":"traceutil/trace.go:171","msg":"trace[939717024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"296.914005ms","start":"2026-04-08T22:51:21.757005Z","end":"2026-04-08T22:51:22.053919Z","steps":["trace[939717024] 'agreement among raft nodes before linearized reading' (duration: 296.622865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.054413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.2936ms","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-04-08T22:51:22.054487Z","caller":"traceutil/trace.go:171","msg":"trace[658652196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:377; }","duration":"296.393561ms","start":"2026-04-08T22:51:21.758076Z","end":"2026-04-08T22:51:22.05447Z","steps":["trace[658652196] 'agreement among raft nodes before linearized reading' (duration: 295.584304ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:22.05355Z","caller":"traceutil/trace.go:171","msg":"trace[1123332527] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"413.798221ms","start":"2026-04-08T22:51:21.639709Z","end":"2026-04-08T22:51:22.053507Z","steps":["trace[1123332527] 'process raft request' (duration: 413.471566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.055442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:21.639682Z","time spent":"415.676222ms","remote":"127.0.0.1:43676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:51:33.129601Z","caller":"traceutil/trace.go:171","msg":"trace[39072351] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"245.625946ms","start":"2026-04-08T22:51:32.883951Z","end":"2026-04-08T22:51:33.129577Z","steps":["trace[39072351] 'process raft request' (duration: 245.023553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:33.453667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.280136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:51:33.453766Z","caller":"traceutil/trace.go:171","msg":"trace[1205443020] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"291.551581ms","start":"2026-04-08T22:51:33.162197Z","end":"2026-04-08T22:51:33.453749Z","steps":["trace[1205443020] 'process raft request' (duration: 73.119383ms)","trace[1205443020] 'compare' (duration: 218.085449ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:33.69961Z","caller":"traceutil/trace.go:171","msg":"trace[851777051] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"185.359878ms","start":"2026-04-08T22:51:33.514222Z","end":"2026-04-08T22:51:33.699582Z","steps":["trace[851777051] 'process raft request' (duration: 91.288368ms)","trace[851777051] 'compare' (duration: 93.91061ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:33.894871Z","caller":"traceutil/trace.go:171","msg":"trace[1042172004] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"137.849427ms","start":"2026-04-08T22:51:33.756999Z","end":"2026-04-08T22:51:33.894848Z","steps":["trace[1042172004] 'process raft request' (duration: 137.263822ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:34.193276Z","caller":"traceutil/trace.go:171","msg":"trace[194373250] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"236.187827ms","start":"2026-04-08T22:51:33.957067Z","end":"2026-04-08T22:51:34.193254Z","steps":["trace[194373250] 'process raft request' (duration: 235.871105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:34.611506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.84328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:51:34.61167Z","caller":"traceutil/trace.go:171","msg":"trace[74098873] linearizableReadLoop","detail":"{readStateIndex:457; appliedIndex:456; }","duration":"369.816688ms","start":"2026-04-08T22:51:34.241798Z","end":"2026-04-08T22:51:34.611615Z","steps":["trace[74098873] 'read index received' (duration: 228.879414ms)","trace[74098873] 'applied index is now lower than readState.Index' (duration: 140.935764ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:34.611757Z","caller":"traceutil/trace.go:171","msg":"trace[2121486358] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"408.226894ms","start":"2026-04-08T22:51:34.203499Z","end":"2026-04-08T22:51:34.611726Z","steps":["trace[2121486358] 'process raft request' (duration: 267.075383ms)","trace[2121486358] 'compare' (duration: 140.582625ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:34.611885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:34.203478Z","time spent":"408.348906ms","remote":"127.0.0.1:43978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T22:51:34.612104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.29183ms","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-04-08T22:51:34.612149Z","caller":"traceutil/trace.go:171","msg":"trace[37633130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:443; }","duration":"370.376685ms","start":"2026-04-08T22:51:34.24176Z","end":"2026-04-08T22:51:34.612137Z","steps":["trace[37633130] 'agreement among raft nodes before linearized reading' (duration: 370.11496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:34.612275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:34.241746Z","time spent":"370.481109ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T22:51:34.645148Z","caller":"traceutil/trace.go:171","msg":"trace[719679047] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"402.027405ms","start":"2026-04-08T22:51:34.243079Z","end":"2026-04-08T22:51:34.645107Z","steps":["trace[719679047] 'process raft request' (duration: 401.668607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:34.645332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:34.243061Z","time spent":"402.187845ms","remote":"127.0.0.1:43974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4177,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:51:34.645597Z","caller":"traceutil/trace.go:171","msg":"trace[1511676292] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"331.262281ms","start":"2026-04-08T22:51:34.3143Z","end":"2026-04-08T22:51:34.645562Z","steps":["trace[1511676292] 'process raft request' (duration: 330.946999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:34.645635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.700327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-08T22:51:34.645664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.038714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:34.645705Z","caller":"traceutil/trace.go:171","msg":"trace[509267252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:445; }","duration":"248.79552ms","start":"2026-04-08T22:51:34.396891Z","end":"2026-04-08T22:51:34.645687Z","steps":["trace[509267252] 'agreement among raft nodes before linearized reading' (duration: 248.622801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:34.645718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.725844ms","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-04-08T22:51:34.645741Z","caller":"traceutil/trace.go:171","msg":"trace[2088332038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:445; }","duration":"286.145509ms","start":"2026-04-08T22:51:34.359574Z","end":"2026-04-08T22:51:34.64572Z","steps":["trace[2088332038] 'agreement among raft nodes before linearized reading' (duration: 286.014396ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:34.645795Z","caller":"traceutil/trace.go:171","msg":"trace[998560704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:445; }","duration":"248.816418ms","start":"2026-04-08T22:51:34.396954Z","end":"2026-04-08T22:51:34.64577Z","steps":["trace[998560704] 'agreement among raft nodes before linearized reading' (duration: 248.604041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:34.645801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:34.31427Z","time spent":"331.423111ms","remote":"127.0.0.1:43594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:51:34.646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.788831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:34.646042Z","caller":"traceutil/trace.go:171","msg":"trace[1592470423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:445; }","duration":"293.857208ms","start":"2026-04-08T22:51:34.352176Z","end":"2026-04-08T22:51:34.646033Z","steps":["trace[1592470423] 'agreement among raft nodes before linearized reading' (duration: 293.799331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:34.64616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.407017ms","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-04-08T22:51:34.64623Z","caller":"traceutil/trace.go:171","msg":"trace[666474933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:445; }","duration":"340.462363ms","start":"2026-04-08T22:51:34.30574Z","end":"2026-04-08T22:51:34.646203Z","steps":["trace[666474933] 'agreement among raft nodes before linearized reading' (duration: 339.720286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:34.646276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:34.305699Z","time spent":"340.568048ms","remote":"127.0.0.1:43776","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-04-08T22:51:35.041802Z","caller":"traceutil/trace.go:171","msg":"trace[500000522] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"293.509697ms","start":"2026-04-08T22:51:34.748267Z","end":"2026-04-08T22:51:35.041776Z","steps":["trace[500000522] 'process raft request' (duration: 254.768761ms)","trace[500000522] 'compare' (duration: 38.549587ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:35.053914Z","caller":"traceutil/trace.go:171","msg":"trace[1280747369] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"298.112056ms","start":"2026-04-08T22:51:34.755768Z","end":"2026-04-08T22:51:35.05388Z","steps":["trace[1280747369] 'process raft request' (duration: 297.906107ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:35.054059Z","caller":"traceutil/trace.go:171","msg":"trace[595964780] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:478; }","duration":"218.095913ms","start":"2026-04-08T22:51:34.835936Z","end":"2026-04-08T22:51:35.054032Z","steps":["trace[595964780] 'read index received' (duration: 167.113684ms)","trace[595964780] 'applied index is now lower than readState.Index' (duration: 50.979249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:35.054271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.528538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-04-08T22:51:35.054283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.608744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-04-08T22:51:35.054312Z","caller":"traceutil/trace.go:171","msg":"trace[153484383] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:466; }","duration":"206.600395ms","start":"2026-04-08T22:51:34.847701Z","end":"2026-04-08T22:51:35.054301Z","steps":["trace[153484383] 'agreement among raft nodes before linearized reading' (duration: 206.496741ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:35.054353Z","caller":"traceutil/trace.go:171","msg":"trace[928192349] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:466; }","duration":"206.716648ms","start":"2026-04-08T22:51:34.84762Z","end":"2026-04-08T22:51:35.054336Z","steps":["trace[928192349] 'agreement among raft nodes before linearized reading' (duration: 206.579596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:35.054443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.497861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329960"} {"level":"info","ts":"2026-04-08T22:51:35.054481Z","caller":"traceutil/trace.go:171","msg":"trace[1438288906] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:466; }","duration":"218.575445ms","start":"2026-04-08T22:51:34.835895Z","end":"2026-04-08T22:51:35.054471Z","steps":["trace[1438288906] 'agreement among raft nodes before linearized reading' (duration: 218.278503ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:36.319328Z","caller":"traceutil/trace.go:171","msg":"trace[1698069134] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"180.744494ms","start":"2026-04-08T22:51:36.138565Z","end":"2026-04-08T22:51:36.319309Z","steps":["trace[1698069134] 'process raft request' (duration: 180.619001ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:36.537165Z","caller":"traceutil/trace.go:171","msg":"trace[1981394976] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"256.300578ms","start":"2026-04-08T22:51:36.280829Z","end":"2026-04-08T22:51:36.537129Z","steps":["trace[1981394976] 'process raft request' (duration: 256.022053ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:36.537457Z","caller":"traceutil/trace.go:171","msg":"trace[1327389467] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"239.412417ms","start":"2026-04-08T22:51:36.297996Z","end":"2026-04-08T22:51:36.537408Z","steps":["trace[1327389467] 'process raft request' (duration: 239.234447ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:36.537472Z","caller":"traceutil/trace.go:171","msg":"trace[1602781158] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"238.44995ms","start":"2026-04-08T22:51:36.298994Z","end":"2026-04-08T22:51:36.537444Z","steps":["trace[1602781158] 'process raft request' (duration: 238.282809ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:36.53751Z","caller":"traceutil/trace.go:171","msg":"trace[222949833] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"225.0307ms","start":"2026-04-08T22:51:36.312461Z","end":"2026-04-08T22:51:36.537492Z","steps":["trace[222949833] 'process raft request' (duration: 224.968424ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:36.53744Z","caller":"traceutil/trace.go:171","msg":"trace[549459041] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"245.534028ms","start":"2026-04-08T22:51:36.291699Z","end":"2026-04-08T22:51:36.537233Z","steps":["trace[549459041] 'process raft request' (duration: 245.323899ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:36.537601Z","caller":"traceutil/trace.go:171","msg":"trace[1129778794] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"230.264709ms","start":"2026-04-08T22:51:36.3073Z","end":"2026-04-08T22:51:36.537565Z","steps":["trace[1129778794] 'process raft request' (duration: 230.041191ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:36.537788Z","caller":"traceutil/trace.go:171","msg":"trace[1636692528] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"241.527164ms","start":"2026-04-08T22:51:36.295723Z","end":"2026-04-08T22:51:36.53725Z","steps":["trace[1636692528] 'process raft request' (duration: 241.353503ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:36.537859Z","caller":"traceutil/trace.go:171","msg":"trace[761121038] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"240.452431ms","start":"2026-04-08T22:51:36.296817Z","end":"2026-04-08T22:51:36.537269Z","steps":["trace[761121038] 'process raft request' (duration: 240.317179ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:36.537807Z","caller":"traceutil/trace.go:171","msg":"trace[40508835] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"248.244112ms","start":"2026-04-08T22:51:36.288936Z","end":"2026-04-08T22:51:36.53718Z","steps":["trace[40508835] 'process raft request' (duration: 248.039013ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:36.537946Z","caller":"traceutil/trace.go:171","msg":"trace[1253066096] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"239.329541ms","start":"2026-04-08T22:51:36.297958Z","end":"2026-04-08T22:51:36.537288Z","steps":["trace[1253066096] 'process raft request' (duration: 239.227467ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:36.563467Z","caller":"traceutil/trace.go:171","msg":"trace[421296255] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"116.154299ms","start":"2026-04-08T22:51:36.447291Z","end":"2026-04-08T22:51:36.563445Z","steps":["trace[421296255] 'process raft request' (duration: 116.074963ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:36.563525Z","caller":"traceutil/trace.go:171","msg":"trace[1724544571] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"234.011218ms","start":"2026-04-08T22:51:36.329476Z","end":"2026-04-08T22:51:36.563487Z","steps":["trace[1724544571] 'process raft request' (duration: 233.764291ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:37.304471Z","caller":"traceutil/trace.go:171","msg":"trace[874270019] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"196.50035ms","start":"2026-04-08T22:51:37.107934Z","end":"2026-04-08T22:51:37.304435Z","steps":["trace[874270019] 'process raft request' (duration: 139.28721ms)","trace[874270019] 'compare' (duration: 56.993342ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:37.305046Z","caller":"traceutil/trace.go:171","msg":"trace[1414089152] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"119.483071ms","start":"2026-04-08T22:51:37.185539Z","end":"2026-04-08T22:51:37.305023Z","steps":["trace[1414089152] 'process raft request' (duration: 119.3147ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:37.305037Z","caller":"traceutil/trace.go:171","msg":"trace[1429368634] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"166.118346ms","start":"2026-04-08T22:51:37.138871Z","end":"2026-04-08T22:51:37.304989Z","steps":["trace[1429368634] 'process raft request' (duration: 165.794663ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:37.952377Z","caller":"traceutil/trace.go:171","msg":"trace[703216717] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"185.46617ms","start":"2026-04-08T22:51:37.766844Z","end":"2026-04-08T22:51:37.95231Z","steps":["trace[703216717] 'read index received' (duration: 167.653298ms)","trace[703216717] 'applied index is now lower than readState.Index' (duration: 17.810952ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:37.952778Z","caller":"traceutil/trace.go:171","msg":"trace[671830329] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"210.09756ms","start":"2026-04-08T22:51:37.742639Z","end":"2026-04-08T22:51:37.952737Z","steps":["trace[671830329] 'process raft request' (duration: 191.932136ms)","trace[671830329] 'compare' (duration: 16.611353ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:37.953713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.841878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-s7cv8\" ","response":"range_response_count:1 size:4675"} {"level":"info","ts":"2026-04-08T22:51:37.953788Z","caller":"traceutil/trace.go:171","msg":"trace[2102084983] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-s7cv8; range_end:; response_count:1; response_revision:554; }","duration":"186.962621ms","start":"2026-04-08T22:51:37.766797Z","end":"2026-04-08T22:51:37.953759Z","steps":["trace[2102084983] 'agreement among raft nodes before linearized reading' (duration: 186.665927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:37.958593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.648704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-04-08T22:51:37.958669Z","caller":"traceutil/trace.go:171","msg":"trace[1987871822] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:556; }","duration":"189.776097ms","start":"2026-04-08T22:51:37.768874Z","end":"2026-04-08T22:51:37.95865Z","steps":["trace[1987871822] 'agreement among raft nodes before linearized reading' (duration: 189.475363ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:37.95891Z","caller":"traceutil/trace.go:171","msg":"trace[1572498100] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"166.225521ms","start":"2026-04-08T22:51:37.792669Z","end":"2026-04-08T22:51:37.958895Z","steps":["trace[1572498100] 'process raft request' (duration: 165.243442ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:37.95894Z","caller":"traceutil/trace.go:171","msg":"trace[1408429975] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"107.320138ms","start":"2026-04-08T22:51:37.851592Z","end":"2026-04-08T22:51:37.958912Z","steps":["trace[1408429975] 'process raft request' (duration: 106.713989ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:38.602783Z","caller":"traceutil/trace.go:171","msg":"trace[2055201402] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"151.809023ms","start":"2026-04-08T22:51:38.450952Z","end":"2026-04-08T22:51:38.602761Z","steps":["trace[2055201402] 'process raft request' (duration: 151.678069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.150157Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310560671153365,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T22:51:41.621625Z","caller":"traceutil/trace.go:171","msg":"trace[1897592340] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"993.776886ms","start":"2026-04-08T22:51:40.627791Z","end":"2026-04-08T22:51:41.621568Z","steps":["trace[1897592340] 'process raft request' (duration: 993.069205ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:41.621686Z","caller":"traceutil/trace.go:171","msg":"trace[358218480] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"971.815785ms","start":"2026-04-08T22:51:40.649839Z","end":"2026-04-08T22:51:41.621655Z","steps":["trace[358218480] 'read index received' (duration: 845.970788ms)","trace[358218480] 'applied index is now lower than readState.Index' (duration: 125.840967ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:41.621944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.606302ms","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-04-08T22:51:41.622177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.124281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-08T22:51:41.622227Z","caller":"traceutil/trace.go:171","msg":"trace[159110123] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"919.921759ms","start":"2026-04-08T22:51:40.702279Z","end":"2026-04-08T22:51:41.622201Z","steps":["trace[159110123] 'agreement among raft nodes before linearized reading' (duration: 919.573125ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:41.622267Z","caller":"traceutil/trace.go:171","msg":"trace[1370963990] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:598; }","duration":"972.447266ms","start":"2026-04-08T22:51:40.649798Z","end":"2026-04-08T22:51:41.622245Z","steps":["trace[1370963990] 'agreement among raft nodes before linearized reading' (duration: 971.972917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.622281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:40.702268Z","time spent":"920.004005ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T22:51:41.622315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.640206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-08T22:51:41.622323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:40.649785Z","time spent":"972.529274ms","remote":"127.0.0.1:43544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-04-08T22:51:41.62229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:40.627762Z","time spent":"993.999417ms","remote":"127.0.0.1:43594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:51:41.622374Z","caller":"traceutil/trace.go:171","msg":"trace[1307314925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"902.747561ms","start":"2026-04-08T22:51:40.719608Z","end":"2026-04-08T22:51:41.622355Z","steps":["trace[1307314925] 'agreement among raft nodes before linearized reading' (duration: 902.32426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.622372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.851654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"warn","ts":"2026-04-08T22:51:41.622417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:40.719596Z","time spent":"902.81413ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T22:51:41.622427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.869009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-08T22:51:41.622555Z","caller":"traceutil/trace.go:171","msg":"trace[1162962434] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:599; }","duration":"573.052153ms","start":"2026-04-08T22:51:41.049471Z","end":"2026-04-08T22:51:41.622524Z","steps":["trace[1162962434] 'agreement among raft nodes before linearized reading' (duration: 572.438099ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:41.622579Z","caller":"traceutil/trace.go:171","msg":"trace[1215471330] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"346.392225ms","start":"2026-04-08T22:51:41.276173Z","end":"2026-04-08T22:51:41.622566Z","steps":["trace[1215471330] 'process raft request' (duration: 345.448176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.622626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:41.049447Z","time spent":"573.165868ms","remote":"127.0.0.1:43544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-08T22:51:41.622642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:41.276142Z","time spent":"346.463563ms","remote":"127.0.0.1:43544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T22:51:41.622644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.783576ms","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-04-08T22:51:41.622683Z","caller":"traceutil/trace.go:171","msg":"trace[744066742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"907.841523ms","start":"2026-04-08T22:51:40.714831Z","end":"2026-04-08T22:51:41.622673Z","steps":["trace[744066742] 'agreement among raft nodes before linearized reading' (duration: 907.704579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.622719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:40.714786Z","time spent":"907.924991ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T22:51:41.622857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.535048ms","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-04-08T22:51:41.622959Z","caller":"traceutil/trace.go:171","msg":"trace[898145673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"905.657022ms","start":"2026-04-08T22:51:40.717274Z","end":"2026-04-08T22:51:41.622931Z","steps":["trace[898145673] 'agreement among raft nodes before linearized reading' (duration: 904.950152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.622993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.391492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:41.623035Z","caller":"traceutil/trace.go:171","msg":"trace[712147099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"260.468538ms","start":"2026-04-08T22:51:41.362556Z","end":"2026-04-08T22:51:41.623024Z","steps":["trace[712147099] 'agreement among raft nodes before linearized reading' (duration: 260.407071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.623146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.210608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-08T22:51:41.62318Z","caller":"traceutil/trace.go:171","msg":"trace[1407697069] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:599; }","duration":"173.244286ms","start":"2026-04-08T22:51:41.449926Z","end":"2026-04-08T22:51:41.62317Z","steps":["trace[1407697069] 'agreement among raft nodes before linearized reading' (duration: 173.15213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.623024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:40.717184Z","time spent":"905.829944ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T22:51:41.622469Z","caller":"traceutil/trace.go:171","msg":"trace[2126523475] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:599; }","duration":"772.966949ms","start":"2026-04-08T22:51:40.849475Z","end":"2026-04-08T22:51:41.622442Z","steps":["trace[2126523475] 'agreement among raft nodes before linearized reading' (duration: 772.765347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.625212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:40.84944Z","time spent":"775.737689ms","remote":"127.0.0.1:43544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-04-08T22:51:41.765403Z","caller":"traceutil/trace.go:171","msg":"trace[2123793315] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"136.765359ms","start":"2026-04-08T22:51:41.628602Z","end":"2026-04-08T22:51:41.765368Z","steps":["trace[2123793315] 'process raft request' (duration: 136.675543ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:41.765751Z","caller":"traceutil/trace.go:171","msg":"trace[529975611] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"136.832977ms","start":"2026-04-08T22:51:41.628881Z","end":"2026-04-08T22:51:41.765714Z","steps":["trace[529975611] 'process raft request' (duration: 136.442323ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:41.766701Z","caller":"traceutil/trace.go:171","msg":"trace[1761613616] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"140.438912ms","start":"2026-04-08T22:51:41.626224Z","end":"2026-04-08T22:51:41.766663Z","steps":["trace[1761613616] 'process raft request' (duration: 82.736595ms)","trace[1761613616] 'compare' (duration: 56.084006ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:47.57102Z","caller":"traceutil/trace.go:171","msg":"trace[1178554792] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"125.207085ms","start":"2026-04-08T22:51:47.445787Z","end":"2026-04-08T22:51:47.570994Z","steps":["trace[1178554792] 'process raft request' (duration: 125.050389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:48.254564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.453553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-08T22:51:48.254663Z","caller":"traceutil/trace.go:171","msg":"trace[955356234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:641; }","duration":"429.586429ms","start":"2026-04-08T22:51:47.82505Z","end":"2026-04-08T22:51:48.254636Z","steps":["trace[955356234] 'range keys from in-memory index tree' (duration: 429.273447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:48.254721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:47.825039Z","time spent":"429.667636ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T22:51:48.254936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.104022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:51:48.255006Z","caller":"traceutil/trace.go:171","msg":"trace[1672627092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"570.214629ms","start":"2026-04-08T22:51:47.684772Z","end":"2026-04-08T22:51:48.254986Z","steps":["trace[1672627092] 'range keys from in-memory index tree' (duration: 569.968816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:48.255049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:47.684752Z","time spent":"570.288697ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T22:51:48.255106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.684668ms","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-04-08T22:51:48.255158Z","caller":"traceutil/trace.go:171","msg":"trace[644172002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"463.794236ms","start":"2026-04-08T22:51:47.791351Z","end":"2026-04-08T22:51:48.255145Z","steps":["trace[644172002] 'range keys from in-memory index tree' (duration: 463.586142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:48.255195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:47.791335Z","time spent":"463.852744ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T22:51:48.255344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.562261ms","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-04-08T22:51:48.255397Z","caller":"traceutil/trace.go:171","msg":"trace[345128953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"454.638629ms","start":"2026-04-08T22:51:47.800751Z","end":"2026-04-08T22:51:48.255389Z","steps":["trace[345128953] 'range keys from in-memory index tree' (duration: 454.491903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:48.255416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:47.80074Z","time spent":"454.671758ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T22:51:48.255492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.063632ms","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-04-08T22:51:48.255512Z","caller":"traceutil/trace.go:171","msg":"trace[1809273547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"460.10423ms","start":"2026-04-08T22:51:47.795403Z","end":"2026-04-08T22:51:48.255507Z","steps":["trace[1809273547] 'range keys from in-memory index tree' (duration: 459.984495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:48.255529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:47.795392Z","time spent":"460.132859ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T22:51:49.368602Z","caller":"traceutil/trace.go:171","msg":"trace[1415641140] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"557.602872ms","start":"2026-04-08T22:51:48.810971Z","end":"2026-04-08T22:51:49.368573Z","steps":["trace[1415641140] 'process raft request' (duration: 557.391777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:49.368756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:48.81093Z","time spent":"557.749518ms","remote":"127.0.0.1:43676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:51:50.577736Z","caller":"traceutil/trace.go:171","msg":"trace[1845227984] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"308.787125ms","start":"2026-04-08T22:51:50.268921Z","end":"2026-04-08T22:51:50.577708Z","steps":["trace[1845227984] 'process raft request' (duration: 308.588849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:50.577914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:50.268901Z","time spent":"308.918411ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:51:52.363969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.670539497s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:51:52.364153Z","caller":"traceutil/trace.go:171","msg":"trace[2122703295] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"2.095174415s","start":"2026-04-08T22:51:50.26896Z","end":"2026-04-08T22:51:52.364135Z","steps":["trace[2122703295] 'process raft request' (duration: 424.379894ms)","trace[2122703295] 'compare' (duration: 1.670338831s)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:52.364219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:50.268943Z","time spent":"2.095245102s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:51:52.36447Z","caller":"traceutil/trace.go:171","msg":"trace[1453050369] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"2.094010455s","start":"2026-04-08T22:51:50.27043Z","end":"2026-04-08T22:51:52.36444Z","steps":["trace[1453050369] 'process raft request' (duration: 2.093655402s)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:52.364719Z","caller":"traceutil/trace.go:171","msg":"trace[579948965] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"2.093707983s","start":"2026-04-08T22:51:50.270969Z","end":"2026-04-08T22:51:52.364677Z","steps":["trace[579948965] 'process raft request' (duration: 2.093370391s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:52.364856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:50.270955Z","time spent":"2.09384799s","remote":"127.0.0.1:43776","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-04-08T22:51:52.364967Z","caller":"traceutil/trace.go:171","msg":"trace[26614829] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:668; }","duration":"2.090226622s","start":"2026-04-08T22:51:50.274723Z","end":"2026-04-08T22:51:52.364949Z","steps":["trace[26614829] 'read index received' (duration: 302.834844ms)","trace[26614829] 'applied index is now lower than readState.Index' (duration: 1.787390488s)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:52.364938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:50.270402Z","time spent":"2.094141192s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:51:52.520001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.355348ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-08T22:51:52.520103Z","caller":"traceutil/trace.go:171","msg":"trace[435647238] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"155.080683ms","start":"2026-04-08T22:51:52.365007Z","end":"2026-04-08T22:51:52.520087Z","steps":["trace[435647238] 'read index received' (duration: 45.838µs)","trace[435647238] 'applied index is now lower than readState.Index' (duration: 155.033335ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:52.520188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157671911s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:52.52023Z","caller":"traceutil/trace.go:171","msg":"trace[1747941802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"1.15775323s","start":"2026-04-08T22:51:51.362466Z","end":"2026-04-08T22:51:52.520219Z","steps":["trace[1747941802] 'agreement among raft nodes before linearized reading' (duration: 1.157665033s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:52.520264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:51.362443Z","time spent":"1.15781448s","remote":"127.0.0.1:43512","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-04-08T22:51:52.520942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.246081188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-08T22:51:52.521061Z","caller":"traceutil/trace.go:171","msg":"trace[1081075244] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:654; }","duration":"2.246330613s","start":"2026-04-08T22:51:50.274702Z","end":"2026-04-08T22:51:52.521033Z","steps":["trace[1081075244] 'agreement among raft nodes before linearized reading' (duration: 2.090335639s)","trace[1081075244] 'range keys from in-memory index tree' (duration: 154.938556ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:52.521366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:50.274694Z","time spent":"2.246630307s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T22:51:53.028898Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310560671153578,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T22:51:53.529753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310560671153578,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T22:51:53.862341Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.334825839s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T22:51:53.862761Z","caller":"traceutil/trace.go:171","msg":"trace[155363300] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"1.334568906s","start":"2026-04-08T22:51:52.52817Z","end":"2026-04-08T22:51:53.862739Z","steps":["trace[155363300] 'read index received' (duration: 1.334496137s)","trace[155363300] 'applied index is now lower than readState.Index' (duration: 71.809µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:53.862984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281858422s","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-04-08T22:51:53.863025Z","caller":"traceutil/trace.go:171","msg":"trace[672639496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"1.281944331s","start":"2026-04-08T22:51:52.581071Z","end":"2026-04-08T22:51:53.863015Z","steps":["trace[672639496] 'agreement among raft nodes before linearized reading' (duration: 1.281800284s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:53.863059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:52.581059Z","time spent":"1.281992841s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T22:51:53.86307Z","caller":"traceutil/trace.go:171","msg":"trace[1116038459] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.335579284s","start":"2026-04-08T22:51:52.527411Z","end":"2026-04-08T22:51:53.862991Z","steps":["trace[1116038459] 'process raft request' (duration: 1.335169052s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:53.863275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.334840049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:51:53.863468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:52.527343Z","time spent":"1.335874918s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:51:53.86352Z","caller":"traceutil/trace.go:171","msg":"trace[719854959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"1.335165773s","start":"2026-04-08T22:51:52.528153Z","end":"2026-04-08T22:51:53.863319Z","steps":["trace[719854959] 'agreement among raft nodes before linearized reading' (duration: 1.33481322s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:53.863884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:52.528145Z","time spent":"1.335573605s","remote":"127.0.0.1:43512","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-04-08T22:51:54.373993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310560671153582,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T22:51:54.818006Z","caller":"traceutil/trace.go:171","msg":"trace[1103347831] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"949.995852ms","start":"2026-04-08T22:51:53.867978Z","end":"2026-04-08T22:51:54.817974Z","steps":["trace[1103347831] 'process raft request' (duration: 880.855172ms)","trace[1103347831] 'compare' (duration: 68.929733ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:54.818159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:53.867958Z","time spent":"950.126461ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:51:54.818245Z","caller":"traceutil/trace.go:171","msg":"trace[1276522509] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:674; }","duration":"944.277256ms","start":"2026-04-08T22:51:53.873938Z","end":"2026-04-08T22:51:54.818215Z","steps":["trace[1276522509] 'read index received' (duration: 874.900501ms)","trace[1276522509] 'applied index is now lower than readState.Index' (duration: 69.375565ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:54.818448Z","caller":"traceutil/trace.go:171","msg":"trace[1480329522] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"701.579979ms","start":"2026-04-08T22:51:54.116857Z","end":"2026-04-08T22:51:54.818437Z","steps":["trace[1480329522] 'process raft request' (duration: 701.164667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:54.818569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.852093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-08T22:51:54.818571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:54.116834Z","time spent":"701.653159ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:51:54.81893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.037669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:51:54.818963Z","caller":"traceutil/trace.go:171","msg":"trace[1977904982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:657; }","duration":"450.086167ms","start":"2026-04-08T22:51:54.368867Z","end":"2026-04-08T22:51:54.818953Z","steps":["trace[1977904982] 'agreement among raft nodes before linearized reading' (duration: 449.967899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:54.818991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:54.368861Z","time spent":"450.124067ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T22:51:54.819233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.287068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:54.819282Z","caller":"traceutil/trace.go:171","msg":"trace[1175040337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"945.354466ms","start":"2026-04-08T22:51:53.873918Z","end":"2026-04-08T22:51:54.819273Z","steps":["trace[1175040337] 'agreement among raft nodes before linearized reading' (duration: 945.212089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:54.819311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:53.873909Z","time spent":"945.394625ms","remote":"127.0.0.1:43512","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-04-08T22:51:54.819466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.589709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T22:51:54.819534Z","caller":"traceutil/trace.go:171","msg":"trace[1870446752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"450.693456ms","start":"2026-04-08T22:51:54.368824Z","end":"2026-04-08T22:51:54.819517Z","steps":["trace[1870446752] 'agreement among raft nodes before linearized reading' (duration: 450.449711ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:54.819577Z","caller":"traceutil/trace.go:171","msg":"trace[961562423] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"449.891712ms","start":"2026-04-08T22:51:54.368706Z","end":"2026-04-08T22:51:54.818597Z","steps":["trace[961562423] 'agreement among raft nodes before linearized reading' (duration: 449.762634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:54.819594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:54.368817Z","time spent":"450.767646ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T22:51:54.81965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:54.368649Z","time spent":"450.982081ms","remote":"127.0.0.1:43776","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-04-08T22:51:57.997984Z","caller":"traceutil/trace.go:171","msg":"trace[1520613326] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"166.455354ms","start":"2026-04-08T22:51:57.831506Z","end":"2026-04-08T22:51:57.997961Z","steps":["trace[1520613326] 'process raft request' (duration: 166.321926ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:58.238738Z","caller":"traceutil/trace.go:171","msg":"trace[1005016399] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"358.605331ms","start":"2026-04-08T22:51:57.880122Z","end":"2026-04-08T22:51:58.238728Z","steps":["trace[1005016399] 'read index received' (duration: 117.875119ms)","trace[1005016399] 'applied index is now lower than readState.Index' (duration: 240.729232ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:58.239038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.902606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-08T22:51:58.2391Z","caller":"traceutil/trace.go:171","msg":"trace[1658065927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:677; }","duration":"359.014605ms","start":"2026-04-08T22:51:57.880069Z","end":"2026-04-08T22:51:58.239084Z","steps":["trace[1658065927] 'agreement among raft nodes before linearized reading' (duration: 358.709298ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:58.239035Z","caller":"traceutil/trace.go:171","msg":"trace[1277693423] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"293.483931ms","start":"2026-04-08T22:51:57.945458Z","end":"2026-04-08T22:51:58.238942Z","steps":["trace[1277693423] 'process raft request' (duration: 293.064766ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:58.238658Z","caller":"traceutil/trace.go:171","msg":"trace[1803970343] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"397.224235ms","start":"2026-04-08T22:51:57.841371Z","end":"2026-04-08T22:51:58.238595Z","steps":["trace[1803970343] 'process raft request' (duration: 353.275648ms)","trace[1803970343] 'compare' (duration: 43.791498ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:58.239691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:57.841344Z","time spent":"398.249683ms","remote":"127.0.0.1:43676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17767,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:51:58.239235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:57.880047Z","time spent":"359.174033ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-08T22:51:59.026808Z","caller":"traceutil/trace.go:171","msg":"trace[1018794540] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"149.375081ms","start":"2026-04-08T22:51:58.877407Z","end":"2026-04-08T22:51:59.026782Z","steps":["trace[1018794540] 'process raft request' (duration: 148.706457ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:59.027273Z","caller":"traceutil/trace.go:171","msg":"trace[1684976394] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"125.04901ms","start":"2026-04-08T22:51:58.902199Z","end":"2026-04-08T22:51:59.027248Z","steps":["trace[1684976394] 'process raft request' (duration: 125.005691ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:59.027453Z","caller":"traceutil/trace.go:171","msg":"trace[803636431] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"125.430437ms","start":"2026-04-08T22:51:58.902003Z","end":"2026-04-08T22:51:59.027433Z","steps":["trace[803636431] 'process raft request' (duration: 125.085639ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:52:01.219444Z","caller":"traceutil/trace.go:171","msg":"trace[1046306316] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"184.323602ms","start":"2026-04-08T22:52:01.035079Z","end":"2026-04-08T22:52:01.219403Z","steps":["trace[1046306316] 'process raft request' (duration: 184.076724ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:52:01.222407Z","caller":"traceutil/trace.go:171","msg":"trace[81686235] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"187.042533ms","start":"2026-04-08T22:52:01.035349Z","end":"2026-04-08T22:52:01.222391Z","steps":["trace[81686235] 'process raft request' (duration: 186.991063ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:52:01.222698Z","caller":"traceutil/trace.go:171","msg":"trace[1134283272] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"187.329391ms","start":"2026-04-08T22:52:01.035331Z","end":"2026-04-08T22:52:01.222661Z","steps":["trace[1134283272] 'process raft request' (duration: 186.915414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:12.877353Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310560671153825,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T22:52:13.018432Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.064558163s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T22:52:13.019508Z","caller":"traceutil/trace.go:171","msg":"trace[1356172449] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"642.826852ms","start":"2026-04-08T22:52:12.376657Z","end":"2026-04-08T22:52:13.019483Z","steps":["trace[1356172449] 'read index received' (duration: 642.53094ms)","trace[1356172449] 'applied index is now lower than readState.Index' (duration: 294.902µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:52:13.019579Z","caller":"traceutil/trace.go:171","msg":"trace[1087574423] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"1.065789925s","start":"2026-04-08T22:52:11.953763Z","end":"2026-04-08T22:52:13.019553Z","steps":["trace[1087574423] 'process raft request' (duration: 1.065552503s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:13.019808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.140575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-08T22:52:13.019871Z","caller":"traceutil/trace.go:171","msg":"trace[1749072826] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:776; }","duration":"643.214066ms","start":"2026-04-08T22:52:12.376641Z","end":"2026-04-08T22:52:13.019855Z","steps":["trace[1749072826] 'agreement among raft nodes before linearized reading' (duration: 642.977174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:13.019808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:11.953739Z","time spent":"1.065885986s","remote":"127.0.0.1:43676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4026,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:52:13.019919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:12.376579Z","time spent":"643.328176ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-08T22:52:19.159277Z","caller":"traceutil/trace.go:171","msg":"trace[476957826] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"107.578189ms","start":"2026-04-08T22:52:19.051669Z","end":"2026-04-08T22:52:19.159247Z","steps":["trace[476957826] 'process raft request' (duration: 18.450991ms)","trace[476957826] 'compare' (duration: 88.730221ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:52:19.159735Z","caller":"traceutil/trace.go:171","msg":"trace[553101688] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"107.174592ms","start":"2026-04-08T22:52:19.052519Z","end":"2026-04-08T22:52:19.159694Z","steps":["trace[553101688] 'process raft request' (duration: 106.665843ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:52:27.633366Z","caller":"traceutil/trace.go:171","msg":"trace[477891981] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"407.814651ms","start":"2026-04-08T22:52:27.225533Z","end":"2026-04-08T22:52:27.633348Z","steps":["trace[477891981] 'read index received' (duration: 407.597976ms)","trace[477891981] 'applied index is now lower than readState.Index' (duration: 216.125µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:52:27.633487Z","caller":"traceutil/trace.go:171","msg":"trace[1504693911] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"416.090647ms","start":"2026-04-08T22:52:27.217354Z","end":"2026-04-08T22:52:27.633445Z","steps":["trace[1504693911] 'process raft request' (duration: 415.84577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.633554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.004425ms","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-04-08T22:52:27.633585Z","caller":"traceutil/trace.go:171","msg":"trace[1482689431] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"408.088229ms","start":"2026-04-08T22:52:27.225489Z","end":"2026-04-08T22:52:27.633578Z","steps":["trace[1482689431] 'agreement among raft nodes before linearized reading' (duration: 407.944135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.633609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:27.225469Z","time spent":"408.135ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T22:52:27.63368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:27.217334Z","time spent":"416.232131ms","remote":"127.0.0.1:43776","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-04-08T22:52:27.737097Z","caller":"traceutil/trace.go:171","msg":"trace[2139022464] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"470.448925ms","start":"2026-04-08T22:52:27.266609Z","end":"2026-04-08T22:52:27.737058Z","steps":["trace[2139022464] 'process raft request' (duration: 411.082706ms)","trace[2139022464] 'compare' (duration: 59.087931ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:52:27.737172Z","caller":"traceutil/trace.go:171","msg":"trace[1999049637] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"103.718344ms","start":"2026-04-08T22:52:27.633422Z","end":"2026-04-08T22:52:27.73714Z","steps":["trace[1999049637] 'read index received' (duration: 44.246234ms)","trace[1999049637] 'applied index is now lower than readState.Index' (duration: 59.46927ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:52:27.737306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.514076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T22:52:27.73733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.16581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:52:27.737343Z","caller":"traceutil/trace.go:171","msg":"trace[1004467950] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:934; }","duration":"480.599237ms","start":"2026-04-08T22:52:27.256732Z","end":"2026-04-08T22:52:27.737331Z","steps":["trace[1004467950] 'agreement among raft nodes before linearized reading' (duration: 480.503505ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:52:27.737395Z","caller":"traceutil/trace.go:171","msg":"trace[1686015450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"375.262883ms","start":"2026-04-08T22:52:27.362114Z","end":"2026-04-08T22:52:27.737377Z","steps":["trace[1686015450] 'agreement among raft nodes before linearized reading' (duration: 375.14879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.737413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:27.256717Z","time spent":"480.676071ms","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:52:27.737427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:27.36209Z","time spent":"375.330224ms","remote":"127.0.0.1:43512","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-04-08T22:52:27.737452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.41075ms","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-04-08T22:52:27.737501Z","caller":"traceutil/trace.go:171","msg":"trace[1009311224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"290.516314ms","start":"2026-04-08T22:52:27.446971Z","end":"2026-04-08T22:52:27.737487Z","steps":["trace[1009311224] 'agreement among raft nodes before linearized reading' (duration: 290.307148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.737484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.57471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-08T22:52:27.737253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:27.266575Z","time spent":"470.603058ms","remote":"127.0.0.1:43582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:52:27.737586Z","caller":"traceutil/trace.go:171","msg":"trace[1174512529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:934; }","duration":"347.770095ms","start":"2026-04-08T22:52:27.389788Z","end":"2026-04-08T22:52:27.737558Z","steps":["trace[1174512529] 'agreement among raft nodes before linearized reading' (duration: 347.458987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.737601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.132962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-8rv4n\" ","response":"range_response_count:1 size:5675"} {"level":"warn","ts":"2026-04-08T22:52:27.737651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:27.389761Z","time spent":"347.880798ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T22:52:27.737673Z","caller":"traceutil/trace.go:171","msg":"trace[1492891715] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-8rv4n; range_end:; response_count:1; response_revision:934; }","duration":"323.247896ms","start":"2026-04-08T22:52:27.414406Z","end":"2026-04-08T22:52:27.737654Z","steps":["trace[1492891715] 'agreement among raft nodes before linearized reading' (duration: 322.930107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.737714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:27.414363Z","time spent":"323.342349ms","remote":"127.0.0.1:43676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5698,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-8rv4n\" "} {"level":"warn","ts":"2026-04-08T22:52:27.739662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.926211ms","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-04-08T22:52:27.739701Z","caller":"traceutil/trace.go:171","msg":"trace[285134603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"334.987182ms","start":"2026-04-08T22:52:27.404705Z","end":"2026-04-08T22:52:27.739692Z","steps":["trace[285134603] 'agreement among raft nodes before linearized reading' (duration: 332.497447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.739721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:27.404686Z","time spent":"335.030353ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T22:53:02.060216Z","caller":"traceutil/trace.go:171","msg":"trace[1389578446] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"108.882877ms","start":"2026-04-08T22:53:01.951307Z","end":"2026-04-08T22:53:02.06019Z","steps":["trace[1389578446] 'read index received' (duration: 108.688816ms)","trace[1389578446] 'applied index is now lower than readState.Index' (duration: 192.931µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:53:02.060253Z","caller":"traceutil/trace.go:171","msg":"trace[1112460261] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"130.293461ms","start":"2026-04-08T22:53:01.929927Z","end":"2026-04-08T22:53:02.060221Z","steps":["trace[1112460261] 'process raft request' (duration: 130.126262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:02.060406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.080727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:53:02.060436Z","caller":"traceutil/trace.go:171","msg":"trace[562873449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1067; }","duration":"109.12955ms","start":"2026-04-08T22:53:01.951301Z","end":"2026-04-08T22:53:02.060431Z","steps":["trace[562873449] 'agreement among raft nodes before linearized reading' (duration: 108.995423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:02.060556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.306345ms","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-04-08T22:53:02.060574Z","caller":"traceutil/trace.go:171","msg":"trace[891356285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1067; }","duration":"104.346018ms","start":"2026-04-08T22:53:01.956224Z","end":"2026-04-08T22:53:02.06057Z","steps":["trace[891356285] 'agreement among raft nodes before linearized reading' (duration: 104.300356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:02.060877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.842685ms","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-04-08T22:53:02.06121Z","caller":"traceutil/trace.go:171","msg":"trace[221696120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1067; }","duration":"104.984121ms","start":"2026-04-08T22:53:01.955967Z","end":"2026-04-08T22:53:02.060951Z","steps":["trace[221696120] 'agreement among raft nodes before linearized reading' (duration: 104.429312ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:04.236935Z","caller":"traceutil/trace.go:171","msg":"trace[96680120] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"167.088696ms","start":"2026-04-08T22:53:04.069823Z","end":"2026-04-08T22:53:04.236912Z","steps":["trace[96680120] 'read index received' (duration: 166.865404ms)","trace[96680120] 'applied index is now lower than readState.Index' (duration: 222.412µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:53:04.237032Z","caller":"traceutil/trace.go:171","msg":"trace[118635655] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"168.350745ms","start":"2026-04-08T22:53:04.068651Z","end":"2026-04-08T22:53:04.237002Z","steps":["trace[118635655] 'process raft request' (duration: 168.088792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:04.237166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.32255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:53:04.23724Z","caller":"traceutil/trace.go:171","msg":"trace[1614090010] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1073; }","duration":"167.438557ms","start":"2026-04-08T22:53:04.069789Z","end":"2026-04-08T22:53:04.237227Z","steps":["trace[1614090010] 'agreement among raft nodes before linearized reading' (duration: 167.246036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:04.237174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.33818ms","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-04-08T22:53:04.237403Z","caller":"traceutil/trace.go:171","msg":"trace[3207293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1073; }","duration":"167.604466ms","start":"2026-04-08T22:53:04.069789Z","end":"2026-04-08T22:53:04.237393Z","steps":["trace[3207293] 'agreement among raft nodes before linearized reading' (duration: 167.213424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:04.237443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.241825ms","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-04-08T22:53:04.23763Z","caller":"traceutil/trace.go:171","msg":"trace[976143679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1073; }","duration":"167.455247ms","start":"2026-04-08T22:53:04.070115Z","end":"2026-04-08T22:53:04.23757Z","steps":["trace[976143679] 'agreement among raft nodes before linearized reading' (duration: 167.031914ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:08.528563Z","caller":"traceutil/trace.go:171","msg":"trace[418732746] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"235.830227ms","start":"2026-04-08T22:53:08.292706Z","end":"2026-04-08T22:53:08.528536Z","steps":["trace[418732746] 'process raft request' (duration: 205.012184ms)","trace[418732746] 'compare' (duration: 30.653614ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:53:08.528832Z","caller":"traceutil/trace.go:171","msg":"trace[2002921694] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1125; }","duration":"168.820374ms","start":"2026-04-08T22:53:08.359989Z","end":"2026-04-08T22:53:08.52881Z","steps":["trace[2002921694] 'read index received' (duration: 137.819081ms)","trace[2002921694] 'applied index is now lower than readState.Index' (duration: 31.000393ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:53:08.528964Z","caller":"traceutil/trace.go:171","msg":"trace[1357690440] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"235.264714ms","start":"2026-04-08T22:53:08.293641Z","end":"2026-04-08T22:53:08.528905Z","steps":["trace[1357690440] 'process raft request' (duration: 234.85219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:08.529101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.08785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:53:08.529152Z","caller":"traceutil/trace.go:171","msg":"trace[1060200229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1091; }","duration":"169.186454ms","start":"2026-04-08T22:53:08.359954Z","end":"2026-04-08T22:53:08.529141Z","steps":["trace[1060200229] 'agreement among raft nodes before linearized reading' (duration: 168.946861ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:20.768066Z","caller":"traceutil/trace.go:171","msg":"trace[2074049050] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"110.7589ms","start":"2026-04-08T22:53:20.657257Z","end":"2026-04-08T22:53:20.768015Z","steps":["trace[2074049050] 'process raft request' (duration: 110.004283ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:26.809589Z","caller":"traceutil/trace.go:171","msg":"trace[1121194078] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"177.006045ms","start":"2026-04-08T22:53:26.632563Z","end":"2026-04-08T22:53:26.809569Z","steps":["trace[1121194078] 'process raft request' (duration: 176.887237ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:26.829139Z","caller":"traceutil/trace.go:171","msg":"trace[723561495] linearizableReadLoop","detail":"{readStateIndex:1190; appliedIndex:1189; }","duration":"138.17155ms","start":"2026-04-08T22:53:26.690942Z","end":"2026-04-08T22:53:26.829113Z","steps":["trace[723561495] 'read index received' (duration: 118.796065ms)","trace[723561495] 'applied index is now lower than readState.Index' (duration: 19.374475ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:53:26.829398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.438697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:53:26.829452Z","caller":"traceutil/trace.go:171","msg":"trace[1450547936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1149; }","duration":"138.533003ms","start":"2026-04-08T22:53:26.690906Z","end":"2026-04-08T22:53:26.829439Z","steps":["trace[1450547936] 'agreement among raft nodes before linearized reading' (duration: 138.394934ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:26.829591Z","caller":"traceutil/trace.go:171","msg":"trace[1575236549] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"191.257574ms","start":"2026-04-08T22:53:26.638323Z","end":"2026-04-08T22:53:26.829581Z","steps":["trace[1575236549] 'process raft request' (duration: 190.645165ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:26.950864Z","caller":"traceutil/trace.go:171","msg":"trace[232221550] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"116.053515ms","start":"2026-04-08T22:53:26.834776Z","end":"2026-04-08T22:53:26.95083Z","steps":["trace[232221550] 'process raft request' (duration: 116.013372ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:26.950893Z","caller":"traceutil/trace.go:171","msg":"trace[2065123786] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"117.324299ms","start":"2026-04-08T22:53:26.833545Z","end":"2026-04-08T22:53:26.950869Z","steps":["trace[2065123786] 'process raft request' (duration: 29.42411ms)","trace[2065123786] 'compare' (duration: 87.698686ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:53:28.697969Z","caller":"traceutil/trace.go:171","msg":"trace[1701616660] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"179.401303ms","start":"2026-04-08T22:53:28.518545Z","end":"2026-04-08T22:53:28.697946Z","steps":["trace[1701616660] 'process raft request' (duration: 178.254548ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:34.926071Z","caller":"traceutil/trace.go:171","msg":"trace[2121915357] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1213; }","duration":"361.805793ms","start":"2026-04-08T22:53:34.564241Z","end":"2026-04-08T22:53:34.926047Z","steps":["trace[2121915357] 'read index received' (duration: 361.521733ms)","trace[2121915357] 'applied index is now lower than readState.Index' (duration: 283.22µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:53:34.92618Z","caller":"traceutil/trace.go:171","msg":"trace[1826435256] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"386.032099ms","start":"2026-04-08T22:53:34.540067Z","end":"2026-04-08T22:53:34.926099Z","steps":["trace[1826435256] 'process raft request' (duration: 385.75191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:34.926323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.234887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:53:34.92635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:34.540049Z","time spent":"386.210221ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:53:34.926369Z","caller":"traceutil/trace.go:171","msg":"trace[741980666] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1172; }","duration":"313.431078ms","start":"2026-04-08T22:53:34.612925Z","end":"2026-04-08T22:53:34.926356Z","steps":["trace[741980666] 'agreement among raft nodes before linearized reading' (duration: 313.271157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:34.926471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:34.612892Z","time spent":"313.510374ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:53:34.926495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.029537ms","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-04-08T22:53:34.926596Z","caller":"traceutil/trace.go:171","msg":"trace[2138546287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1172; }","duration":"145.196797ms","start":"2026-04-08T22:53:34.781376Z","end":"2026-04-08T22:53:34.926573Z","steps":["trace[2138546287] 'agreement among raft nodes before linearized reading' (duration: 144.872705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:34.927773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.534992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329960"} {"level":"info","ts":"2026-04-08T22:53:34.928545Z","caller":"traceutil/trace.go:171","msg":"trace[2036976323] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1172; }","duration":"364.300164ms","start":"2026-04-08T22:53:34.564166Z","end":"2026-04-08T22:53:34.928467Z","steps":["trace[2036976323] 'agreement among raft nodes before linearized reading' (duration: 362.014337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:34.928671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:34.564134Z","time spent":"364.491706ms","remote":"127.0.0.1:43594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329983,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-04-08T22:53:35.08824Z","caller":"traceutil/trace.go:171","msg":"trace[452386169] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"157.67464ms","start":"2026-04-08T22:53:34.930538Z","end":"2026-04-08T22:53:35.088212Z","steps":["trace[452386169] 'process raft request' (duration: 146.222443ms)","trace[452386169] 'compare' (duration: 11.264264ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:53:35.088479Z","caller":"traceutil/trace.go:171","msg":"trace[1919788] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"152.185335ms","start":"2026-04-08T22:53:34.936258Z","end":"2026-04-08T22:53:35.088443Z","steps":["trace[1919788] 'process raft request' (duration: 151.911126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:35.088868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.376122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:53:35.088945Z","caller":"traceutil/trace.go:171","msg":"trace[1919123447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1174; }","duration":"109.51137ms","start":"2026-04-08T22:53:34.979418Z","end":"2026-04-08T22:53:35.08893Z","steps":["trace[1919123447] 'agreement among raft nodes before linearized reading' (duration: 109.272194ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:35.088667Z","caller":"traceutil/trace.go:171","msg":"trace[266128479] linearizableReadLoop","detail":"{readStateIndex:1216; appliedIndex:1214; }","duration":"108.897218ms","start":"2026-04-08T22:53:34.979473Z","end":"2026-04-08T22:53:35.088371Z","steps":["trace[266128479] 'read index received' (duration: 97.27886ms)","trace[266128479] 'applied index is now lower than readState.Index' (duration: 11.615908ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:53:35.089546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.938953ms","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-04-08T22:53:35.08959Z","caller":"traceutil/trace.go:171","msg":"trace[1645998404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1174; }","duration":"108.017648ms","start":"2026-04-08T22:53:34.981557Z","end":"2026-04-08T22:53:35.089575Z","steps":["trace[1645998404] 'agreement among raft nodes before linearized reading' (duration: 107.868548ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:53.344839Z","caller":"traceutil/trace.go:171","msg":"trace[717572044] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"174.66749ms","start":"2026-04-08T22:53:53.170144Z","end":"2026-04-08T22:53:53.344811Z","steps":["trace[717572044] 'read index received' (duration: 174.52604ms)","trace[717572044] 'applied index is now lower than readState.Index' (duration: 140.08µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:53:53.345089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.926559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T22:53:53.345131Z","caller":"traceutil/trace.go:171","msg":"trace[1515863138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1251; }","duration":"175.020396ms","start":"2026-04-08T22:53:53.170099Z","end":"2026-04-08T22:53:53.34512Z","steps":["trace[1515863138] 'agreement among raft nodes before linearized reading' (duration: 174.863864ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:53.345199Z","caller":"traceutil/trace.go:171","msg":"trace[1038770296] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"186.417121ms","start":"2026-04-08T22:53:53.15866Z","end":"2026-04-08T22:53:53.345077Z","steps":["trace[1038770296] 'process raft request' (duration: 185.979558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:53.345266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.257326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T22:53:53.345368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.352483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:53:53.345409Z","caller":"traceutil/trace.go:171","msg":"trace[829456621] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1251; }","duration":"108.481562ms","start":"2026-04-08T22:53:53.236902Z","end":"2026-04-08T22:53:53.345383Z","steps":["trace[829456621] 'agreement among raft nodes before linearized reading' (duration: 108.169959ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:53.345479Z","caller":"traceutil/trace.go:171","msg":"trace[1763841211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1251; }","duration":"127.501803ms","start":"2026-04-08T22:53:53.217933Z","end":"2026-04-08T22:53:53.345435Z","steps":["trace[1763841211] 'agreement among raft nodes before linearized reading' (duration: 127.209141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:53.345512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.788275ms","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-04-08T22:53:53.345645Z","caller":"traceutil/trace.go:171","msg":"trace[1328920385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1251; }","duration":"132.945686ms","start":"2026-04-08T22:53:53.212659Z","end":"2026-04-08T22:53:53.345605Z","steps":["trace[1328920385] 'agreement among raft nodes before linearized reading' (duration: 132.382534ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:53.471124Z","caller":"traceutil/trace.go:171","msg":"trace[1301967045] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"119.638669ms","start":"2026-04-08T22:53:53.351442Z","end":"2026-04-08T22:53:53.471081Z","steps":["trace[1301967045] 'process raft request' (duration: 54.667832ms)","trace[1301967045] 'compare' (duration: 64.735ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:53:53.491585Z","caller":"traceutil/trace.go:171","msg":"trace[222723212] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"139.966947ms","start":"2026-04-08T22:53:53.351589Z","end":"2026-04-08T22:53:53.491556Z","steps":["trace[222723212] 'process raft request' (duration: 139.700367ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:53.491955Z","caller":"traceutil/trace.go:171","msg":"trace[1159322961] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"140.28545ms","start":"2026-04-08T22:53:53.351657Z","end":"2026-04-08T22:53:53.491943Z","steps":["trace[1159322961] 'process raft request' (duration: 139.845077ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:53.492015Z","caller":"traceutil/trace.go:171","msg":"trace[1338081537] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"137.399967ms","start":"2026-04-08T22:53:53.354592Z","end":"2026-04-08T22:53:53.491992Z","steps":["trace[1338081537] 'process raft request' (duration: 137.279977ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:54.350912Z","caller":"traceutil/trace.go:171","msg":"trace[1914031360] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"138.636584ms","start":"2026-04-08T22:53:54.212251Z","end":"2026-04-08T22:53:54.350888Z","steps":["trace[1914031360] 'process raft request' (duration: 138.490784ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:59.602137Z","caller":"traceutil/trace.go:171","msg":"trace[87385495] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"164.457171ms","start":"2026-04-08T22:53:59.437652Z","end":"2026-04-08T22:53:59.602109Z","steps":["trace[87385495] 'process raft request' (duration: 164.18585ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:54:05.849141Z","caller":"traceutil/trace.go:171","msg":"trace[1822937982] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1363; }","duration":"152.031599ms","start":"2026-04-08T22:54:05.697083Z","end":"2026-04-08T22:54:05.849115Z","steps":["trace[1822937982] 'read index received' (duration: 151.783671ms)","trace[1822937982] 'applied index is now lower than readState.Index' (duration: 246.868µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:54:05.849253Z","caller":"traceutil/trace.go:171","msg":"trace[1569583151] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"154.546243ms","start":"2026-04-08T22:54:05.694677Z","end":"2026-04-08T22:54:05.849224Z","steps":["trace[1569583151] 'process raft request' (duration: 154.244249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:54:05.84941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.301461ms","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-04-08T22:54:05.849457Z","caller":"traceutil/trace.go:171","msg":"trace[622758386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1313; }","duration":"152.376536ms","start":"2026-04-08T22:54:05.697067Z","end":"2026-04-08T22:54:05.849443Z","steps":["trace[622758386] 'agreement among raft nodes before linearized reading' (duration: 152.152338ms)"],"step_count":1}