{"level":"warn","ts":"2026-06-28T19:53:01.004428Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-28T19:53:01.004523Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-06-28T19:53:01.004603Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-28T19:53:01.004622Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-06-28T19:53:01.004641Z","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-06-28T19:53:01.005708Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-06-28T19:53:01.005855Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-06-28T19:53:01.155818Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"149.689171ms"} {"level":"info","ts":"2026-06-28T19:53:03.022177Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-06-28T19:53:03.022357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-06-28T19:53:03.02245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-06-28T19:53:03.022517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-28T19:53:03.022552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-06-28T19:53:03.022631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-06-28T19:53:03.181322Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-28T19:53:03.23961Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-28T19:53:03.246529Z","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-06-28T19:53:03.260813Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-28T19:53:03.261287Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-28T19:53:03.261349Z","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-06-28T19:53:03.261526Z","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-06-28T19:53:03.261546Z","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-06-28T19:53:03.266196Z","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-06-28T19:53:03.266464Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-06-28T19:53:03.266589Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-06-28T19:53:03.266649Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-28T19:53:03.266692Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-28T19:53:03.271545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-06-28T19:53:03.271672Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-06-28T19:53:03.3238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-06-28T19:53:03.323855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-06-28T19:53:03.323877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-06-28T19:53:03.32389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-06-28T19:53:03.323898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-06-28T19:53:03.323909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-06-28T19:53:03.323923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-06-28T19:53:03.327052Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-06-28T19:53:03.32711Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-28T19:53:03.327139Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-28T19:53:03.327113Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-28T19:53:03.327317Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-28T19:53:03.327452Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-28T19:53:03.329666Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-28T19:53:03.335753Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-06-28T19:53:03.375783Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-06-28T19:53:03.463426Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-28T19:53:03.46352Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-06-28T19:53:05.511787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.842628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-28T19:53:05.512027Z","caller":"traceutil/trace.go:171","msg":"trace[378861406] linearizableReadLoop","detail":"{readStateIndex:67; appliedIndex:63; }","duration":"148.119527ms","start":"2026-06-28T19:53:05.363889Z","end":"2026-06-28T19:53:05.512008Z","steps":["trace[378861406] 'read index received' (duration: 25.825675ms)","trace[378861406] 'applied index is now lower than readState.Index' (duration: 122.292892ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:53:05.512197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.292382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-28T19:53:05.51215Z","caller":"traceutil/trace.go:171","msg":"trace[1713205881] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"220.656177ms","start":"2026-06-28T19:53:05.291432Z","end":"2026-06-28T19:53:05.512088Z","steps":["trace[1713205881] 'process raft request' (duration: 98.105245ms)","trace[1713205881] 'compare' (duration: 121.687992ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:05.512245Z","caller":"traceutil/trace.go:171","msg":"trace[191658743] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"210.591648ms","start":"2026-06-28T19:53:05.301648Z","end":"2026-06-28T19:53:05.51224Z","steps":["trace[191658743] 'process raft request' (duration: 210.303868ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:05.51225Z","caller":"traceutil/trace.go:171","msg":"trace[1107589704] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"220.584993ms","start":"2026-06-28T19:53:05.291656Z","end":"2026-06-28T19:53:05.512241Z","steps":["trace[1107589704] 'process raft request' (duration: 220.21646ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:05.512223Z","caller":"traceutil/trace.go:171","msg":"trace[1475459486] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:62; }","duration":"148.377286ms","start":"2026-06-28T19:53:05.363839Z","end":"2026-06-28T19:53:05.512216Z","steps":["trace[1475459486] 'agreement among raft nodes before linearized reading' (duration: 148.246181ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:05.512203Z","caller":"traceutil/trace.go:171","msg":"trace[105411642] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"219.832687ms","start":"2026-06-28T19:53:05.29234Z","end":"2026-06-28T19:53:05.512173Z","steps":["trace[105411642] 'process raft request' (duration: 219.573899ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:07.56447Z","caller":"traceutil/trace.go:171","msg":"trace[695840922] transaction","detail":"{read_only:false; response_revision:184; number_of_response:1; }","duration":"475.181919ms","start":"2026-06-28T19:53:07.089254Z","end":"2026-06-28T19:53:07.564436Z","steps":["trace[695840922] 'process raft request' (duration: 423.123618ms)","trace[695840922] 'compare' (duration: 51.810653ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:53:07.564779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.326487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:07.564814Z","caller":"traceutil/trace.go:171","msg":"trace[1141630988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:184; }","duration":"455.405231ms","start":"2026-06-28T19:53:07.109399Z","end":"2026-06-28T19:53:07.564804Z","steps":["trace[1141630988] 'agreement among raft nodes before linearized reading' (duration: 455.173223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:07.564608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:07.089241Z","time spent":"475.304853ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":694,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:53:07.564882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:07.109379Z","time spent":"455.449212ms","remote":"127.0.0.1:51586","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-06-28T19:53:07.56431Z","caller":"traceutil/trace.go:171","msg":"trace[1340416818] linearizableReadLoop","detail":"{readStateIndex:189; appliedIndex:188; }","duration":"454.848522ms","start":"2026-06-28T19:53:07.109442Z","end":"2026-06-28T19:53:07.564291Z","steps":["trace[1340416818] 'read index received' (duration: 402.80256ms)","trace[1340416818] 'applied index is now lower than readState.Index' (duration: 52.045142ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:53:07.569053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.438018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-28T19:53:07.569076Z","caller":"traceutil/trace.go:171","msg":"trace[1191719685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:184; }","duration":"389.497311ms","start":"2026-06-28T19:53:07.179569Z","end":"2026-06-28T19:53:07.569067Z","steps":["trace[1191719685] 'agreement among raft nodes before linearized reading' (duration: 389.425407ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:07.5691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:07.179558Z","time spent":"389.535342ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-28T19:53:07.569115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.945513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:07.569374Z","caller":"traceutil/trace.go:171","msg":"trace[1188641345] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:184; }","duration":"233.238543ms","start":"2026-06-28T19:53:07.336111Z","end":"2026-06-28T19:53:07.569349Z","steps":["trace[1188641345] 'agreement among raft nodes before linearized reading' (duration: 232.933622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:07.570656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.679579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:430"} {"level":"info","ts":"2026-06-28T19:53:07.570736Z","caller":"traceutil/trace.go:171","msg":"trace[1077288734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:184; }","duration":"375.151724ms","start":"2026-06-28T19:53:07.195545Z","end":"2026-06-28T19:53:07.570697Z","steps":["trace[1077288734] 'agreement among raft nodes before linearized reading' (duration: 373.82321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:07.570785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:07.195538Z","time spent":"375.226847ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-28T19:53:07.945603Z","caller":"traceutil/trace.go:171","msg":"trace[1229171267] transaction","detail":"{read_only:false; response_revision:190; number_of_response:1; }","duration":"205.300506ms","start":"2026-06-28T19:53:07.740276Z","end":"2026-06-28T19:53:07.945576Z","steps":["trace[1229171267] 'process raft request' (duration: 138.652632ms)","trace[1229171267] 'compare' (duration: 66.501899ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:08.142828Z","caller":"traceutil/trace.go:171","msg":"trace[645957492] transaction","detail":"{read_only:false; response_revision:192; number_of_response:1; }","duration":"156.205905ms","start":"2026-06-28T19:53:07.986596Z","end":"2026-06-28T19:53:08.142802Z","steps":["trace[645957492] 'process raft request' (duration: 69.777902ms)","trace[645957492] 'compare' (duration: 86.066151ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:08.967408Z","caller":"traceutil/trace.go:171","msg":"trace[297318712] linearizableReadLoop","detail":"{readStateIndex:198; appliedIndex:197; }","duration":"357.109791ms","start":"2026-06-28T19:53:08.610275Z","end":"2026-06-28T19:53:08.967384Z","steps":["trace[297318712] 'read index received' (duration: 349.01189ms)","trace[297318712] 'applied index is now lower than readState.Index' (duration: 8.097011ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:08.967465Z","caller":"traceutil/trace.go:171","msg":"trace[419743459] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"820.458954ms","start":"2026-06-28T19:53:08.146985Z","end":"2026-06-28T19:53:08.967444Z","steps":["trace[419743459] 'process raft request' (duration: 812.296641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:08.967585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.299247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:08.967624Z","caller":"traceutil/trace.go:171","msg":"trace[897198033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:193; }","duration":"357.39336ms","start":"2026-06-28T19:53:08.610222Z","end":"2026-06-28T19:53:08.967615Z","steps":["trace[897198033] 'agreement among raft nodes before linearized reading' (duration: 357.295636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:08.96766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:08.610165Z","time spent":"357.488193ms","remote":"127.0.0.1:51582","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-06-28T19:53:08.96773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:08.146966Z","time spent":"820.549396ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:53:08.968012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.165876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:08.968207Z","caller":"traceutil/trace.go:171","msg":"trace[851035512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:193; }","duration":"263.524408ms","start":"2026-06-28T19:53:08.704583Z","end":"2026-06-28T19:53:08.968108Z","steps":["trace[851035512] 'agreement among raft nodes before linearized reading' (duration: 263.019031ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:09.952815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.672474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:53:09.952884Z","caller":"traceutil/trace.go:171","msg":"trace[2125071089] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"149.928271ms","start":"2026-06-28T19:53:09.802943Z","end":"2026-06-28T19:53:09.952872Z","steps":["trace[2125071089] 'process raft request' (duration: 32.140973ms)","trace[2125071089] 'compare' (duration: 117.523419ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:10.159837Z","caller":"traceutil/trace.go:171","msg":"trace[401028369] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:260; }","duration":"153.798853ms","start":"2026-06-28T19:53:10.006006Z","end":"2026-06-28T19:53:10.159805Z","steps":["trace[401028369] 'read index received' (duration: 152.884943ms)","trace[401028369] 'applied index is now lower than readState.Index' (duration: 912.48µs)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:53:10.160047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.984769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:10.160152Z","caller":"traceutil/trace.go:171","msg":"trace[1048935748] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:255; }","duration":"154.142794ms","start":"2026-06-28T19:53:10.005939Z","end":"2026-06-28T19:53:10.160082Z","steps":["trace[1048935748] 'agreement among raft nodes before linearized reading' (duration: 153.996869ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:10.16004Z","caller":"traceutil/trace.go:171","msg":"trace[1880719885] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"183.079561ms","start":"2026-06-28T19:53:09.976896Z","end":"2026-06-28T19:53:10.159976Z","steps":["trace[1880719885] 'process raft request' (duration: 182.041127ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:10.473616Z","caller":"traceutil/trace.go:171","msg":"trace[485639468] linearizableReadLoop","detail":"{readStateIndex:265; appliedIndex:264; }","duration":"226.163369ms","start":"2026-06-28T19:53:10.247424Z","end":"2026-06-28T19:53:10.473588Z","steps":["trace[485639468] 'read index received' (duration: 160.482601ms)","trace[485639468] 'applied index is now lower than readState.Index' (duration: 65.679658ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:10.473645Z","caller":"traceutil/trace.go:171","msg":"trace[334194861] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"292.534301ms","start":"2026-06-28T19:53:10.181088Z","end":"2026-06-28T19:53:10.473622Z","steps":["trace[334194861] 'process raft request' (duration: 226.796121ms)","trace[334194861] 'compare' (duration: 65.554074ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:53:10.473767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.314814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:10.473881Z","caller":"traceutil/trace.go:171","msg":"trace[1897553233] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:0; response_revision:259; }","duration":"226.422057ms","start":"2026-06-28T19:53:10.247373Z","end":"2026-06-28T19:53:10.473795Z","steps":["trace[1897553233] 'agreement among raft nodes before linearized reading' (duration: 226.309353ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:10.790855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.981845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:53:10.791004Z","caller":"traceutil/trace.go:171","msg":"trace[1766082111] linearizableReadLoop","detail":"{readStateIndex:268; appliedIndex:267; }","duration":"245.775084ms","start":"2026-06-28T19:53:10.545208Z","end":"2026-06-28T19:53:10.790983Z","steps":["trace[1766082111] 'read index received' (duration: 104.563651ms)","trace[1766082111] 'applied index is now lower than readState.Index' (duration: 141.209433ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:10.791068Z","caller":"traceutil/trace.go:171","msg":"trace[78779782] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"250.802089ms","start":"2026-06-28T19:53:10.540215Z","end":"2026-06-28T19:53:10.791017Z","steps":["trace[78779782] 'process raft request' (duration: 109.577436ms)","trace[78779782] 'compare' (duration: 140.823309ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:53:10.791153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.94008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:10.791187Z","caller":"traceutil/trace.go:171","msg":"trace[251160126] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:0; response_revision:262; }","duration":"246.030922ms","start":"2026-06-28T19:53:10.545145Z","end":"2026-06-28T19:53:10.791176Z","steps":["trace[251160126] 'agreement among raft nodes before linearized reading' (duration: 245.892798ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:11.036693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.976508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:53:11.036776Z","caller":"traceutil/trace.go:171","msg":"trace[467072249] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:270; }","duration":"133.842658ms","start":"2026-06-28T19:53:10.902921Z","end":"2026-06-28T19:53:11.036763Z","steps":["trace[467072249] 'read index received' (duration: 19.649993ms)","trace[467072249] 'applied index is now lower than readState.Index' (duration: 114.191875ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:11.036811Z","caller":"traceutil/trace.go:171","msg":"trace[180990385] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"169.757464ms","start":"2026-06-28T19:53:10.867023Z","end":"2026-06-28T19:53:11.036781Z","steps":["trace[180990385] 'process raft request' (duration: 55.629541ms)","trace[180990385] 'compare' (duration: 113.845484ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:53:11.037287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.972852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:11.037331Z","caller":"traceutil/trace.go:171","msg":"trace[1246790971] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:265; }","duration":"134.431888ms","start":"2026-06-28T19:53:10.902882Z","end":"2026-06-28T19:53:11.037314Z","steps":["trace[1246790971] 'agreement among raft nodes before linearized reading' (duration: 133.944261ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:15.413336Z","caller":"traceutil/trace.go:171","msg":"trace[1064833412] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"109.950408ms","start":"2026-06-28T19:53:15.303359Z","end":"2026-06-28T19:53:15.413309Z","steps":["trace[1064833412] 'process raft request' (duration: 73.20553ms)","trace[1064833412] 'compare' (duration: 36.544962ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:15.57801Z","caller":"traceutil/trace.go:171","msg":"trace[1224192555] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"118.373448ms","start":"2026-06-28T19:53:15.459607Z","end":"2026-06-28T19:53:15.57798Z","steps":["trace[1224192555] 'process raft request' (duration: 118.269085ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:15.5781Z","caller":"traceutil/trace.go:171","msg":"trace[935764759] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"115.569401ms","start":"2026-06-28T19:53:15.462513Z","end":"2026-06-28T19:53:15.578083Z","steps":["trace[935764759] 'process raft request' (duration: 115.405206ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:15.578116Z","caller":"traceutil/trace.go:171","msg":"trace[435611949] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"118.845353ms","start":"2026-06-28T19:53:15.45923Z","end":"2026-06-28T19:53:15.578075Z","steps":["trace[435611949] 'process raft request' (duration: 66.952875ms)","trace[435611949] 'compare' (duration: 51.571318ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:18.530558Z","caller":"traceutil/trace.go:171","msg":"trace[1929576147] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"139.197917ms","start":"2026-06-28T19:53:18.391332Z","end":"2026-06-28T19:53:18.53053Z","steps":["trace[1929576147] 'process raft request' (duration: 139.044063ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:21.027512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.734592ms","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-06-28T19:53:21.027525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.714642395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-28T19:53:21.02757Z","caller":"traceutil/trace.go:171","msg":"trace[634292313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:411; }","duration":"982.830484ms","start":"2026-06-28T19:53:20.044727Z","end":"2026-06-28T19:53:21.027557Z","steps":["trace[634292313] 'range keys from in-memory index tree' (duration: 982.483114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:21.027592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:20.044707Z","time spent":"982.879736ms","remote":"127.0.0.1:51824","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-06-28T19:53:21.027596Z","caller":"traceutil/trace.go:171","msg":"trace[301948417] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:411; }","duration":"1.714782049s","start":"2026-06-28T19:53:19.312799Z","end":"2026-06-28T19:53:21.027581Z","steps":["trace[301948417] 'range keys from in-memory index tree' (duration: 1.714377457s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:21.027595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256610994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-28T19:53:21.027628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:19.312772Z","time spent":"1.714848111s","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"info","ts":"2026-06-28T19:53:21.027675Z","caller":"traceutil/trace.go:171","msg":"trace[1488919705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:411; }","duration":"1.256731167s","start":"2026-06-28T19:53:19.770923Z","end":"2026-06-28T19:53:21.027654Z","steps":["trace[1488919705] 'range keys from in-memory index tree' (duration: 1.25649911s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:21.027716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:19.770904Z","time spent":"1.256803389s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-28T19:53:21.027754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.515852132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:21.027783Z","caller":"traceutil/trace.go:171","msg":"trace[240704291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"1.515924344s","start":"2026-06-28T19:53:19.51185Z","end":"2026-06-28T19:53:21.027774Z","steps":["trace[240704291] 'range keys from in-memory index tree' (duration: 1.515702548s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:21.027823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:19.511819Z","time spent":"1.515998696s","remote":"127.0.0.1:51582","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-06-28T19:53:21.027885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189408978s","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-06-28T19:53:21.027971Z","caller":"traceutil/trace.go:171","msg":"trace[564289415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:411; }","duration":"1.18950385s","start":"2026-06-28T19:53:19.838441Z","end":"2026-06-28T19:53:21.027945Z","steps":["trace[564289415] 'range keys from in-memory index tree' (duration: 1.189215122s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:21.028019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:19.838354Z","time spent":"1.189653964s","remote":"127.0.0.1:51824","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":"info","ts":"2026-06-28T19:53:21.528125Z","caller":"traceutil/trace.go:171","msg":"trace[162825900] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"240.963758ms","start":"2026-06-28T19:53:21.287137Z","end":"2026-06-28T19:53:21.5281Z","steps":["trace[162825900] 'process raft request' (duration: 240.793023ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:23.214214Z","caller":"traceutil/trace.go:171","msg":"trace[270890030] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"143.2316ms","start":"2026-06-28T19:53:23.070965Z","end":"2026-06-28T19:53:23.214196Z","steps":["trace[270890030] 'process raft request' (duration: 143.203779ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:23.214258Z","caller":"traceutil/trace.go:171","msg":"trace[1823639846] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"143.276431ms","start":"2026-06-28T19:53:23.070965Z","end":"2026-06-28T19:53:23.214241Z","steps":["trace[1823639846] 'process raft request' (duration: 143.098766ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:23.233765Z","caller":"traceutil/trace.go:171","msg":"trace[513515286] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"162.763966ms","start":"2026-06-28T19:53:23.07099Z","end":"2026-06-28T19:53:23.233754Z","steps":["trace[513515286] 'process raft request' (duration: 162.688124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:25.035365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.164556ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-28T19:53:25.44173Z","caller":"traceutil/trace.go:171","msg":"trace[674943318] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"218.679451ms","start":"2026-06-28T19:53:25.223026Z","end":"2026-06-28T19:53:25.441706Z","steps":["trace[674943318] 'process raft request' (duration: 218.497476ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:25.470193Z","caller":"traceutil/trace.go:171","msg":"trace[1829775819] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:436; }","duration":"233.209479ms","start":"2026-06-28T19:53:25.23696Z","end":"2026-06-28T19:53:25.470169Z","steps":["trace[1829775819] 'read index received' (duration: 204.62484ms)","trace[1829775819] 'applied index is now lower than readState.Index' (duration: 28.583449ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:25.470229Z","caller":"traceutil/trace.go:171","msg":"trace[408893833] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"245.648025ms","start":"2026-06-28T19:53:25.224564Z","end":"2026-06-28T19:53:25.470212Z","steps":["trace[408893833] 'process raft request' (duration: 245.447089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:25.470329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.338082ms","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-06-28T19:53:25.470365Z","caller":"traceutil/trace.go:171","msg":"trace[6935683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:423; }","duration":"233.412794ms","start":"2026-06-28T19:53:25.23694Z","end":"2026-06-28T19:53:25.470353Z","steps":["trace[6935683] 'agreement among raft nodes before linearized reading' (duration: 233.324511ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:26.132755Z","caller":"traceutil/trace.go:171","msg":"trace[806568583] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"475.176833ms","start":"2026-06-28T19:53:25.657539Z","end":"2026-06-28T19:53:26.132716Z","steps":["trace[806568583] 'process raft request' (duration: 474.840054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:26.133912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:25.657516Z","time spent":"475.355598ms","remote":"127.0.0.1:51824","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-06-28T19:53:36.018Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368671420461620,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-28T19:53:36.189504Z","caller":"traceutil/trace.go:171","msg":"trace[248884760] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"671.603005ms","start":"2026-06-28T19:53:35.517879Z","end":"2026-06-28T19:53:36.189482Z","steps":["trace[248884760] 'read index received' (duration: 671.353039ms)","trace[248884760] 'applied index is now lower than readState.Index' (duration: 249.116µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:36.189557Z","caller":"traceutil/trace.go:171","msg":"trace[1642358780] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"672.810845ms","start":"2026-06-28T19:53:35.516714Z","end":"2026-06-28T19:53:36.189525Z","steps":["trace[1642358780] 'process raft request' (duration: 672.62446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:36.189672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.768939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-28T19:53:36.189717Z","caller":"traceutil/trace.go:171","msg":"trace[1651188449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"671.8509ms","start":"2026-06-28T19:53:35.517856Z","end":"2026-06-28T19:53:36.189707Z","steps":["trace[1651188449] 'agreement among raft nodes before linearized reading' (duration: 671.756938ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:36.18972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:35.516696Z","time spent":"672.927717ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:53:36.189762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:35.517847Z","time spent":"671.907062ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-28T19:53:36.189834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.91696ms","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-06-28T19:53:36.189952Z","caller":"traceutil/trace.go:171","msg":"trace[1989875269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:475; }","duration":"664.044634ms","start":"2026-06-28T19:53:35.525878Z","end":"2026-06-28T19:53:36.189923Z","steps":["trace[1989875269] 'agreement among raft nodes before linearized reading' (duration: 663.857889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:36.191339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:35.525869Z","time spent":"665.419138ms","remote":"127.0.0.1:51824","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-06-28T19:53:36.542564Z","caller":"traceutil/trace.go:171","msg":"trace[748540100] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"348.08225ms","start":"2026-06-28T19:53:36.194455Z","end":"2026-06-28T19:53:36.542538Z","steps":["trace[748540100] 'process raft request' (duration: 269.72655ms)","trace[748540100] 'compare' (duration: 78.008412ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:53:36.542702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:36.194433Z","time spent":"348.197203ms","remote":"127.0.0.1:51824","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-06-28T19:53:36.542668Z","caller":"traceutil/trace.go:171","msg":"trace[536371845] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"347.451335ms","start":"2026-06-28T19:53:36.195192Z","end":"2026-06-28T19:53:36.542644Z","steps":["trace[536371845] 'process raft request' (duration: 347.296191ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:36.54285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:36.195172Z","time spent":"347.602618ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:53:36.542845Z","caller":"traceutil/trace.go:171","msg":"trace[2086626921] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"324.874069ms","start":"2026-06-28T19:53:36.217947Z","end":"2026-06-28T19:53:36.542821Z","steps":["trace[2086626921] 'process raft request' (duration: 324.651124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:36.542964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:36.21793Z","time spent":"324.972572ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:53:36.546834Z","caller":"traceutil/trace.go:171","msg":"trace[893692491] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:491; }","duration":"324.703185ms","start":"2026-06-28T19:53:36.222119Z","end":"2026-06-28T19:53:36.546822Z","steps":["trace[893692491] 'read index received' (duration: 242.073427ms)","trace[893692491] 'applied index is now lower than readState.Index' (duration: 82.628888ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:53:36.546916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.788748ms","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-06-28T19:53:36.546933Z","caller":"traceutil/trace.go:171","msg":"trace[488702759] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:478; }","duration":"324.842639ms","start":"2026-06-28T19:53:36.222086Z","end":"2026-06-28T19:53:36.546929Z","steps":["trace[488702759] 'agreement among raft nodes before linearized reading' (duration: 324.781527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:36.54695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:36.222069Z","time spent":"324.87772ms","remote":"127.0.0.1:51824","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-06-28T19:53:42.327052Z","caller":"traceutil/trace.go:171","msg":"trace[1554199902] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"111.401785ms","start":"2026-06-28T19:53:42.215614Z","end":"2026-06-28T19:53:42.327016Z","steps":["trace[1554199902] 'process raft request' (duration: 111.169119ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:42.706199Z","caller":"traceutil/trace.go:171","msg":"trace[1259721866] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"133.690851ms","start":"2026-06-28T19:53:42.572481Z","end":"2026-06-28T19:53:42.706172Z","steps":["trace[1259721866] 'process raft request' (duration: 133.317412ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:42.718677Z","caller":"traceutil/trace.go:171","msg":"trace[1896445528] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"119.308613ms","start":"2026-06-28T19:53:42.599349Z","end":"2026-06-28T19:53:42.718657Z","steps":["trace[1896445528] 'process raft request' (duration: 119.20761ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:47.50489Z","caller":"traceutil/trace.go:171","msg":"trace[1748490287] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"100.156964ms","start":"2026-06-28T19:53:47.40472Z","end":"2026-06-28T19:53:47.504877Z","steps":["trace[1748490287] 'process raft request' (duration: 99.649363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:48.543225Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.020644342s","expected-duration":"1s"} {"level":"info","ts":"2026-06-28T19:53:48.622256Z","caller":"traceutil/trace.go:171","msg":"trace[1963488060] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.101056363s","start":"2026-06-28T19:53:47.521176Z","end":"2026-06-28T19:53:48.622233Z","steps":["trace[1963488060] 'process raft request' (duration: 1.022257118s)","trace[1963488060] 'compare' (duration: 78.544779ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:53:48.622375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:47.52115Z","time spent":"1.101172166s","remote":"127.0.0.1:52050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:53:48.62419Z","caller":"traceutil/trace.go:171","msg":"trace[448795289] transaction","detail":"{read_only:false; number_of_response:1; response_revision:552; }","duration":"1.082945514s","start":"2026-06-28T19:53:47.54123Z","end":"2026-06-28T19:53:48.624176Z","steps":["trace[448795289] 'process raft request' (duration: 1.08277464s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:48.624288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:47.541205Z","time spent":"1.083031856s","remote":"127.0.0.1:51632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:53:48.624427Z","caller":"traceutil/trace.go:171","msg":"trace[2017864645] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.095118889s","start":"2026-06-28T19:53:47.529298Z","end":"2026-06-28T19:53:48.624417Z","steps":["trace[2017864645] 'process raft request' (duration: 1.094594398s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:48.624487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:47.529266Z","time spent":"1.09519011s","remote":"127.0.0.1:51632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:53:48.624527Z","caller":"traceutil/trace.go:171","msg":"trace[1556724035] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:570; }","duration":"275.097742ms","start":"2026-06-28T19:53:48.349404Z","end":"2026-06-28T19:53:48.624502Z","steps":["trace[1556724035] 'read index received' (duration: 194.114108ms)","trace[1556724035] 'applied index is now lower than readState.Index' (duration: 80.982844ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:48.624595Z","caller":"traceutil/trace.go:171","msg":"trace[1402028502] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.061463718s","start":"2026-06-28T19:53:47.563124Z","end":"2026-06-28T19:53:48.624587Z","steps":["trace[1402028502] 'process raft request' (duration: 1.061132171s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:48.624646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:47.563119Z","time spent":"1.061503459s","remote":"127.0.0.1:52050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:53:48.624779Z","caller":"traceutil/trace.go:171","msg":"trace[264480723] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.056587967s","start":"2026-06-28T19:53:47.568182Z","end":"2026-06-28T19:53:48.624769Z","steps":["trace[264480723] 'process raft request' (duration: 1.056108206s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:48.624838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:47.568169Z","time spent":"1.056636439s","remote":"127.0.0.1:51632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:53:48.624847Z","caller":"traceutil/trace.go:171","msg":"trace[1720092061] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.083353003s","start":"2026-06-28T19:53:47.541476Z","end":"2026-06-28T19:53:48.624829Z","steps":["trace[1720092061] 'process raft request' (duration: 1.082562226s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:48.624903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:47.541468Z","time spent":"1.083410074s","remote":"127.0.0.1:52050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:53:48.625052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.485935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:48.624597Z","caller":"traceutil/trace.go:171","msg":"trace[20442021] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.061590511s","start":"2026-06-28T19:53:47.562994Z","end":"2026-06-28T19:53:48.624584Z","steps":["trace[20442021] 'process raft request' (duration: 1.061218963s)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:48.625134Z","caller":"traceutil/trace.go:171","msg":"trace[239277559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"114.600698ms","start":"2026-06-28T19:53:48.510512Z","end":"2026-06-28T19:53:48.625113Z","steps":["trace[239277559] 'agreement among raft nodes before linearized reading' (duration: 114.466835ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:48.625161Z","caller":"traceutil/trace.go:171","msg":"trace[1932845238] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.072943397s","start":"2026-06-28T19:53:47.552206Z","end":"2026-06-28T19:53:48.625149Z","steps":["trace[1932845238] 'process raft request' (duration: 1.071944765s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:48.625197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:47.562959Z","time spent":"1.062193174s","remote":"127.0.0.1:51632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:53:48.625221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:47.552189Z","time spent":"1.073001308s","remote":"127.0.0.1:51632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:53:48.625327Z","caller":"traceutil/trace.go:171","msg":"trace[161705402] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.047463941s","start":"2026-06-28T19:53:47.57785Z","end":"2026-06-28T19:53:48.625314Z","steps":["trace[161705402] 'process raft request' (duration: 1.046489919s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:48.625418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.010042ms","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-06-28T19:53:48.625443Z","caller":"traceutil/trace.go:171","msg":"trace[354899987] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.002173738s","start":"2026-06-28T19:53:47.623258Z","end":"2026-06-28T19:53:48.625431Z","steps":["trace[354899987] 'process raft request' (duration: 1.001188396s)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:48.625454Z","caller":"traceutil/trace.go:171","msg":"trace[1917463821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"276.084234ms","start":"2026-06-28T19:53:48.34936Z","end":"2026-06-28T19:53:48.625444Z","steps":["trace[1917463821] 'agreement among raft nodes before linearized reading' (duration: 275.964941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:48.625458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:47.577827Z","time spent":"1.047569274s","remote":"127.0.0.1:51632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:53:48.626078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:47.623232Z","time spent":"1.002237289s","remote":"127.0.0.1:51632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:53:55.003276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.703315ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-28T19:53:55.061438Z","caller":"traceutil/trace.go:171","msg":"trace[250333567] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"269.568683ms","start":"2026-06-28T19:53:54.791843Z","end":"2026-06-28T19:53:55.061412Z","steps":["trace[250333567] 'read index received' (duration: 269.309908ms)","trace[250333567] 'applied index is now lower than readState.Index' (duration: 257.635µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:55.06156Z","caller":"traceutil/trace.go:171","msg":"trace[902806577] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"303.415733ms","start":"2026-06-28T19:53:54.758099Z","end":"2026-06-28T19:53:55.061514Z","steps":["trace[902806577] 'process raft request' (duration: 303.072975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:55.061785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:54.758076Z","time spent":"303.603167ms","remote":"127.0.0.1:52020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:53:55.061886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.972007ms","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-06-28T19:53:55.06191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.013743ms","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-06-28T19:53:55.061984Z","caller":"traceutil/trace.go:171","msg":"trace[748790391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"267.13325ms","start":"2026-06-28T19:53:54.794822Z","end":"2026-06-28T19:53:55.061956Z","steps":["trace[748790391] 'agreement among raft nodes before linearized reading' (duration: 266.865004ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:55.062032Z","caller":"traceutil/trace.go:171","msg":"trace[2055642812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:646; }","duration":"270.202566ms","start":"2026-06-28T19:53:54.791815Z","end":"2026-06-28T19:53:55.062017Z","steps":["trace[2055642812] 'agreement among raft nodes before linearized reading' (duration: 269.793008ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:55.260627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.165983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:53:55.260769Z","caller":"traceutil/trace.go:171","msg":"trace[356018331] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"193.498001ms","start":"2026-06-28T19:53:55.067247Z","end":"2026-06-28T19:53:55.260745Z","steps":["trace[356018331] 'process raft request' (duration: 37.118903ms)","trace[356018331] 'compare' (duration: 156.01908ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:55.287605Z","caller":"traceutil/trace.go:171","msg":"trace[912152645] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"214.332849ms","start":"2026-06-28T19:53:55.073253Z","end":"2026-06-28T19:53:55.287586Z","steps":["trace[912152645] 'read index received' (duration: 31.197388ms)","trace[912152645] 'applied index is now lower than readState.Index' (duration: 183.134601ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:55.287713Z","caller":"traceutil/trace.go:171","msg":"trace[1891259638] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"207.149587ms","start":"2026-06-28T19:53:55.080551Z","end":"2026-06-28T19:53:55.287701Z","steps":["trace[1891259638] 'process raft request' (duration: 206.989234ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:55.287715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.441072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:55.287784Z","caller":"traceutil/trace.go:171","msg":"trace[1385046531] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:649; }","duration":"214.545474ms","start":"2026-06-28T19:53:55.073224Z","end":"2026-06-28T19:53:55.287769Z","steps":["trace[1385046531] 'agreement among raft nodes before linearized reading' (duration: 214.444732ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:55.288015Z","caller":"traceutil/trace.go:171","msg":"trace[334638525] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"220.03675ms","start":"2026-06-28T19:53:55.067587Z","end":"2026-06-28T19:53:55.287623Z","steps":["trace[334638525] 'process raft request' (duration: 219.833405ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:55.820998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.421266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:55.821106Z","caller":"traceutil/trace.go:171","msg":"trace[920583949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"310.588399ms","start":"2026-06-28T19:53:55.510492Z","end":"2026-06-28T19:53:55.82108Z","steps":["trace[920583949] 'range keys from in-memory index tree' (duration: 310.327624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:55.82125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:55.510474Z","time spent":"310.673742ms","remote":"127.0.0.1:51586","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-06-28T19:53:56.323085Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368671420461945,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-28T19:53:56.479232Z","caller":"traceutil/trace.go:171","msg":"trace[1162251970] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"656.976351ms","start":"2026-06-28T19:53:55.82223Z","end":"2026-06-28T19:53:56.479206Z","steps":["trace[1162251970] 'read index received' (duration: 656.118102ms)","trace[1162251970] 'applied index is now lower than readState.Index' (duration: 857.279µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:56.47939Z","caller":"traceutil/trace.go:171","msg":"trace[1696734505] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"768.082143ms","start":"2026-06-28T19:53:55.711287Z","end":"2026-06-28T19:53:56.479369Z","steps":["trace[1696734505] 'process raft request' (duration: 767.356818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:56.479489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.237886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-28T19:53:56.479517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:55.711255Z","time spent":"768.185905ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:53:56.479556Z","caller":"traceutil/trace.go:171","msg":"trace[465258506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"657.342788ms","start":"2026-06-28T19:53:55.822195Z","end":"2026-06-28T19:53:56.479537Z","steps":["trace[465258506] 'agreement among raft nodes before linearized reading' (duration: 657.102073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:56.479607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:55.822177Z","time spent":"657.41813ms","remote":"127.0.0.1:51582","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-06-28T19:53:57.230936Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368671420461947,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:53:57.623929Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.084719545s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-28T19:53:57.753595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.392034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:53:57.753813Z","caller":"traceutil/trace.go:171","msg":"trace[74879407] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:675; }","duration":"1.023588254s","start":"2026-06-28T19:53:56.730204Z","end":"2026-06-28T19:53:57.753792Z","steps":["trace[74879407] 'read index received' (duration: 893.946995ms)","trace[74879407] 'applied index is now lower than readState.Index' (duration: 129.639989ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:53:57.754225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.696259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:57.754274Z","caller":"traceutil/trace.go:171","msg":"trace[1825017524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"576.776391ms","start":"2026-06-28T19:53:57.177485Z","end":"2026-06-28T19:53:57.754261Z","steps":["trace[1825017524] 'agreement among raft nodes before linearized reading' (duration: 576.694989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:57.754308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:57.177472Z","time spent":"576.829332ms","remote":"127.0.0.1:51586","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-06-28T19:53:57.754335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022252076s","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-06-28T19:53:57.754391Z","caller":"traceutil/trace.go:171","msg":"trace[1633706345] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.215230361s","start":"2026-06-28T19:53:56.539132Z","end":"2026-06-28T19:53:57.754362Z","steps":["trace[1633706345] 'process raft request' (duration: 1.0849941s)","trace[1633706345] 'compare' (duration: 128.891583ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:53:57.754546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:56.539111Z","time spent":"1.215366634s","remote":"127.0.0.1:51812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:53:57.75442Z","caller":"traceutil/trace.go:171","msg":"trace[1532512827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:652; }","duration":"1.022375099s","start":"2026-06-28T19:53:56.732026Z","end":"2026-06-28T19:53:57.754401Z","steps":["trace[1532512827] 'agreement among raft nodes before linearized reading' (duration: 1.022131074s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:57.754562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.472599ms","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-06-28T19:53:57.754765Z","caller":"traceutil/trace.go:171","msg":"trace[1557409177] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:652; }","duration":"460.691993ms","start":"2026-06-28T19:53:57.294048Z","end":"2026-06-28T19:53:57.75474Z","steps":["trace[1557409177] 'agreement among raft nodes before linearized reading' (duration: 460.127551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:57.754875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:57.294037Z","time spent":"460.820905ms","remote":"127.0.0.1:51824","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-06-28T19:53:57.75468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:56.732018Z","time spent":"1.022646394s","remote":"127.0.0.1:51824","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-06-28T19:53:57.754337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024117805s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-28T19:53:57.755324Z","caller":"traceutil/trace.go:171","msg":"trace[1509207107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:652; }","duration":"1.025159376s","start":"2026-06-28T19:53:56.730151Z","end":"2026-06-28T19:53:57.75531Z","steps":["trace[1509207107] 'agreement among raft nodes before linearized reading' (duration: 1.023983742s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:57.755392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:56.730128Z","time spent":"1.025250309s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-28T19:53:57.754431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.665992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:57.75552Z","caller":"traceutil/trace.go:171","msg":"trace[1594810358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"243.785735ms","start":"2026-06-28T19:53:57.511717Z","end":"2026-06-28T19:53:57.755503Z","steps":["trace[1594810358] 'agreement among raft nodes before linearized reading' (duration: 242.677253ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:57.754419Z","caller":"traceutil/trace.go:171","msg":"trace[888325580] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"698.555586ms","start":"2026-06-28T19:53:57.055841Z","end":"2026-06-28T19:53:57.754396Z","steps":["trace[888325580] 'process raft request' (duration: 697.873502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:57.756465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:57.055803Z","time spent":"699.831692ms","remote":"127.0.0.1:51824","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-06-28T19:53:57.754337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.851418ms","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-06-28T19:53:57.756699Z","caller":"traceutil/trace.go:171","msg":"trace[775573434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:652; }","duration":"492.248398ms","start":"2026-06-28T19:53:57.264431Z","end":"2026-06-28T19:53:57.756679Z","steps":["trace[775573434] 'agreement among raft nodes before linearized reading' (duration: 489.748066ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:57.756745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:57.264418Z","time spent":"492.313959ms","remote":"127.0.0.1:51824","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-06-28T19:53:59.012297Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368671420461958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:53:59.435399Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.58827941s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-28T19:54:00.429175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.492515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:54:00.429277Z","caller":"traceutil/trace.go:171","msg":"trace[2116123098] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"1.917925504s","start":"2026-06-28T19:53:58.511329Z","end":"2026-06-28T19:54:00.429255Z","steps":["trace[2116123098] 'read index received' (duration: 924.193374ms)","trace[2116123098] 'applied index is now lower than readState.Index' (duration: 993.73065ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:54:00.429437Z","caller":"traceutil/trace.go:171","msg":"trace[1875791653] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"2.582413817s","start":"2026-06-28T19:53:57.847012Z","end":"2026-06-28T19:54:00.429426Z","steps":["trace[1875791653] 'process raft request' (duration: 1.588604946s)","trace[1875791653] 'compare' (duration: 993.117288ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:54:00.429487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:57.846994Z","time spent":"2.582469008s","remote":"127.0.0.1:51754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:54:00.429917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.918590408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-28T19:54:00.429994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.141748ms","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-06-28T19:54:00.429998Z","caller":"traceutil/trace.go:171","msg":"trace[1130258515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"1.918720241s","start":"2026-06-28T19:53:58.51126Z","end":"2026-06-28T19:54:00.42998Z","steps":["trace[1130258515] 'agreement among raft nodes before linearized reading' (duration: 1.918602698s)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:00.430041Z","caller":"traceutil/trace.go:171","msg":"trace[2091549016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"594.23164ms","start":"2026-06-28T19:53:59.835794Z","end":"2026-06-28T19:54:00.430026Z","steps":["trace[2091549016] 'agreement among raft nodes before linearized reading' (duration: 594.025736ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:00.430043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:58.511209Z","time spent":"1.918826543s","remote":"127.0.0.1:51586","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-06-28T19:54:00.430083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:59.835771Z","time spent":"594.304021ms","remote":"127.0.0.1:51824","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-06-28T19:54:00.43018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.061406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-28T19:54:00.43029Z","caller":"traceutil/trace.go:171","msg":"trace[828408226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:657; }","duration":"594.179519ms","start":"2026-06-28T19:53:59.836076Z","end":"2026-06-28T19:54:00.430256Z","steps":["trace[828408226] 'agreement among raft nodes before linearized reading' (duration: 593.817792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:00.430369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:59.836062Z","time spent":"594.293901ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-28T19:54:00.430292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.641518ms","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-06-28T19:54:00.430374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.660875ms","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-06-28T19:54:00.430455Z","caller":"traceutil/trace.go:171","msg":"trace[755728563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"594.849973ms","start":"2026-06-28T19:53:59.835595Z","end":"2026-06-28T19:54:00.430445Z","steps":["trace[755728563] 'agreement among raft nodes before linearized reading' (duration: 594.501696ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:00.430464Z","caller":"traceutil/trace.go:171","msg":"trace[928583916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"611.782968ms","start":"2026-06-28T19:53:59.818669Z","end":"2026-06-28T19:54:00.430452Z","steps":["trace[928583916] 'agreement among raft nodes before linearized reading' (duration: 611.640735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:00.430494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:59.835583Z","time spent":"594.899154ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-28T19:54:00.430494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:59.818645Z","time spent":"611.841699ms","remote":"127.0.0.1:51824","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-06-28T19:54:00.430862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116612655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-28T19:54:00.431504Z","caller":"traceutil/trace.go:171","msg":"trace[403061781] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:657; }","duration":"1.117284049s","start":"2026-06-28T19:53:59.314203Z","end":"2026-06-28T19:54:00.431487Z","steps":["trace[403061781] 'agreement among raft nodes before linearized reading' (duration: 1.11536999s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:00.431635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:59.314184Z","time spent":"1.117434942s","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"info","ts":"2026-06-28T19:54:00.556893Z","caller":"traceutil/trace.go:171","msg":"trace[1690794524] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"121.982687ms","start":"2026-06-28T19:54:00.434875Z","end":"2026-06-28T19:54:00.556857Z","steps":["trace[1690794524] 'process raft request' (duration: 112.2022ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:00.557464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.616201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:54:00.557048Z","caller":"traceutil/trace.go:171","msg":"trace[176490244] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"120.537458ms","start":"2026-06-28T19:54:00.43649Z","end":"2026-06-28T19:54:00.557028Z","steps":["trace[176490244] 'process raft request' (duration: 120.459207ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:00.557512Z","caller":"traceutil/trace.go:171","msg":"trace[1332317350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"118.695642ms","start":"2026-06-28T19:54:00.438805Z","end":"2026-06-28T19:54:00.557501Z","steps":["trace[1332317350] 'agreement among raft nodes before linearized reading' (duration: 118.61185ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:00.557108Z","caller":"traceutil/trace.go:171","msg":"trace[603196770] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"122.224602ms","start":"2026-06-28T19:54:00.434874Z","end":"2026-06-28T19:54:00.557099Z","steps":["trace[603196770] 'process raft request' (duration: 121.882865ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:00.557103Z","caller":"traceutil/trace.go:171","msg":"trace[333630031] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"121.521208ms","start":"2026-06-28T19:54:00.435552Z","end":"2026-06-28T19:54:00.557073Z","steps":["trace[333630031] 'process raft request' (duration: 121.339665ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:00.557166Z","caller":"traceutil/trace.go:171","msg":"trace[302803470] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"122.12541ms","start":"2026-06-28T19:54:00.435016Z","end":"2026-06-28T19:54:00.557142Z","steps":["trace[302803470] 'process raft request' (duration: 121.834814ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:00.557197Z","caller":"traceutil/trace.go:171","msg":"trace[1528222701] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"122.217452ms","start":"2026-06-28T19:54:00.43495Z","end":"2026-06-28T19:54:00.557168Z","steps":["trace[1528222701] 'process raft request' (duration: 121.865225ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:00.557282Z","caller":"traceutil/trace.go:171","msg":"trace[189042115] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"119.790774ms","start":"2026-06-28T19:54:00.437476Z","end":"2026-06-28T19:54:00.557267Z","steps":["trace[189042115] 'process raft request' (duration: 119.516598ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:00.557345Z","caller":"traceutil/trace.go:171","msg":"trace[1222813118] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"119.744733ms","start":"2026-06-28T19:54:00.437587Z","end":"2026-06-28T19:54:00.557332Z","steps":["trace[1222813118] 'process raft request' (duration: 119.628981ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:00.557396Z","caller":"traceutil/trace.go:171","msg":"trace[783703448] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:682; }","duration":"118.55613ms","start":"2026-06-28T19:54:00.43883Z","end":"2026-06-28T19:54:00.557386Z","steps":["trace[783703448] 'read index received' (duration: 79.846876ms)","trace[783703448] 'applied index is now lower than readState.Index' (duration: 38.708303ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:54:02.837262Z","caller":"traceutil/trace.go:171","msg":"trace[653048600] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"275.521115ms","start":"2026-06-28T19:54:02.561723Z","end":"2026-06-28T19:54:02.837244Z","steps":["trace[653048600] 'process raft request' (duration: 273.429864ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:02.837242Z","caller":"traceutil/trace.go:171","msg":"trace[1005936747] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"274.509155ms","start":"2026-06-28T19:54:02.562708Z","end":"2026-06-28T19:54:02.837217Z","steps":["trace[1005936747] 'read index received' (duration: 272.392674ms)","trace[1005936747] 'applied index is now lower than readState.Index' (duration: 2.115201ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:54:02.837381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.655048ms","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-06-28T19:54:02.837429Z","caller":"traceutil/trace.go:171","msg":"trace[951663858] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:678; }","duration":"274.715039ms","start":"2026-06-28T19:54:02.562702Z","end":"2026-06-28T19:54:02.837417Z","steps":["trace[951663858] 'agreement among raft nodes before linearized reading' (duration: 274.613337ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:02.83776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.183649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-28T19:54:02.837805Z","caller":"traceutil/trace.go:171","msg":"trace[692359585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:678; }","duration":"274.25243ms","start":"2026-06-28T19:54:02.563542Z","end":"2026-06-28T19:54:02.837795Z","steps":["trace[692359585] 'agreement among raft nodes before linearized reading' (duration: 274.122317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:02.838037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.557286ms","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-06-28T19:54:02.83829Z","caller":"traceutil/trace.go:171","msg":"trace[446935993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:678; }","duration":"274.886203ms","start":"2026-06-28T19:54:02.563386Z","end":"2026-06-28T19:54:02.838272Z","steps":["trace[446935993] 'agreement among raft nodes before linearized reading' (duration: 274.454985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:02.838545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.109037ms","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-06-28T19:54:02.838609Z","caller":"traceutil/trace.go:171","msg":"trace[1618248378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:678; }","duration":"275.204789ms","start":"2026-06-28T19:54:02.563385Z","end":"2026-06-28T19:54:02.83859Z","steps":["trace[1618248378] 'agreement among raft nodes before linearized reading' (duration: 274.048436ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:05.559734Z","caller":"traceutil/trace.go:171","msg":"trace[812614116] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"281.90548ms","start":"2026-06-28T19:54:05.277797Z","end":"2026-06-28T19:54:05.559702Z","steps":["trace[812614116] 'process raft request' (duration: 281.687016ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:13.120297Z","caller":"traceutil/trace.go:171","msg":"trace[2013416808] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"213.117314ms","start":"2026-06-28T19:54:12.907153Z","end":"2026-06-28T19:54:13.12027Z","steps":["trace[2013416808] 'read index received' (duration: 212.989551ms)","trace[2013416808] 'applied index is now lower than readState.Index' (duration: 126.763µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:54:13.120419Z","caller":"traceutil/trace.go:171","msg":"trace[547874713] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"228.220352ms","start":"2026-06-28T19:54:12.892167Z","end":"2026-06-28T19:54:13.120388Z","steps":["trace[547874713] 'process raft request' (duration: 227.954907ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:13.120727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.536411ms","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-06-28T19:54:13.120779Z","caller":"traceutil/trace.go:171","msg":"trace[1547612700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"213.639123ms","start":"2026-06-28T19:54:12.907125Z","end":"2026-06-28T19:54:13.120765Z","steps":["trace[1547612700] 'agreement among raft nodes before linearized reading' (duration: 213.284826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:13.193512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.164689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-28T19:54:13.193544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.275392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-28T19:54:13.193564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.993097ms","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-06-28T19:54:13.193589Z","caller":"traceutil/trace.go:171","msg":"trace[1965770777] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"267.271822ms","start":"2026-06-28T19:54:12.926301Z","end":"2026-06-28T19:54:13.193572Z","steps":["trace[1965770777] 'agreement among raft nodes before linearized reading' (duration: 267.085839ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:13.193606Z","caller":"traceutil/trace.go:171","msg":"trace[658573940] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"197.246591ms","start":"2026-06-28T19:54:12.99633Z","end":"2026-06-28T19:54:13.193576Z","steps":["trace[658573940] 'process raft request' (duration: 196.849364ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:13.193619Z","caller":"traceutil/trace.go:171","msg":"trace[2103614691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"280.392273ms","start":"2026-06-28T19:54:12.913213Z","end":"2026-06-28T19:54:13.193605Z","steps":["trace[2103614691] 'agreement among raft nodes before linearized reading' (duration: 280.226861ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:13.193642Z","caller":"traceutil/trace.go:171","msg":"trace[89964119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"273.103349ms","start":"2026-06-28T19:54:12.920518Z","end":"2026-06-28T19:54:13.193621Z","steps":["trace[89964119] 'agreement among raft nodes before linearized reading' (duration: 272.863074ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:15.239356Z","caller":"traceutil/trace.go:171","msg":"trace[1609705249] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"107.557711ms","start":"2026-06-28T19:54:15.131779Z","end":"2026-06-28T19:54:15.239337Z","steps":["trace[1609705249] 'process raft request' (duration: 106.816327ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:15.390234Z","caller":"traceutil/trace.go:171","msg":"trace[777809440] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"147.409889ms","start":"2026-06-28T19:54:15.242805Z","end":"2026-06-28T19:54:15.390215Z","steps":["trace[777809440] 'process raft request' (duration: 147.291406ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:15.390419Z","caller":"traceutil/trace.go:171","msg":"trace[551848587] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"146.603854ms","start":"2026-06-28T19:54:15.243801Z","end":"2026-06-28T19:54:15.390405Z","steps":["trace[551848587] 'process raft request' (duration: 146.3868ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:15.390571Z","caller":"traceutil/trace.go:171","msg":"trace[1392123297] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"146.666007ms","start":"2026-06-28T19:54:15.243896Z","end":"2026-06-28T19:54:15.390562Z","steps":["trace[1392123297] 'process raft request' (duration: 146.460642ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:17.306957Z","caller":"traceutil/trace.go:171","msg":"trace[107328776] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:848; }","duration":"126.336207ms","start":"2026-06-28T19:54:17.180588Z","end":"2026-06-28T19:54:17.306924Z","steps":["trace[107328776] 'read index received' (duration: 126.042272ms)","trace[107328776] 'applied index is now lower than readState.Index' (duration: 292.295µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:54:17.307074Z","caller":"traceutil/trace.go:171","msg":"trace[63447964] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"184.160784ms","start":"2026-06-28T19:54:17.122857Z","end":"2026-06-28T19:54:17.307018Z","steps":["trace[63447964] 'process raft request' (duration: 183.806388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:17.307208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.590341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:54:17.307305Z","caller":"traceutil/trace.go:171","msg":"trace[829331852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:818; }","duration":"126.723903ms","start":"2026-06-28T19:54:17.180556Z","end":"2026-06-28T19:54:17.30728Z","steps":["trace[829331852] 'agreement among raft nodes before linearized reading' (duration: 126.533ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:17.821489Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368671420462335,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:54:18.322326Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368671420462335,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:54:18.822939Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368671420462335,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:54:19.323637Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368671420462335,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:54:19.512656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000230877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-28T19:54:19.512815Z","caller":"traceutil/trace.go:171","msg":"trace[825580669] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000449611s","start":"2026-06-28T19:54:17.512335Z","end":"2026-06-28T19:54:19.512784Z","steps":["trace[825580669] 'agreement among raft nodes before linearized reading' (duration: 2.000254058s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:19.51321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:17.512318Z","time spent":"2.000845558s","remote":"127.0.0.1:51582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/28 19:54:19 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-28T19:54:19.569073Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.117163943s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-28T19:54:19.796259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.856697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:54:19.796376Z","caller":"traceutil/trace.go:171","msg":"trace[1244322335] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"2.483042872s","start":"2026-06-28T19:54:17.31331Z","end":"2026-06-28T19:54:19.796352Z","steps":["trace[1244322335] 'process raft request' (duration: 2.256005572s)","trace[1244322335] 'compare' (duration: 226.414499ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:54:19.796455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:17.313278Z","time spent":"2.483139164s","remote":"127.0.0.1:51824","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-06-28T19:54:19.869662Z","caller":"traceutil/trace.go:171","msg":"trace[1029785091] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"1.936665539s","start":"2026-06-28T19:54:17.932968Z","end":"2026-06-28T19:54:19.869634Z","steps":["trace[1029785091] 'process raft request' (duration: 1.936430805s)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:19.869686Z","caller":"traceutil/trace.go:171","msg":"trace[1701151157] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"1.553172248s","start":"2026-06-28T19:54:18.316489Z","end":"2026-06-28T19:54:19.869662Z","steps":["trace[1701151157] 'process raft request' (duration: 1.553088357s)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:19.86977Z","caller":"traceutil/trace.go:171","msg":"trace[110116412] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:849; }","duration":"2.548365365s","start":"2026-06-28T19:54:17.321389Z","end":"2026-06-28T19:54:19.869755Z","steps":["trace[110116412] 'read index received' (duration: 2.247902097s)","trace[110116412] 'applied index is now lower than readState.Index' (duration: 300.462068ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:54:19.869835Z","caller":"traceutil/trace.go:171","msg":"trace[614406545] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"351.723187ms","start":"2026-06-28T19:54:19.518107Z","end":"2026-06-28T19:54:19.869831Z","steps":["trace[614406545] 'process raft request' (duration: 351.509632ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:19.869893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:19.518058Z","time spent":"351.793708ms","remote":"127.0.0.1:51664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:54:19.86996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:18.316453Z","time spent":"1.55330004s","remote":"127.0.0.1:51812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:54:19.870073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.548692752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-28T19:54:19.870094Z","caller":"traceutil/trace.go:171","msg":"trace[2076002197] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:822; }","duration":"2.548764923s","start":"2026-06-28T19:54:17.321324Z","end":"2026-06-28T19:54:19.870089Z","steps":["trace[2076002197] 'agreement among raft nodes before linearized reading' (duration: 2.548672831s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:19.870109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:17.321303Z","time spent":"2.548802294s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-28T19:54:19.869983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:17.932903Z","time spent":"1.936838112s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:54:19.870109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040047089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:54:19.870141Z","caller":"traceutil/trace.go:171","msg":"trace[1883764391] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:822; }","duration":"1.040194981s","start":"2026-06-28T19:54:18.829938Z","end":"2026-06-28T19:54:19.870133Z","steps":["trace[1883764391] 'agreement among raft nodes before linearized reading' (duration: 1.04013499s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:19.87017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:18.829912Z","time spent":"1.040251882s","remote":"127.0.0.1:51918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-06-28T19:54:19.870242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.474648395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-28T19:54:19.870271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.47430924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-06-28T19:54:19.870283Z","caller":"traceutil/trace.go:171","msg":"trace[1261846091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:822; }","duration":"2.474740027s","start":"2026-06-28T19:54:17.395534Z","end":"2026-06-28T19:54:19.870274Z","steps":["trace[1261846091] 'agreement among raft nodes before linearized reading' (duration: 2.474525003s)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:19.870289Z","caller":"traceutil/trace.go:171","msg":"trace[620777182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:822; }","duration":"2.474370561s","start":"2026-06-28T19:54:17.395912Z","end":"2026-06-28T19:54:19.870283Z","steps":["trace[620777182] 'agreement among raft nodes before linearized reading' (duration: 2.4743257s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:19.870303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:17.395904Z","time spent":"2.474396311s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-28T19:54:19.870334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:17.39551Z","time spent":"2.474806948s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-28T19:54:19.870336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.077174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-28T19:54:19.870351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.450532456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:54:19.870359Z","caller":"traceutil/trace.go:171","msg":"trace[1545772034] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:822; }","duration":"125.159885ms","start":"2026-06-28T19:54:19.745192Z","end":"2026-06-28T19:54:19.870352Z","steps":["trace[1545772034] 'agreement among raft nodes before linearized reading' (duration: 125.126424ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:19.870365Z","caller":"traceutil/trace.go:171","msg":"trace[357451270] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:822; }","duration":"1.450603257s","start":"2026-06-28T19:54:18.419758Z","end":"2026-06-28T19:54:19.870361Z","steps":["trace[357451270] 'agreement among raft nodes before linearized reading' (duration: 1.450577557s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:19.87038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:18.419728Z","time spent":"1.450648287s","remote":"127.0.0.1:51902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-28T19:54:19.87046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.474741347s","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-06-28T19:54:19.870481Z","caller":"traceutil/trace.go:171","msg":"trace[1105368688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:822; }","duration":"2.474786567s","start":"2026-06-28T19:54:17.395689Z","end":"2026-06-28T19:54:19.870476Z","steps":["trace[1105368688] 'agreement among raft nodes before linearized reading' (duration: 2.474734566s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:19.870495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:17.395673Z","time spent":"2.474819158s","remote":"127.0.0.1:51824","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-06-28T19:54:19.870461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.864387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:54:19.870551Z","caller":"traceutil/trace.go:171","msg":"trace[483839433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:822; }","duration":"348.992269ms","start":"2026-06-28T19:54:19.521553Z","end":"2026-06-28T19:54:19.870545Z","steps":["trace[483839433] 'agreement among raft nodes before linearized reading' (duration: 348.892488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:19.87056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.250379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-28T19:54:19.870573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:19.521527Z","time spent":"349.039851ms","remote":"127.0.0.1:51586","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-06-28T19:54:19.87059Z","caller":"traceutil/trace.go:171","msg":"trace[127160774] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:822; }","duration":"554.306649ms","start":"2026-06-28T19:54:19.316275Z","end":"2026-06-28T19:54:19.870582Z","steps":["trace[127160774] 'agreement among raft nodes before linearized reading' (duration: 554.198097ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:19.870621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:19.316254Z","time spent":"554.358371ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-06-28T19:54:20.459592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:19.872032Z","time spent":"587.555138ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-28T19:54:20.55208Z","caller":"traceutil/trace.go:171","msg":"trace[1867608694] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"677.29218ms","start":"2026-06-28T19:54:19.874769Z","end":"2026-06-28T19:54:20.552061Z","steps":["trace[1867608694] 'process raft request' (duration: 677.127787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:20.552232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:19.874738Z","time spent":"677.408822ms","remote":"127.0.0.1:51824","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-06-28T19:54:20.552238Z","caller":"traceutil/trace.go:171","msg":"trace[1522114742] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"676.990365ms","start":"2026-06-28T19:54:19.875232Z","end":"2026-06-28T19:54:20.552222Z","steps":["trace[1522114742] 'process raft request' (duration: 676.791822ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:20.552321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:19.875218Z","time spent":"677.059136ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:54:20.55239Z","caller":"traceutil/trace.go:171","msg":"trace[1220459769] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"677.146218ms","start":"2026-06-28T19:54:19.87523Z","end":"2026-06-28T19:54:20.552376Z","steps":["trace[1220459769] 'process raft request' (duration: 676.933334ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:20.552471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:19.875203Z","time spent":"677.229569ms","remote":"127.0.0.1:51824","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-06-28T19:54:20.55248Z","caller":"traceutil/trace.go:171","msg":"trace[1688772484] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"676.460554ms","start":"2026-06-28T19:54:19.87601Z","end":"2026-06-28T19:54:20.55247Z","steps":["trace[1688772484] 'process raft request' (duration: 676.323472ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:20.552533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:19.876005Z","time spent":"676.500816ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:54:20.921008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.292363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:54:20.921364Z","caller":"traceutil/trace.go:171","msg":"trace[1348870591] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:854; }","duration":"444.227186ms","start":"2026-06-28T19:54:20.477124Z","end":"2026-06-28T19:54:20.921351Z","steps":["trace[1348870591] 'read index received' (duration: 74.800373ms)","trace[1348870591] 'applied index is now lower than readState.Index' (duration: 369.425053ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:54:20.921425Z","caller":"traceutil/trace.go:171","msg":"trace[1326687200] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"460.122942ms","start":"2026-06-28T19:54:20.461292Z","end":"2026-06-28T19:54:20.921415Z","steps":["trace[1326687200] 'process raft request' (duration: 137.347982ms)","trace[1326687200] 'compare' (duration: 322.1518ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:54:20.921461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:20.46125Z","time spent":"460.189624ms","remote":"127.0.0.1:51608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:54:20.92169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.565352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ph2vd\" ","response":"range_response_count:1 size:4043"} {"level":"info","ts":"2026-06-28T19:54:20.921706Z","caller":"traceutil/trace.go:171","msg":"trace[840918861] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ph2vd; range_end:; response_count:1; response_revision:827; }","duration":"444.616502ms","start":"2026-06-28T19:54:20.477084Z","end":"2026-06-28T19:54:20.9217Z","steps":["trace[840918861] 'agreement among raft nodes before linearized reading' (duration: 444.49573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:20.921723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:20.477064Z","time spent":"444.653663ms","remote":"127.0.0.1:51754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":4066,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ph2vd\" "} {"level":"warn","ts":"2026-06-28T19:54:20.921855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.948085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-28T19:54:20.921916Z","caller":"traceutil/trace.go:171","msg":"trace[1179165505] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:827; }","duration":"257.065677ms","start":"2026-06-28T19:54:20.664837Z","end":"2026-06-28T19:54:20.921902Z","steps":["trace[1179165505] 'agreement among raft nodes before linearized reading' (duration: 256.946085ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:21.27699Z","caller":"traceutil/trace.go:171","msg":"trace[730595973] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"346.273808ms","start":"2026-06-28T19:54:20.930693Z","end":"2026-06-28T19:54:21.276966Z","steps":["trace[730595973] 'process raft request' (duration: 305.764497ms)","trace[730595973] 'compare' (duration: 40.384889ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:54:21.277124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:20.930675Z","time spent":"346.37964ms","remote":"127.0.0.1:51754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4214,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:54:34.002537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.509172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:54:34.002638Z","caller":"traceutil/trace.go:171","msg":"trace[1012382036] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"247.403729ms","start":"2026-06-28T19:54:33.755218Z","end":"2026-06-28T19:54:34.002621Z","steps":["trace[1012382036] 'read index received' (duration: 79.041µs)","trace[1012382036] 'applied index is now lower than readState.Index' (duration: 247.323658ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:54:34.00266Z","caller":"traceutil/trace.go:171","msg":"trace[1455061694] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"356.621578ms","start":"2026-06-28T19:54:33.646015Z","end":"2026-06-28T19:54:34.002637Z","steps":["trace[1455061694] 'process raft request' (duration: 58.937644ms)","trace[1455061694] 'compare' (duration: 297.392759ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:54:34.002787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:33.645995Z","time spent":"356.73427ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:54:34.003291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-28T19:54:34.003371Z","caller":"traceutil/trace.go:171","msg":"trace[1181791431] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:988; }","duration":"248.191003ms","start":"2026-06-28T19:54:33.755166Z","end":"2026-06-28T19:54:34.003357Z","steps":["trace[1181791431] 'agreement among raft nodes before linearized reading' (duration: 248.085911ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:42.270521Z","caller":"traceutil/trace.go:171","msg":"trace[391084509] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"164.047462ms","start":"2026-06-28T19:54:42.106455Z","end":"2026-06-28T19:54:42.270502Z","steps":["trace[391084509] 'process raft request' (duration: 163.94255ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:42.926232Z","caller":"traceutil/trace.go:171","msg":"trace[360091050] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"129.553627ms","start":"2026-06-28T19:54:42.796638Z","end":"2026-06-28T19:54:42.926191Z","steps":["trace[360091050] 'process raft request' (duration: 54.068511ms)","trace[360091050] 'compare' (duration: 75.342974ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:54:42.94503Z","caller":"traceutil/trace.go:171","msg":"trace[2117243093] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"146.050438ms","start":"2026-06-28T19:54:42.798959Z","end":"2026-06-28T19:54:42.945009Z","steps":["trace[2117243093] 'process raft request' (duration: 145.860865ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:42.945191Z","caller":"traceutil/trace.go:171","msg":"trace[1653846846] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"146.125729ms","start":"2026-06-28T19:54:42.799037Z","end":"2026-06-28T19:54:42.945163Z","steps":["trace[1653846846] 'process raft request' (duration: 145.938026ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:44.472614Z","caller":"traceutil/trace.go:171","msg":"trace[1836233767] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"155.753969ms","start":"2026-06-28T19:54:44.316833Z","end":"2026-06-28T19:54:44.472587Z","steps":["trace[1836233767] 'process raft request' (duration: 155.611217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:44.88517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.945681ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-28T19:54:45.130864Z","caller":"traceutil/trace.go:171","msg":"trace[135483828] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"331.860924ms","start":"2026-06-28T19:54:44.798979Z","end":"2026-06-28T19:54:45.13084Z","steps":["trace[135483828] 'process raft request' (duration: 331.669692ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:45.131083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:44.798959Z","time spent":"331.984196ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:54:45.254494Z","caller":"traceutil/trace.go:171","msg":"trace[2125700330] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1070; }","duration":"323.820093ms","start":"2026-06-28T19:54:44.930646Z","end":"2026-06-28T19:54:45.254466Z","steps":["trace[2125700330] 'read index received' (duration: 200.000035ms)","trace[2125700330] 'applied index is now lower than readState.Index' (duration: 123.818908ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:54:45.254741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.896203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-28T19:54:45.25478Z","caller":"traceutil/trace.go:171","msg":"trace[410040995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1035; }","duration":"306.003725ms","start":"2026-06-28T19:54:44.948766Z","end":"2026-06-28T19:54:45.25477Z","steps":["trace[410040995] 'agreement among raft nodes before linearized reading' (duration: 305.854263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:45.254815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:44.948745Z","time spent":"306.062465ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-28T19:54:45.255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.330401ms","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-06-28T19:54:45.255124Z","caller":"traceutil/trace.go:171","msg":"trace[8208817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1035; }","duration":"324.493623ms","start":"2026-06-28T19:54:44.930605Z","end":"2026-06-28T19:54:45.255099Z","steps":["trace[8208817] 'agreement among raft nodes before linearized reading' (duration: 324.011096ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:45.255257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:44.930592Z","time spent":"324.644986ms","remote":"127.0.0.1:51824","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-06-28T19:54:45.255023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.96891ms","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-06-28T19:54:45.255416Z","caller":"traceutil/trace.go:171","msg":"trace[1526236373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1035; }","duration":"305.396006ms","start":"2026-06-28T19:54:44.949975Z","end":"2026-06-28T19:54:45.255371Z","steps":["trace[1526236373] 'agreement among raft nodes before linearized reading' (duration: 304.843708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:45.255523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:44.949964Z","time spent":"305.530098ms","remote":"127.0.0.1:51824","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-06-28T19:54:45.255691Z","caller":"traceutil/trace.go:171","msg":"trace[956833961] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"366.716446ms","start":"2026-06-28T19:54:44.888745Z","end":"2026-06-28T19:54:45.255462Z","steps":["trace[956833961] 'process raft request' (duration: 354.196399ms)","trace[956833961] 'compare' (duration: 11.402511ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:54:45.256022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:44.88872Z","time spent":"367.167544ms","remote":"127.0.0.1:51824","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-06-28T19:54:45.493013Z","caller":"traceutil/trace.go:171","msg":"trace[1553922805] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"233.314245ms","start":"2026-06-28T19:54:45.259672Z","end":"2026-06-28T19:54:45.492986Z","steps":["trace[1553922805] 'process raft request' (duration: 164.345855ms)","trace[1553922805] 'compare' (duration: 68.767677ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:54:45.493281Z","caller":"traceutil/trace.go:171","msg":"trace[2136294566] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"233.0229ms","start":"2026-06-28T19:54:45.260237Z","end":"2026-06-28T19:54:45.49326Z","steps":["trace[2136294566] 'process raft request' (duration: 232.930718ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:45.493322Z","caller":"traceutil/trace.go:171","msg":"trace[966454738] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"233.434316ms","start":"2026-06-28T19:54:45.259873Z","end":"2026-06-28T19:54:45.493307Z","steps":["trace[966454738] 'process raft request' (duration: 233.049731ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:46.602616Z","caller":"traceutil/trace.go:171","msg":"trace[1677677263] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"224.83621ms","start":"2026-06-28T19:54:46.377756Z","end":"2026-06-28T19:54:46.602592Z","steps":["trace[1677677263] 'read index received' (duration: 224.588386ms)","trace[1677677263] 'applied index is now lower than readState.Index' (duration: 246.724µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:54:46.60275Z","caller":"traceutil/trace.go:171","msg":"trace[1725715431] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"311.375556ms","start":"2026-06-28T19:54:46.291342Z","end":"2026-06-28T19:54:46.602718Z","steps":["trace[1725715431] 'process raft request' (duration: 311.103792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:46.602798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.016792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:54:46.602835Z","caller":"traceutil/trace.go:171","msg":"trace[1200902984] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1039; }","duration":"225.107053ms","start":"2026-06-28T19:54:46.377718Z","end":"2026-06-28T19:54:46.602825Z","steps":["trace[1200902984] 'agreement among raft nodes before linearized reading' (duration: 225.006542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:46.602917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:54:46.291312Z","time spent":"311.519197ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:54:47.240032Z","caller":"traceutil/trace.go:171","msg":"trace[113504416] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"104.122538ms","start":"2026-06-28T19:54:47.13589Z","end":"2026-06-28T19:54:47.240012Z","steps":["trace[113504416] 'process raft request' (duration: 103.998676ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:47.454932Z","caller":"traceutil/trace.go:171","msg":"trace[20956465] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"124.571461ms","start":"2026-06-28T19:54:47.330338Z","end":"2026-06-28T19:54:47.454909Z","steps":["trace[20956465] 'process raft request' (duration: 124.364817ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:47.605399Z","caller":"traceutil/trace.go:171","msg":"trace[113328900] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"104.053857ms","start":"2026-06-28T19:54:47.501308Z","end":"2026-06-28T19:54:47.605362Z","steps":["trace[113328900] 'process raft request' (duration: 65.566649ms)","trace[113328900] 'compare' (duration: 38.041572ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:54:47.605441Z","caller":"traceutil/trace.go:171","msg":"trace[1153739555] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"103.50952ms","start":"2026-06-28T19:54:47.501856Z","end":"2026-06-28T19:54:47.605365Z","steps":["trace[1153739555] 'process raft request' (duration: 103.47533ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:49.592281Z","caller":"traceutil/trace.go:171","msg":"trace[977671208] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"125.733138ms","start":"2026-06-28T19:54:49.466502Z","end":"2026-06-28T19:54:49.592235Z","steps":["trace[977671208] 'process raft request' (duration: 96.776724ms)","trace[977671208] 'compare' (duration: 28.802191ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:54:49.592395Z","caller":"traceutil/trace.go:171","msg":"trace[180881623] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"123.043749ms","start":"2026-06-28T19:54:49.469325Z","end":"2026-06-28T19:54:49.592368Z","steps":["trace[180881623] 'process raft request' (duration: 122.884847ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:53.87279Z","caller":"traceutil/trace.go:171","msg":"trace[2002941761] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"191.607756ms","start":"2026-06-28T19:54:53.681157Z","end":"2026-06-28T19:54:53.872765Z","steps":["trace[2002941761] 'read index received' (duration: 191.463343ms)","trace[2002941761] 'applied index is now lower than readState.Index' (duration: 143.242µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:54:53.87287Z","caller":"traceutil/trace.go:171","msg":"trace[266045698] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"251.368892ms","start":"2026-06-28T19:54:53.621476Z","end":"2026-06-28T19:54:53.872845Z","steps":["trace[266045698] 'process raft request' (duration: 251.107417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:53.873104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.93221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-28T19:54:53.873119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.946695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-28T19:54:53.87316Z","caller":"traceutil/trace.go:171","msg":"trace[885387374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1065; }","duration":"192.008461ms","start":"2026-06-28T19:54:53.681138Z","end":"2026-06-28T19:54:53.873146Z","steps":["trace[885387374] 'agreement among raft nodes before linearized reading' (duration: 191.757607ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:53.873172Z","caller":"traceutil/trace.go:171","msg":"trace[1036496190] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1065; }","duration":"119.026476ms","start":"2026-06-28T19:54:53.754131Z","end":"2026-06-28T19:54:53.873157Z","steps":["trace[1036496190] 'agreement among raft nodes before linearized reading' (duration: 118.941005ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:54:53.873226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.807833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-28T19:54:53.873111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.727108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-28T19:54:53.873285Z","caller":"traceutil/trace.go:171","msg":"trace[1654060403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1065; }","duration":"162.873534ms","start":"2026-06-28T19:54:53.710396Z","end":"2026-06-28T19:54:53.873269Z","steps":["trace[1654060403] 'agreement among raft nodes before linearized reading' (duration: 162.748102ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:53.873314Z","caller":"traceutil/trace.go:171","msg":"trace[309733078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1065; }","duration":"191.955561ms","start":"2026-06-28T19:54:53.681333Z","end":"2026-06-28T19:54:53.873288Z","steps":["trace[309733078] 'agreement among raft nodes before linearized reading' (duration: 191.632076ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:54:58.909141Z","caller":"traceutil/trace.go:171","msg":"trace[567349564] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"124.929116ms","start":"2026-06-28T19:54:58.784185Z","end":"2026-06-28T19:54:58.909114Z","steps":["trace[567349564] 'process raft request' (duration: 124.776824ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:55:02.145934Z","caller":"traceutil/trace.go:171","msg":"trace[53410889] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"152.716088ms","start":"2026-06-28T19:55:01.993193Z","end":"2026-06-28T19:55:02.145909Z","steps":["trace[53410889] 'process raft request' (duration: 152.528805ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:55:06.477271Z","caller":"traceutil/trace.go:171","msg":"trace[902900579] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1147; }","duration":"112.063682ms","start":"2026-06-28T19:55:06.36519Z","end":"2026-06-28T19:55:06.477253Z","steps":["trace[902900579] 'read index received' (duration: 111.92024ms)","trace[902900579] 'applied index is now lower than readState.Index' (duration: 142.842µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:55:06.477381Z","caller":"traceutil/trace.go:171","msg":"trace[1654989118] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"133.607593ms","start":"2026-06-28T19:55:06.343731Z","end":"2026-06-28T19:55:06.477339Z","steps":["trace[1654989118] 'process raft request' (duration: 133.426191ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:55:06.477477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.616996ms","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-06-28T19:55:06.477498Z","caller":"traceutil/trace.go:171","msg":"trace[2066424797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1106; }","duration":"111.663847ms","start":"2026-06-28T19:55:06.365829Z","end":"2026-06-28T19:55:06.477493Z","steps":["trace[2066424797] 'agreement among raft nodes before linearized reading' (duration: 111.622096ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:55:06.477422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.222984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-06-28T19:55:06.477581Z","caller":"traceutil/trace.go:171","msg":"trace[1373758559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1106; }","duration":"112.439247ms","start":"2026-06-28T19:55:06.365139Z","end":"2026-06-28T19:55:06.477578Z","steps":["trace[1373758559] 'agreement among raft nodes before linearized reading' (duration: 112.197104ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:55:08.648427Z","caller":"traceutil/trace.go:171","msg":"trace[649041518] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1153; }","duration":"137.182495ms","start":"2026-06-28T19:55:08.511206Z","end":"2026-06-28T19:55:08.648389Z","steps":["trace[649041518] 'read index received' (duration: 136.914992ms)","trace[649041518] 'applied index is now lower than readState.Index' (duration: 265.793µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:55:08.648507Z","caller":"traceutil/trace.go:171","msg":"trace[222642766] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"161.982127ms","start":"2026-06-28T19:55:08.486485Z","end":"2026-06-28T19:55:08.648468Z","steps":["trace[222642766] 'process raft request' (duration: 161.676643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:55:08.648604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.380098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:55:08.648654Z","caller":"traceutil/trace.go:171","msg":"trace[1555550959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1112; }","duration":"137.482518ms","start":"2026-06-28T19:55:08.511163Z","end":"2026-06-28T19:55:08.648646Z","steps":["trace[1555550959] 'agreement among raft nodes before linearized reading' (duration: 137.330026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:55:08.648817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.811059ms","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-06-28T19:55:08.648848Z","caller":"traceutil/trace.go:171","msg":"trace[451648092] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1112; }","duration":"112.864909ms","start":"2026-06-28T19:55:08.535978Z","end":"2026-06-28T19:55:08.648843Z","steps":["trace[451648092] 'agreement among raft nodes before linearized reading' (duration: 112.721617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:55:08.648861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.218445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-28T19:55:08.648958Z","caller":"traceutil/trace.go:171","msg":"trace[1939291178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1112; }","duration":"125.353137ms","start":"2026-06-28T19:55:08.523566Z","end":"2026-06-28T19:55:08.648919Z","steps":["trace[1939291178] 'agreement among raft nodes before linearized reading' (duration: 125.093484ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:55:11.231307Z","caller":"traceutil/trace.go:171","msg":"trace[928687497] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"105.454604ms","start":"2026-06-28T19:55:11.125812Z","end":"2026-06-28T19:55:11.231267Z","steps":["trace[928687497] 'process raft request' (duration: 87.603908ms)","trace[928687497] 'compare' (duration: 17.628563ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:55:20.840856Z","caller":"traceutil/trace.go:171","msg":"trace[1505049736] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"203.01942ms","start":"2026-06-28T19:55:20.637801Z","end":"2026-06-28T19:55:20.84082Z","steps":["trace[1505049736] 'process raft request' (duration: 202.912069ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:55:37.16967Z","caller":"traceutil/trace.go:171","msg":"trace[1919048265] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"113.236145ms","start":"2026-06-28T19:55:37.056416Z","end":"2026-06-28T19:55:37.169652Z","steps":["trace[1919048265] 'read index received' (duration: 113.133724ms)","trace[1919048265] 'applied index is now lower than readState.Index' (duration: 101.621µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:55:37.169799Z","caller":"traceutil/trace.go:171","msg":"trace[1639174154] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"230.480883ms","start":"2026-06-28T19:55:36.939286Z","end":"2026-06-28T19:55:37.169767Z","steps":["trace[1639174154] 'process raft request' (duration: 230.26923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:55:37.169924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.499279ms","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-06-28T19:55:37.169991Z","caller":"traceutil/trace.go:171","msg":"trace[494016435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1204; }","duration":"113.60317ms","start":"2026-06-28T19:55:37.056371Z","end":"2026-06-28T19:55:37.169974Z","steps":["trace[494016435] 'agreement among raft nodes before linearized reading' (duration: 113.394308ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:55:43.917587Z","caller":"traceutil/trace.go:171","msg":"trace[1985097848] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"196.262498ms","start":"2026-06-28T19:55:43.7213Z","end":"2026-06-28T19:55:43.917562Z","steps":["trace[1985097848] 'process raft request' (duration: 196.118236ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:55:44.064012Z","caller":"traceutil/trace.go:171","msg":"trace[168025656] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"200.563826ms","start":"2026-06-28T19:55:43.863395Z","end":"2026-06-28T19:55:44.063959Z","steps":["trace[168025656] 'process raft request' (duration: 198.29356ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:55:55.824044Z","caller":"traceutil/trace.go:171","msg":"trace[703214287] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"249.779764ms","start":"2026-06-28T19:55:55.574245Z","end":"2026-06-28T19:55:55.824025Z","steps":["trace[703214287] 'process raft request' (duration: 249.668962ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:55:57.318462Z","caller":"traceutil/trace.go:171","msg":"trace[416617435] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"224.712643ms","start":"2026-06-28T19:55:57.093724Z","end":"2026-06-28T19:55:57.318437Z","steps":["trace[416617435] 'process raft request' (duration: 224.586672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:00.331287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.383432ms","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-06-28T19:56:00.331356Z","caller":"traceutil/trace.go:171","msg":"trace[560629569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1296; }","duration":"290.506014ms","start":"2026-06-28T19:56:00.040832Z","end":"2026-06-28T19:56:00.331338Z","steps":["trace[560629569] 'range keys from in-memory index tree' (duration: 290.16154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:00.331344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.877386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:56:00.331424Z","caller":"traceutil/trace.go:171","msg":"trace[866670666] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1296; }","duration":"122.026677ms","start":"2026-06-28T19:56:00.209376Z","end":"2026-06-28T19:56:00.331403Z","steps":["trace[866670666] 'count revisions from in-memory index tree' (duration: 121.781235ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:56:06.755296Z","caller":"traceutil/trace.go:171","msg":"trace[868190698] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1388; }","duration":"353.672344ms","start":"2026-06-28T19:56:06.401605Z","end":"2026-06-28T19:56:06.755277Z","steps":["trace[868190698] 'read index received' (duration: 353.571972ms)","trace[868190698] 'applied index is now lower than readState.Index' (duration: 99.552µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:56:06.755604Z","caller":"traceutil/trace.go:171","msg":"trace[1872902012] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"381.79938ms","start":"2026-06-28T19:56:06.373793Z","end":"2026-06-28T19:56:06.755592Z","steps":["trace[1872902012] 'process raft request' (duration: 381.381234ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:06.755709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:06.373766Z","time spent":"381.86445ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:56:06.755947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.347291ms","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-06-28T19:56:06.755975Z","caller":"traceutil/trace.go:171","msg":"trace[1912389020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1333; }","duration":"354.411912ms","start":"2026-06-28T19:56:06.401557Z","end":"2026-06-28T19:56:06.755968Z","steps":["trace[1912389020] 'agreement among raft nodes before linearized reading' (duration: 354.323091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:06.756018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:06.401537Z","time spent":"354.453562ms","remote":"127.0.0.1:51824","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-06-28T19:56:06.756144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.546806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:56:06.75617Z","caller":"traceutil/trace.go:171","msg":"trace[2078581304] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1333; }","duration":"194.576236ms","start":"2026-06-28T19:56:06.561588Z","end":"2026-06-28T19:56:06.756164Z","steps":["trace[2078581304] 'agreement among raft nodes before linearized reading' (duration: 194.533645ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:14.012392Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368671420463595,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:56:14.512848Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368671420463595,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:56:14.532755Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.041999448s","expected-duration":"1s"} {"level":"info","ts":"2026-06-28T19:56:14.533003Z","caller":"traceutil/trace.go:171","msg":"trace[1423165094] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1418; }","duration":"1.021591923s","start":"2026-06-28T19:56:13.511394Z","end":"2026-06-28T19:56:14.532986Z","steps":["trace[1423165094] 'read index received' (duration: 1.021585543s)","trace[1423165094] 'applied index is now lower than readState.Index' (duration: 5.24µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:56:14.533023Z","caller":"traceutil/trace.go:171","msg":"trace[1538466269] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"1.042361143s","start":"2026-06-28T19:56:13.490641Z","end":"2026-06-28T19:56:14.533002Z","steps":["trace[1538466269] 'process raft request' (duration: 1.042226422s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:14.533136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:13.490618Z","time spent":"1.042458854s","remote":"127.0.0.1:51824","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-06-28T19:56:14.533428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021791315s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:56:14.533457Z","caller":"traceutil/trace.go:171","msg":"trace[2095731444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"1.022121059s","start":"2026-06-28T19:56:13.511328Z","end":"2026-06-28T19:56:14.533449Z","steps":["trace[2095731444] 'agreement among raft nodes before linearized reading' (duration: 1.021714094s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:14.53354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:13.511299Z","time spent":"1.02223405s","remote":"127.0.0.1:51586","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-06-28T19:56:15.155958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.412365ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-28T19:56:15.15623Z","caller":"traceutil/trace.go:171","msg":"trace[178246698] linearizableReadLoop","detail":"{readStateIndex:1419; appliedIndex:1418; }","duration":"623.171892ms","start":"2026-06-28T19:56:14.533031Z","end":"2026-06-28T19:56:15.156203Z","steps":["trace[178246698] 'read index received' (duration: 252.548045ms)","trace[178246698] 'applied index is now lower than readState.Index' (duration: 370.620677ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:56:15.156341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407176901s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:56:15.156389Z","caller":"traceutil/trace.go:171","msg":"trace[56575863] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1362; }","duration":"1.407263922s","start":"2026-06-28T19:56:13.749111Z","end":"2026-06-28T19:56:15.156375Z","steps":["trace[56575863] 'agreement among raft nodes before linearized reading' (duration: 1.407173851s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:15.156404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.448617571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-28T19:56:15.156433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:13.749094Z","time spent":"1.407329763s","remote":"127.0.0.1:51718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-06-28T19:56:15.156453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.60097386s","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-06-28T19:56:15.15647Z","caller":"traceutil/trace.go:171","msg":"trace[1636993899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1362; }","duration":"1.448715742s","start":"2026-06-28T19:56:13.707739Z","end":"2026-06-28T19:56:15.156455Z","steps":["trace[1636993899] 'agreement among raft nodes before linearized reading' (duration: 1.448479869s)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:56:15.156486Z","caller":"traceutil/trace.go:171","msg":"trace[1594236959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1362; }","duration":"1.601036321s","start":"2026-06-28T19:56:13.555439Z","end":"2026-06-28T19:56:15.156476Z","steps":["trace[1594236959] 'agreement among raft nodes before linearized reading' (duration: 1.600901198s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:15.15647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.504822174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-28T19:56:15.156513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:13.707724Z","time spent":"1.448779013s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-28T19:56:15.156567Z","caller":"traceutil/trace.go:171","msg":"trace[725532494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1362; }","duration":"1.504955285s","start":"2026-06-28T19:56:13.651589Z","end":"2026-06-28T19:56:15.156544Z","steps":["trace[725532494] 'agreement among raft nodes before linearized reading' (duration: 1.504666521s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:15.156635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.854326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-28T19:56:15.156636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:13.651574Z","time spent":"1.505047977s","remote":"127.0.0.1:51824","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-06-28T19:56:15.156517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:13.555423Z","time spent":"1.601086971s","remote":"127.0.0.1:51824","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":"info","ts":"2026-06-28T19:56:15.156679Z","caller":"traceutil/trace.go:171","msg":"trace[1375038393] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1362; }","duration":"244.934508ms","start":"2026-06-28T19:56:14.911725Z","end":"2026-06-28T19:56:15.15666Z","steps":["trace[1375038393] 'agreement among raft nodes before linearized reading' (duration: 244.875217ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:56:15.156225Z","caller":"traceutil/trace.go:171","msg":"trace[723814634] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"361.635858ms","start":"2026-06-28T19:56:14.79457Z","end":"2026-06-28T19:56:15.156206Z","steps":["trace[723814634] 'process raft request' (duration: 361.490196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:15.15656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.239092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-28T19:56:15.156776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:14.794548Z","time spent":"362.177525ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:56:15.156795Z","caller":"traceutil/trace.go:171","msg":"trace[2019031571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1362; }","duration":"622.504705ms","start":"2026-06-28T19:56:14.534277Z","end":"2026-06-28T19:56:15.156782Z","steps":["trace[2019031571] 'agreement among raft nodes before linearized reading' (duration: 622.227442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:15.15683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:14.534267Z","time spent":"622.553395ms","remote":"127.0.0.1:51582","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-06-28T19:56:15.156894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.941136ms","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-06-28T19:56:15.156964Z","caller":"traceutil/trace.go:171","msg":"trace[97659542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1362; }","duration":"347.012866ms","start":"2026-06-28T19:56:14.809905Z","end":"2026-06-28T19:56:15.156918Z","steps":["trace[97659542] 'agreement among raft nodes before linearized reading' (duration: 346.916405ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:15.156996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:14.809889Z","time spent":"347.098167ms","remote":"127.0.0.1:51824","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-06-28T19:56:16.058675Z","caller":"traceutil/trace.go:171","msg":"trace[583130216] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1420; }","duration":"897.27871ms","start":"2026-06-28T19:56:15.161374Z","end":"2026-06-28T19:56:16.058653Z","steps":["trace[583130216] 'read index received' (duration: 90.964435ms)","trace[583130216] 'applied index is now lower than readState.Index' (duration: 806.312955ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:56:16.058999Z","caller":"traceutil/trace.go:171","msg":"trace[1937750927] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"897.660983ms","start":"2026-06-28T19:56:15.161322Z","end":"2026-06-28T19:56:16.058982Z","steps":["trace[1937750927] 'process raft request' (duration: 897.036766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:16.059123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:15.161298Z","time spent":"897.743865ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:56:16.059186Z","caller":"traceutil/trace.go:171","msg":"trace[1291832720] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"897.792285ms","start":"2026-06-28T19:56:15.161378Z","end":"2026-06-28T19:56:16.05917Z","steps":["trace[1291832720] 'process raft request' (duration: 897.136198ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:16.05955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:15.161353Z","time spent":"898.128199ms","remote":"127.0.0.1:51824","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-06-28T19:56:16.059041Z","caller":"traceutil/trace.go:171","msg":"trace[1752568869] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"897.178929ms","start":"2026-06-28T19:56:15.161839Z","end":"2026-06-28T19:56:16.059018Z","steps":["trace[1752568869] 'process raft request' (duration: 896.766554ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:16.059734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:15.161825Z","time spent":"897.802855ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:56:16.059279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.874216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:56:16.059904Z","caller":"traceutil/trace.go:171","msg":"trace[298277468] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1366; }","duration":"898.538473ms","start":"2026-06-28T19:56:15.161345Z","end":"2026-06-28T19:56:16.059883Z","steps":["trace[298277468] 'agreement among raft nodes before linearized reading' (duration: 897.835205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:16.059967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:15.161332Z","time spent":"898.619564ms","remote":"127.0.0.1:51718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-06-28T19:56:16.291614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.589792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:56:16.291706Z","caller":"traceutil/trace.go:171","msg":"trace[837198801] linearizableReadLoop","detail":"{readStateIndex:1425; appliedIndex:1424; }","duration":"232.945118ms","start":"2026-06-28T19:56:16.058745Z","end":"2026-06-28T19:56:16.29169Z","steps":["trace[837198801] 'read index received' (duration: 100.160903ms)","trace[837198801] 'applied index is now lower than readState.Index' (duration: 132.783135ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:56:16.29192Z","caller":"traceutil/trace.go:171","msg":"trace[476087634] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"437.036371ms","start":"2026-06-28T19:56:15.854867Z","end":"2026-06-28T19:56:16.291903Z","steps":["trace[476087634] 'process raft request' (duration: 304.087965ms)","trace[476087634] 'compare' (duration: 132.223778ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:56:16.292004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:15.854842Z","time spent":"437.119322ms","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5898,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:56:16.292122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12594646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:56:16.2922Z","caller":"traceutil/trace.go:171","msg":"trace[817454492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1367; }","duration":"1.126060991s","start":"2026-06-28T19:56:15.166121Z","end":"2026-06-28T19:56:16.292182Z","steps":["trace[817454492] 'agreement among raft nodes before linearized reading' (duration: 1.125858009s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:16.292245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:15.166107Z","time spent":"1.126129722s","remote":"127.0.0.1:51586","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-06-28T19:56:16.292802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.427091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:56:16.292848Z","caller":"traceutil/trace.go:171","msg":"trace[483853552] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1367; }","duration":"192.507272ms","start":"2026-06-28T19:56:16.10033Z","end":"2026-06-28T19:56:16.292837Z","steps":["trace[483853552] 'agreement among raft nodes before linearized reading' (duration: 192.437041ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:56:24.234759Z","caller":"traceutil/trace.go:171","msg":"trace[469570431] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"105.867473ms","start":"2026-06-28T19:56:24.128867Z","end":"2026-06-28T19:56:24.234734Z","steps":["trace[469570431] 'process raft request' (duration: 94.209356ms)","trace[469570431] 'compare' (duration: 11.483225ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:56:24.234957Z","caller":"traceutil/trace.go:171","msg":"trace[436060702] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"104.148083ms","start":"2026-06-28T19:56:24.130785Z","end":"2026-06-28T19:56:24.234933Z","steps":["trace[436060702] 'process raft request' (duration: 103.91431ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:56:24.786842Z","caller":"traceutil/trace.go:171","msg":"trace[1599390603] linearizableReadLoop","detail":"{readStateIndex:1460; appliedIndex:1459; }","duration":"117.079954ms","start":"2026-06-28T19:56:24.669737Z","end":"2026-06-28T19:56:24.786817Z","steps":["trace[1599390603] 'read index received' (duration: 18.884366ms)","trace[1599390603] 'applied index is now lower than readState.Index' (duration: 98.194128ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:56:24.787028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.201805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslice-controller\" ","response":"range_response_count:1 size:793"} {"level":"info","ts":"2026-06-28T19:56:24.787084Z","caller":"traceutil/trace.go:171","msg":"trace[465984652] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslice-controller; range_end:; response_count:1; response_revision:1399; }","duration":"117.357867ms","start":"2026-06-28T19:56:24.669714Z","end":"2026-06-28T19:56:24.787072Z","steps":["trace[465984652] 'agreement among raft nodes before linearized reading' (duration: 117.179625ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:56:25.386434Z","caller":"traceutil/trace.go:171","msg":"trace[1605823396] linearizableReadLoop","detail":"{readStateIndex:1461; appliedIndex:1460; }","duration":"123.725904ms","start":"2026-06-28T19:56:25.262685Z","end":"2026-06-28T19:56:25.386411Z","steps":["trace[1605823396] 'read index received' (duration: 123.580293ms)","trace[1605823396] 'applied index is now lower than readState.Index' (duration: 144.601µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:56:25.386494Z","caller":"traceutil/trace.go:171","msg":"trace[397436639] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"165.521468ms","start":"2026-06-28T19:56:25.22094Z","end":"2026-06-28T19:56:25.386461Z","steps":["trace[397436639] 'process raft request' (duration: 165.320666ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:25.386606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.900606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:job-controller\" ","response":"range_response_count:1 size:782"} {"level":"info","ts":"2026-06-28T19:56:25.386648Z","caller":"traceutil/trace.go:171","msg":"trace[2083279426] range","detail":"{range_begin:/registry/clusterroles/system:controller:job-controller; range_end:; response_count:1; response_revision:1400; }","duration":"123.984547ms","start":"2026-06-28T19:56:25.262653Z","end":"2026-06-28T19:56:25.386637Z","steps":["trace[2083279426] 'agreement among raft nodes before linearized reading' (duration: 123.893796ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:25.386695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.603478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cilium\" ","response":"range_response_count:1 size:749"} {"level":"info","ts":"2026-06-28T19:56:25.386745Z","caller":"traceutil/trace.go:171","msg":"trace[228365119] range","detail":"{range_begin:/registry/clusterrolebindings/cilium; range_end:; response_count:1; response_revision:1400; }","duration":"117.677869ms","start":"2026-06-28T19:56:25.269053Z","end":"2026-06-28T19:56:25.386731Z","steps":["trace[228365119] 'agreement among raft nodes before linearized reading' (duration: 117.566798ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:56:27.521552Z","caller":"traceutil/trace.go:171","msg":"trace[956276921] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1467; }","duration":"116.414661ms","start":"2026-06-28T19:56:27.405114Z","end":"2026-06-28T19:56:27.521529Z","steps":["trace[956276921] 'read index received' (duration: 116.286369ms)","trace[956276921] 'applied index is now lower than readState.Index' (duration: 127.242µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:56:27.521797Z","caller":"traceutil/trace.go:171","msg":"trace[1543077486] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"130.010169ms","start":"2026-06-28T19:56:27.391748Z","end":"2026-06-28T19:56:27.521758Z","steps":["trace[1543077486] 'process raft request' (duration: 129.638094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:27.521974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.829856ms","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-06-28T19:56:27.522024Z","caller":"traceutil/trace.go:171","msg":"trace[247136369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1407; }","duration":"116.911516ms","start":"2026-06-28T19:56:27.405102Z","end":"2026-06-28T19:56:27.522014Z","steps":["trace[247136369] 'agreement among raft nodes before linearized reading' (duration: 116.736965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:27.522079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.149061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-controller\" ","response":"range_response_count:1 size:724"} {"level":"info","ts":"2026-06-28T19:56:27.522198Z","caller":"traceutil/trace.go:171","msg":"trace[2058343337] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-controller; range_end:; response_count:1; response_revision:1407; }","duration":"107.352133ms","start":"2026-06-28T19:56:27.414822Z","end":"2026-06-28T19:56:27.522174Z","steps":["trace[2058343337] 'agreement among raft nodes before linearized reading' (duration: 107.10319ms)"],"step_count":1}