{"level":"warn","ts":"2026-06-11T06:56:10.111082Z","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-11T06:56:10.111216Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-11T06:56:10.111323Z","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-11T06:56:10.111338Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-06-11T06:56:10.111368Z","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-11T06:56:10.112066Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-06-11T06:56:10.112151Z","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.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-11T06:56:10.115165Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.744345ms"} {"level":"info","ts":"2026-06-11T06:56:10.119201Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-06-11T06:56:10.119382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-06-11T06:56:10.119407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-06-11T06:56:10.119431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-11T06:56:10.119441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-06-11T06:56:10.119598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-06-11T06:56:10.124786Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-11T06:56:10.126248Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-11T06:56:10.127604Z","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-11T06:56:10.129914Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-11T06:56:10.134639Z","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-11T06:56:10.134729Z","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-11T06:56:10.134744Z","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-11T06:56:10.134851Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-11T06:56:10.140417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-06-11T06:56:10.140586Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-06-11T06:56:10.140614Z","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-11T06:56:10.140718Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-06-11T06:56:10.140759Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-06-11T06:56:10.140983Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-11T06:56:10.141033Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-11T06:56:11.120203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-06-11T06:56:11.120359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-06-11T06:56:11.120453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-06-11T06:56:11.120511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-06-11T06:56:11.120546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-06-11T06:56:11.120629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-06-11T06:56:11.120671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-06-11T06:56:11.121818Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-06-11T06:56:11.121891Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-11T06:56:11.122074Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-11T06:56:11.122225Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-11T06:56:11.122257Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-11T06:56:11.122154Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-11T06:56:11.122895Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-06-11T06:56:11.123055Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-11T06:56:11.123127Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-11T06:56:11.124074Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-06-11T06:56:11.12579Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-11T06:56:34.633791Z","caller":"traceutil/trace.go:171","msg":"trace[275796945] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"276.831525ms","start":"2026-06-11T06:56:34.356927Z","end":"2026-06-11T06:56:34.633758Z","steps":["trace[275796945] 'read index received' (duration: 276.518488ms)","trace[275796945] 'applied index is now lower than readState.Index' (duration: 311.137µs)"],"step_count":2} {"level":"warn","ts":"2026-06-11T06:56:34.633818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:56:34.331508Z","time spent":"302.301366ms","remote":"127.0.0.1:52604","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-11T06:56:34.634029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.06303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-11T06:56:34.634098Z","caller":"traceutil/trace.go:171","msg":"trace[1404731937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:391; }","duration":"277.180944ms","start":"2026-06-11T06:56:34.356906Z","end":"2026-06-11T06:56:34.634087Z","steps":["trace[1404731937] 'agreement among raft nodes before linearized reading' (duration: 277.048951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:56:34.634076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.508072ms","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-11T06:56:34.63416Z","caller":"traceutil/trace.go:171","msg":"trace[566305072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:391; }","duration":"103.638646ms","start":"2026-06-11T06:56:34.530499Z","end":"2026-06-11T06:56:34.634137Z","steps":["trace[566305072] 'agreement among raft nodes before linearized reading' (duration: 103.472182ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:56:34.942734Z","caller":"traceutil/trace.go:171","msg":"trace[459151388] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:405; }","duration":"307.523648ms","start":"2026-06-11T06:56:34.635185Z","end":"2026-06-11T06:56:34.942709Z","steps":["trace[459151388] 'read index received' (duration: 307.356064ms)","trace[459151388] 'applied index is now lower than readState.Index' (duration: 166.444µs)"],"step_count":2} {"level":"info","ts":"2026-06-11T06:56:34.942835Z","caller":"traceutil/trace.go:171","msg":"trace[484482973] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"307.664652ms","start":"2026-06-11T06:56:34.635128Z","end":"2026-06-11T06:56:34.942793Z","steps":["trace[484482973] 'process raft request' (duration: 307.384335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:56:34.942965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.738984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-11T06:56:34.943049Z","caller":"traceutil/trace.go:171","msg":"trace[484040838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:392; }","duration":"307.896257ms","start":"2026-06-11T06:56:34.635135Z","end":"2026-06-11T06:56:34.943032Z","steps":["trace[484040838] 'agreement among raft nodes before linearized reading' (duration: 307.710963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:56:34.943107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:56:34.635117Z","time spent":"307.976199ms","remote":"127.0.0.1:52582","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-11T06:56:34.943574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.255475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-11T06:56:34.943648Z","caller":"traceutil/trace.go:171","msg":"trace[1050174766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:393; }","duration":"305.364028ms","start":"2026-06-11T06:56:34.638262Z","end":"2026-06-11T06:56:34.943626Z","steps":["trace[1050174766] 'agreement among raft nodes before linearized reading' (duration: 305.212644ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:56:34.943607Z","caller":"traceutil/trace.go:171","msg":"trace[1643316330] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"305.674675ms","start":"2026-06-11T06:56:34.637912Z","end":"2026-06-11T06:56:34.943586Z","steps":["trace[1643316330] 'process raft request' (duration: 305.425429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:56:34.94367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:56:34.635097Z","time spent":"307.834025ms","remote":"127.0.0.1:52604","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-11T06:56:34.943694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:56:34.638245Z","time spent":"305.43386ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-11T06:56:34.943709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.210377ms","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-11T06:56:34.943771Z","caller":"traceutil/trace.go:171","msg":"trace[63711734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:393; }","duration":"175.33765ms","start":"2026-06-11T06:56:34.768418Z","end":"2026-06-11T06:56:34.943756Z","steps":["trace[63711734] 'agreement among raft nodes before linearized reading' (duration: 175.173946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:56:34.943793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:56:34.63789Z","time spent":"305.805829ms","remote":"127.0.0.1:52852","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-11T06:56:41.177809Z","caller":"traceutil/trace.go:171","msg":"trace[1723135160] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"196.748607ms","start":"2026-06-11T06:56:40.98102Z","end":"2026-06-11T06:56:41.177769Z","steps":["trace[1723135160] 'read index received' (duration: 153.938292ms)","trace[1723135160] 'applied index is now lower than readState.Index' (duration: 42.809125ms)"],"step_count":2} {"level":"info","ts":"2026-06-11T06:56:41.177861Z","caller":"traceutil/trace.go:171","msg":"trace[1927710531] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"198.073527ms","start":"2026-06-11T06:56:40.97975Z","end":"2026-06-11T06:56:41.177823Z","steps":["trace[1927710531] 'process raft request' (duration: 155.264233ms)","trace[1927710531] 'compare' (duration: 42.61318ms)"],"step_count":2} {"level":"warn","ts":"2026-06-11T06:56:41.178017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.971862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-11T06:56:41.178076Z","caller":"traceutil/trace.go:171","msg":"trace[95864624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:407; }","duration":"197.069475ms","start":"2026-06-11T06:56:40.980993Z","end":"2026-06-11T06:56:41.178062Z","steps":["trace[95864624] 'agreement among raft nodes before linearized reading' (duration: 196.920261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:56:56.63825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.567934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-11T06:56:56.638373Z","caller":"traceutil/trace.go:171","msg":"trace[908976504] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"576.999633ms","start":"2026-06-11T06:56:56.061356Z","end":"2026-06-11T06:56:56.638356Z","steps":["trace[908976504] 'process raft request' (duration: 258.08242ms)","trace[908976504] 'compare' (duration: 318.442391ms)"],"step_count":2} {"level":"warn","ts":"2026-06-11T06:56:56.638457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:56:56.061321Z","time spent":"577.101075ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-11T06:56:56.649103Z","caller":"traceutil/trace.go:171","msg":"trace[1046553849] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"574.526486ms","start":"2026-06-11T06:56:56.074552Z","end":"2026-06-11T06:56:56.649079Z","steps":["trace[1046553849] 'process raft request' (duration: 574.421814ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:56:56.649267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:56:56.074527Z","time spent":"574.655639ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-11T06:56:56.649109Z","caller":"traceutil/trace.go:171","msg":"trace[897097525] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"579.204004ms","start":"2026-06-11T06:56:56.069877Z","end":"2026-06-11T06:56:56.649081Z","steps":["trace[897097525] 'process raft request' (duration: 578.942558ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:56:56.649365Z","caller":"traceutil/trace.go:171","msg":"trace[1499960270] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"565.542176ms","start":"2026-06-11T06:56:56.083792Z","end":"2026-06-11T06:56:56.649334Z","steps":["trace[1499960270] 'process raft request' (duration: 565.236499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:56:56.649414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:56:56.069855Z","time spent":"579.529221ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-11T06:56:56.649504Z","caller":"traceutil/trace.go:171","msg":"trace[2060065607] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:551; }","duration":"293.212506ms","start":"2026-06-11T06:56:56.356281Z","end":"2026-06-11T06:56:56.649494Z","steps":["trace[2060065607] 'read index received' (duration: 292.440397ms)","trace[2060065607] 'applied index is now lower than readState.Index' (duration: 771.599µs)"],"step_count":2} {"level":"warn","ts":"2026-06-11T06:56:56.649524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:56:56.083771Z","time spent":"565.674671ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-11T06:56:56.649568Z","caller":"traceutil/trace.go:171","msg":"trace[160214124] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"561.099675ms","start":"2026-06-11T06:56:56.088448Z","end":"2026-06-11T06:56:56.649548Z","steps":["trace[160214124] 'process raft request' (duration: 560.772657ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:56:56.649595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.294828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-11T06:56:56.649623Z","caller":"traceutil/trace.go:171","msg":"trace[794625325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"293.367269ms","start":"2026-06-11T06:56:56.356246Z","end":"2026-06-11T06:56:56.649613Z","steps":["trace[794625325] 'agreement among raft nodes before linearized reading' (duration: 293.287888ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:56:56.649633Z","caller":"traceutil/trace.go:171","msg":"trace[98474177] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"464.655006ms","start":"2026-06-11T06:56:56.184971Z","end":"2026-06-11T06:56:56.649626Z","steps":["trace[98474177] 'process raft request' (duration: 464.474732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:56:56.649649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:56:56.08841Z","time spent":"561.195507ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-11T06:56:56.64967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:56:56.184935Z","time spent":"464.717747ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-11T06:56:56.83617Z","caller":"traceutil/trace.go:171","msg":"trace[1706397350] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"171.518711ms","start":"2026-06-11T06:56:56.664628Z","end":"2026-06-11T06:56:56.836147Z","steps":["trace[1706397350] 'process raft request' (duration: 111.091368ms)","trace[1706397350] 'compare' (duration: 60.271639ms)"],"step_count":2} {"level":"info","ts":"2026-06-11T06:56:56.838655Z","caller":"traceutil/trace.go:171","msg":"trace[591811027] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"118.762767ms","start":"2026-06-11T06:56:56.719322Z","end":"2026-06-11T06:56:56.838085Z","steps":["trace[591811027] 'process raft request' (duration: 118.498841ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:56:56.840012Z","caller":"traceutil/trace.go:171","msg":"trace[222439135] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"111.389606ms","start":"2026-06-11T06:56:56.728583Z","end":"2026-06-11T06:56:56.839973Z","steps":["trace[222439135] 'process raft request' (duration: 109.475961ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:56:56.846309Z","caller":"traceutil/trace.go:171","msg":"trace[1034998235] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"108.235333ms","start":"2026-06-11T06:56:56.738034Z","end":"2026-06-11T06:56:56.846269Z","steps":["trace[1034998235] 'process raft request' (duration: 101.492346ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:02.669855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.078727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-11T06:57:02.669978Z","caller":"traceutil/trace.go:171","msg":"trace[1682710071] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"312.692298ms","start":"2026-06-11T06:57:02.357268Z","end":"2026-06-11T06:57:02.669961Z","steps":["trace[1682710071] 'read index received' (duration: 49.375716ms)","trace[1682710071] 'applied index is now lower than readState.Index' (duration: 263.315572ms)"],"step_count":2} {"level":"warn","ts":"2026-06-11T06:57:02.670059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.79782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-11T06:57:02.670105Z","caller":"traceutil/trace.go:171","msg":"trace[1898308917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"312.888532ms","start":"2026-06-11T06:57:02.357207Z","end":"2026-06-11T06:57:02.670096Z","steps":["trace[1898308917] 'agreement among raft nodes before linearized reading' (duration: 312.79753ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:57:02.670086Z","caller":"traceutil/trace.go:171","msg":"trace[663567163] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"473.349217ms","start":"2026-06-11T06:57:02.196689Z","end":"2026-06-11T06:57:02.670038Z","steps":["trace[663567163] 'process raft request' (duration: 210.014314ms)","trace[663567163] 'compare' (duration: 262.953684ms)"],"step_count":2} {"level":"warn","ts":"2026-06-11T06:57:02.67015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:02.357182Z","time spent":"312.959064ms","remote":"127.0.0.1:52574","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-11T06:57:02.670253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:02.196664Z","time spent":"473.506141ms","remote":"127.0.0.1:38292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-11T06:57:03.480752Z","caller":"traceutil/trace.go:171","msg":"trace[1350208606] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"361.405869ms","start":"2026-06-11T06:57:03.119323Z","end":"2026-06-11T06:57:03.480728Z","steps":["trace[1350208606] 'read index received' (duration: 360.674312ms)","trace[1350208606] 'applied index is now lower than readState.Index' (duration: 728.527µs)"],"step_count":2} {"level":"info","ts":"2026-06-11T06:57:03.480905Z","caller":"traceutil/trace.go:171","msg":"trace[1647611163] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"400.456235ms","start":"2026-06-11T06:57:03.08041Z","end":"2026-06-11T06:57:03.480866Z","steps":["trace[1647611163] 'process raft request' (duration: 399.76805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:03.480988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.649904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-11T06:57:03.480989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.857056ms","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-11T06:57:03.481024Z","caller":"traceutil/trace.go:171","msg":"trace[235178174] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:612; }","duration":"361.746647ms","start":"2026-06-11T06:57:03.119267Z","end":"2026-06-11T06:57:03.481013Z","steps":["trace[235178174] 'agreement among raft nodes before linearized reading' (duration: 361.607283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:03.481069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:03.119244Z","time spent":"361.815878ms","remote":"127.0.0.1:52852","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-11T06:57:03.48107Z","caller":"traceutil/trace.go:171","msg":"trace[50143659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:612; }","duration":"171.003059ms","start":"2026-06-11T06:57:03.310045Z","end":"2026-06-11T06:57:03.481048Z","steps":["trace[50143659] 'agreement among raft nodes before linearized reading' (duration: 170.838696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:03.481095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:03.080379Z","time spent":"400.610999ms","remote":"127.0.0.1:52660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-11T06:57:03.481319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.133117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-krj52\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-11T06:57:03.481346Z","caller":"traceutil/trace.go:171","msg":"trace[1476640865] range","detail":"{range_begin:/registry/pods/kube-system/cilium-krj52; range_end:; response_count:1; response_revision:612; }","duration":"154.184148ms","start":"2026-06-11T06:57:03.327154Z","end":"2026-06-11T06:57:03.481338Z","steps":["trace[1476640865] 'agreement among raft nodes before linearized reading' (duration: 154.066905ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:03.481623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.081937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-11T06:57:03.481666Z","caller":"traceutil/trace.go:171","msg":"trace[1087953178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:612; }","duration":"170.148218ms","start":"2026-06-11T06:57:03.311503Z","end":"2026-06-11T06:57:03.481651Z","steps":["trace[1087953178] 'agreement among raft nodes before linearized reading' (duration: 169.908403ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:57:03.622146Z","caller":"traceutil/trace.go:171","msg":"trace[626901212] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"130.781566ms","start":"2026-06-11T06:57:03.491333Z","end":"2026-06-11T06:57:03.622115Z","steps":["trace[626901212] 'process raft request' (duration: 75.601465ms)","trace[626901212] 'compare' (duration: 55.017956ms)"],"step_count":2} {"level":"info","ts":"2026-06-11T06:57:03.651001Z","caller":"traceutil/trace.go:171","msg":"trace[662388844] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"159.512483ms","start":"2026-06-11T06:57:03.491448Z","end":"2026-06-11T06:57:03.65096Z","steps":["trace[662388844] 'process raft request' (duration: 159.170885ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:57:03.651271Z","caller":"traceutil/trace.go:171","msg":"trace[2102912142] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"143.550592ms","start":"2026-06-11T06:57:03.507703Z","end":"2026-06-11T06:57:03.651254Z","steps":["trace[2102912142] 'process raft request' (duration: 143.128542ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:57:03.651345Z","caller":"traceutil/trace.go:171","msg":"trace[28962000] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"120.62264ms","start":"2026-06-11T06:57:03.530684Z","end":"2026-06-11T06:57:03.651307Z","steps":["trace[28962000] 'process raft request' (duration: 120.239191ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:57:03.651767Z","caller":"traceutil/trace.go:171","msg":"trace[1724297582] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"160.303251ms","start":"2026-06-11T06:57:03.491383Z","end":"2026-06-11T06:57:03.651686Z","steps":["trace[1724297582] 'process raft request' (duration: 158.97633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:04.686349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.76154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-11T06:57:04.686429Z","caller":"traceutil/trace.go:171","msg":"trace[884519106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"664.901573ms","start":"2026-06-11T06:57:04.02151Z","end":"2026-06-11T06:57:04.686411Z","steps":["trace[884519106] 'range keys from in-memory index tree' (duration: 664.591437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:04.686475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:04.021485Z","time spent":"664.980005ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-11T06:57:04.723262Z","caller":"traceutil/trace.go:171","msg":"trace[679998545] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"391.202691ms","start":"2026-06-11T06:57:04.331989Z","end":"2026-06-11T06:57:04.723192Z","steps":["trace[679998545] 'read index received' (duration: 385.15516ms)","trace[679998545] 'applied index is now lower than readState.Index' (duration: 6.041691ms)"],"step_count":2} {"level":"info","ts":"2026-06-11T06:57:04.723278Z","caller":"traceutil/trace.go:171","msg":"trace[1056655213] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"686.416003ms","start":"2026-06-11T06:57:04.036834Z","end":"2026-06-11T06:57:04.72325Z","steps":["trace[1056655213] 'process raft request' (duration: 681.1233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:04.723448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:04.036802Z","time spent":"686.550596ms","remote":"127.0.0.1:52660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-11T06:57:04.72351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.517868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-11T06:57:04.723549Z","caller":"traceutil/trace.go:171","msg":"trace[1300134186] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:620; }","duration":"391.59581ms","start":"2026-06-11T06:57:04.331939Z","end":"2026-06-11T06:57:04.723535Z","steps":["trace[1300134186] 'agreement among raft nodes before linearized reading' (duration: 391.472237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:04.723593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:04.33192Z","time spent":"391.664562ms","remote":"127.0.0.1:52604","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.140\" "} {"level":"warn","ts":"2026-06-11T06:57:04.723789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.329076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-11T06:57:04.723815Z","caller":"traceutil/trace.go:171","msg":"trace[887366677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"367.373838ms","start":"2026-06-11T06:57:04.356428Z","end":"2026-06-11T06:57:04.723802Z","steps":["trace[887366677] 'agreement among raft nodes before linearized reading' (duration: 367.342527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:04.723833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:04.356409Z","time spent":"367.419588ms","remote":"127.0.0.1:52574","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-11T06:57:17.543269Z","caller":"traceutil/trace.go:171","msg":"trace[608499504] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"185.151857ms","start":"2026-06-11T06:57:17.358093Z","end":"2026-06-11T06:57:17.543245Z","steps":["trace[608499504] 'read index received' (duration: 185.006784ms)","trace[608499504] 'applied index is now lower than readState.Index' (duration: 143.863µs)"],"step_count":2} {"level":"info","ts":"2026-06-11T06:57:17.543322Z","caller":"traceutil/trace.go:171","msg":"trace[1544363590] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"185.310812ms","start":"2026-06-11T06:57:17.35798Z","end":"2026-06-11T06:57:17.543291Z","steps":["trace[1544363590] 'process raft request' (duration: 185.123057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:17.543419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.306601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-11T06:57:17.54347Z","caller":"traceutil/trace.go:171","msg":"trace[2079186123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"185.405403ms","start":"2026-06-11T06:57:17.358055Z","end":"2026-06-11T06:57:17.54346Z","steps":["trace[2079186123] 'agreement among raft nodes before linearized reading' (duration: 185.315131ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:57:17.549859Z","caller":"traceutil/trace.go:171","msg":"trace[1487226742] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"143.03367ms","start":"2026-06-11T06:57:17.406802Z","end":"2026-06-11T06:57:17.549835Z","steps":["trace[1487226742] 'process raft request' (duration: 142.799925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:17.549881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.818272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-kb7d2\" ","response":"range_response_count:1 size:5050"} {"level":"info","ts":"2026-06-11T06:57:17.549928Z","caller":"traceutil/trace.go:171","msg":"trace[1686045322] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-kb7d2; range_end:; response_count:1; response_revision:762; }","duration":"138.906334ms","start":"2026-06-11T06:57:17.411009Z","end":"2026-06-11T06:57:17.549916Z","steps":["trace[1686045322] 'agreement among raft nodes before linearized reading' (duration: 138.75487ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:57:19.974626Z","caller":"traceutil/trace.go:171","msg":"trace[588570174] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"423.079721ms","start":"2026-06-11T06:57:19.551519Z","end":"2026-06-11T06:57:19.974599Z","steps":["trace[588570174] 'read index received' (duration: 422.857866ms)","trace[588570174] 'applied index is now lower than readState.Index' (duration: 220.595µs)"],"step_count":2} {"level":"info","ts":"2026-06-11T06:57:19.97467Z","caller":"traceutil/trace.go:171","msg":"trace[406880345] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"549.171127ms","start":"2026-06-11T06:57:19.425468Z","end":"2026-06-11T06:57:19.974639Z","steps":["trace[406880345] 'process raft request' (duration: 548.973012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:19.974819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.295065ms","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":"warn","ts":"2026-06-11T06:57:19.974835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:19.425453Z","time spent":"549.29304ms","remote":"127.0.0.1:52758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-11T06:57:19.97488Z","caller":"traceutil/trace.go:171","msg":"trace[2109676319] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:781; }","duration":"423.373267ms","start":"2026-06-11T06:57:19.551489Z","end":"2026-06-11T06:57:19.974862Z","steps":["trace[2109676319] 'agreement among raft nodes before linearized reading' (duration: 423.265984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:19.974915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.406558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-11T06:57:19.974933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:19.55143Z","time spent":"423.48701ms","remote":"127.0.0.1:38330","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":"info","ts":"2026-06-11T06:57:19.974989Z","caller":"traceutil/trace.go:171","msg":"trace[231610673] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:781; }","duration":"364.538951ms","start":"2026-06-11T06:57:19.610432Z","end":"2026-06-11T06:57:19.974971Z","steps":["trace[231610673] 'agreement among raft nodes before linearized reading' (duration: 364.406148ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:19.97503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:19.610413Z","time spent":"364.607123ms","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-11T06:57:19.975093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.570858ms","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-11T06:57:19.975166Z","caller":"traceutil/trace.go:171","msg":"trace[1951821944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:781; }","duration":"235.66114ms","start":"2026-06-11T06:57:19.739487Z","end":"2026-06-11T06:57:19.975148Z","steps":["trace[1951821944] 'agreement among raft nodes before linearized reading' (duration: 235.472936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:19.975287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.864788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-11T06:57:19.975338Z","caller":"traceutil/trace.go:171","msg":"trace[1062475722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:781; }","duration":"239.94511ms","start":"2026-06-11T06:57:19.735381Z","end":"2026-06-11T06:57:19.975326Z","steps":["trace[1062475722] 'agreement among raft nodes before linearized reading' (duration: 239.797177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:19.97532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.291429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-11T06:57:19.975436Z","caller":"traceutil/trace.go:171","msg":"trace[133217734] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:781; }","duration":"144.474293ms","start":"2026-06-11T06:57:19.830932Z","end":"2026-06-11T06:57:19.975407Z","steps":["trace[133217734] 'agreement among raft nodes before linearized reading' (duration: 144.244548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:19.975461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.123864ms","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-11T06:57:19.975495Z","caller":"traceutil/trace.go:171","msg":"trace[1403293395] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:781; }","duration":"237.192036ms","start":"2026-06-11T06:57:19.738292Z","end":"2026-06-11T06:57:19.975485Z","steps":["trace[1403293395] 'agreement among raft nodes before linearized reading' (duration: 237.071993ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:57:20.113276Z","caller":"traceutil/trace.go:171","msg":"trace[71757734] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"134.309348ms","start":"2026-06-11T06:57:19.978945Z","end":"2026-06-11T06:57:20.113254Z","steps":["trace[71757734] 'process raft request' (duration: 126.186439ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:57:20.113605Z","caller":"traceutil/trace.go:171","msg":"trace[558138783] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"129.465385ms","start":"2026-06-11T06:57:19.984117Z","end":"2026-06-11T06:57:20.113583Z","steps":["trace[558138783] 'process raft request' (duration: 129.081336ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:57:20.11384Z","caller":"traceutil/trace.go:171","msg":"trace[888671431] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:807; }","duration":"120.529757ms","start":"2026-06-11T06:57:19.993282Z","end":"2026-06-11T06:57:20.113812Z","steps":["trace[888671431] 'read index received' (duration: 111.862095ms)","trace[888671431] 'applied index is now lower than readState.Index' (duration: 8.665782ms)"],"step_count":2} {"level":"warn","ts":"2026-06-11T06:57:20.114107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.796473ms","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-11T06:57:20.114146Z","caller":"traceutil/trace.go:171","msg":"trace[321460232] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"129.909797ms","start":"2026-06-11T06:57:19.984216Z","end":"2026-06-11T06:57:20.114125Z","steps":["trace[321460232] 'process raft request' (duration: 129.287861ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:57:20.114172Z","caller":"traceutil/trace.go:171","msg":"trace[1140188290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"120.894526ms","start":"2026-06-11T06:57:19.993261Z","end":"2026-06-11T06:57:20.114155Z","steps":["trace[1140188290] 'agreement among raft nodes before linearized reading' (duration: 120.648281ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:57:49.064656Z","caller":"traceutil/trace.go:171","msg":"trace[908902822] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"148.349321ms","start":"2026-06-11T06:57:48.916283Z","end":"2026-06-11T06:57:49.064632Z","steps":["trace[908902822] 'read index received' (duration: 148.059734ms)","trace[908902822] 'applied index is now lower than readState.Index' (duration: 288.507µs)"],"step_count":2} {"level":"info","ts":"2026-06-11T06:57:49.064776Z","caller":"traceutil/trace.go:171","msg":"trace[346560166] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"461.273507ms","start":"2026-06-11T06:57:48.60346Z","end":"2026-06-11T06:57:49.064733Z","steps":["trace[346560166] 'process raft request' (duration: 460.992221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:49.064898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.589776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-11T06:57:49.064922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:48.603438Z","time spent":"461.39961ms","remote":"127.0.0.1:52852","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-11T06:57:49.064933Z","caller":"traceutil/trace.go:171","msg":"trace[803503706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:972; }","duration":"148.645487ms","start":"2026-06-11T06:57:48.916278Z","end":"2026-06-11T06:57:49.064923Z","steps":["trace[803503706] 'agreement among raft nodes before linearized reading' (duration: 148.454343ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:57:54.647707Z","caller":"traceutil/trace.go:171","msg":"trace[1268001337] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"366.853223ms","start":"2026-06-11T06:57:54.280815Z","end":"2026-06-11T06:57:54.647668Z","steps":["trace[1268001337] 'process raft request' (duration: 366.606748ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:54.647979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:54.280802Z","time spent":"367.035307ms","remote":"127.0.0.1:52852","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-11T06:57:55.536459Z","caller":"traceutil/trace.go:171","msg":"trace[957468001] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"1.252436612s","start":"2026-06-11T06:57:54.283996Z","end":"2026-06-11T06:57:55.536432Z","steps":["trace[957468001] 'process raft request' (duration: 1.252354751s)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:55.53661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:54.283975Z","time spent":"1.252562445s","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-11T06:57:55.536819Z","caller":"traceutil/trace.go:171","msg":"trace[439692624] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.254453088s","start":"2026-06-11T06:57:54.282353Z","end":"2026-06-11T06:57:55.536806Z","steps":["trace[439692624] 'process raft request' (duration: 1.253842884s)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:55.536866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:54.282344Z","time spent":"1.254497059s","remote":"127.0.0.1:52852","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-11T06:57:55.537011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202960768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-11T06:57:55.537Z","caller":"traceutil/trace.go:171","msg":"trace[1703028154] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"1.25236368s","start":"2026-06-11T06:57:54.284587Z","end":"2026-06-11T06:57:55.536951Z","steps":["trace[1703028154] 'process raft request' (duration: 1.251794477s)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:55.53725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:54.284583Z","time spent":"1.252527435s","remote":"127.0.0.1:52852","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-11T06:57:55.536777Z","caller":"traceutil/trace.go:171","msg":"trace[2031644967] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1023; }","duration":"1.202720402s","start":"2026-06-11T06:57:54.33402Z","end":"2026-06-11T06:57:55.53674Z","steps":["trace[2031644967] 'read index received' (duration: 313.475569ms)","trace[2031644967] 'applied index is now lower than readState.Index' (duration: 889.243563ms)"],"step_count":2} {"level":"info","ts":"2026-06-11T06:57:55.537271Z","caller":"traceutil/trace.go:171","msg":"trace[1900908142] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:993; }","duration":"1.203263885s","start":"2026-06-11T06:57:54.333995Z","end":"2026-06-11T06:57:55.537258Z","steps":["trace[1900908142] 'agreement among raft nodes before linearized reading' (duration: 1.202844665s)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:55.537513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:54.333984Z","time spent":"1.203494081s","remote":"127.0.0.1:52604","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.140\" "} {"level":"info","ts":"2026-06-11T06:57:55.537951Z","caller":"traceutil/trace.go:171","msg":"trace[1088157425] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"452.652857ms","start":"2026-06-11T06:57:55.085273Z","end":"2026-06-11T06:57:55.537926Z","steps":["trace[1088157425] 'process raft request' (duration: 452.503784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:55.538088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181189045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-11T06:57:55.538154Z","caller":"traceutil/trace.go:171","msg":"trace[690615760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:995; }","duration":"1.181272107s","start":"2026-06-11T06:57:54.356864Z","end":"2026-06-11T06:57:55.538136Z","steps":["trace[690615760] 'agreement among raft nodes before linearized reading' (duration: 1.18097539s)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:57:55.538158Z","caller":"traceutil/trace.go:171","msg":"trace[452811657] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"869.709922ms","start":"2026-06-11T06:57:54.668422Z","end":"2026-06-11T06:57:55.538132Z","steps":["trace[452811657] 'process raft request' (duration: 869.17014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:55.538195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:54.356853Z","time spent":"1.181334188s","remote":"127.0.0.1:52574","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-11T06:57:55.538169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:55.085239Z","time spent":"452.804341ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-11T06:57:55.538301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:54.668383Z","time spent":"869.833575ms","remote":"127.0.0.1:52852","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-11T06:57:55.538498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.911205ms","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-11T06:57:55.538589Z","caller":"traceutil/trace.go:171","msg":"trace[1407710955] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:995; }","duration":"859.996527ms","start":"2026-06-11T06:57:54.678568Z","end":"2026-06-11T06:57:55.538565Z","steps":["trace[1407710955] 'agreement among raft nodes before linearized reading' (duration: 859.877484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:55.538676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:54.678548Z","time spent":"860.11497ms","remote":"127.0.0.1:52644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-06-11T06:57:55.539004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.1515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-11T06:57:55.539065Z","caller":"traceutil/trace.go:171","msg":"trace[792027080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:995; }","duration":"447.247282ms","start":"2026-06-11T06:57:55.091793Z","end":"2026-06-11T06:57:55.53904Z","steps":["trace[792027080] 'agreement among raft nodes before linearized reading' (duration: 446.584307ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:57:55.539101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:57:55.091769Z","time spent":"447.323843ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-11T06:58:16.160687Z","caller":"traceutil/trace.go:171","msg":"trace[352441813] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"220.259213ms","start":"2026-06-11T06:58:15.940399Z","end":"2026-06-11T06:58:16.160658Z","steps":["trace[352441813] 'process raft request' (duration: 220.056039ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:58:22.018492Z","caller":"traceutil/trace.go:171","msg":"trace[833820270] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"273.645308ms","start":"2026-06-11T06:58:21.744812Z","end":"2026-06-11T06:58:22.018458Z","steps":["trace[833820270] 'process raft request' (duration: 273.458703ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:58:22.041306Z","caller":"traceutil/trace.go:171","msg":"trace[1692591220] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"295.868212ms","start":"2026-06-11T06:58:21.745419Z","end":"2026-06-11T06:58:22.041287Z","steps":["trace[1692591220] 'process raft request' (duration: 295.786351ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:58:22.041396Z","caller":"traceutil/trace.go:171","msg":"trace[82964114] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"296.007756ms","start":"2026-06-11T06:58:21.745352Z","end":"2026-06-11T06:58:22.04136Z","steps":["trace[82964114] 'process raft request' (duration: 295.702079ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:58:22.041605Z","caller":"traceutil/trace.go:171","msg":"trace[2108163403] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"295.545485ms","start":"2026-06-11T06:58:21.746037Z","end":"2026-06-11T06:58:22.041583Z","steps":["trace[2108163403] 'process raft request' (duration: 295.212188ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:58:22.966712Z","caller":"traceutil/trace.go:171","msg":"trace[1512134375] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"179.430869ms","start":"2026-06-11T06:58:22.787171Z","end":"2026-06-11T06:58:22.966602Z","steps":["trace[1512134375] 'process raft request' (duration: 179.280485ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:58:24.257268Z","caller":"traceutil/trace.go:171","msg":"trace[2140953946] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"212.238969ms","start":"2026-06-11T06:58:24.044998Z","end":"2026-06-11T06:58:24.257237Z","steps":["trace[2140953946] 'read index received' (duration: 212.096065ms)","trace[2140953946] 'applied index is now lower than readState.Index' (duration: 141.414µs)"],"step_count":2} {"level":"info","ts":"2026-06-11T06:58:24.257508Z","caller":"traceutil/trace.go:171","msg":"trace[2091870132] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"229.245301ms","start":"2026-06-11T06:58:24.028138Z","end":"2026-06-11T06:58:24.257383Z","steps":["trace[2091870132] 'process raft request' (duration: 228.864402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:58:24.257651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.624567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-11T06:58:24.257749Z","caller":"traceutil/trace.go:171","msg":"trace[1060822618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1085; }","duration":"212.773621ms","start":"2026-06-11T06:58:24.044956Z","end":"2026-06-11T06:58:24.257729Z","steps":["trace[1060822618] 'agreement among raft nodes before linearized reading' (duration: 212.502195ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:58:24.257832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.503012ms","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-11T06:58:24.257991Z","caller":"traceutil/trace.go:171","msg":"trace[412925125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1085; }","duration":"211.697936ms","start":"2026-06-11T06:58:24.04628Z","end":"2026-06-11T06:58:24.257978Z","steps":["trace[412925125] 'agreement among raft nodes before linearized reading' (duration: 211.422449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:58:24.257967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.797998ms","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-11T06:58:24.258261Z","caller":"traceutil/trace.go:171","msg":"trace[1072003119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1085; }","duration":"212.137116ms","start":"2026-06-11T06:58:24.046085Z","end":"2026-06-11T06:58:24.258222Z","steps":["trace[1072003119] 'agreement among raft nodes before linearized reading' (duration: 211.524412ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:58:25.090949Z","caller":"traceutil/trace.go:171","msg":"trace[22281647] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"115.00921ms","start":"2026-06-11T06:58:24.975914Z","end":"2026-06-11T06:58:25.090923Z","steps":["trace[22281647] 'process raft request' (duration: 114.832816ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:58:40.034447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.216119ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-11T06:58:40.034685Z","caller":"traceutil/trace.go:171","msg":"trace[391534031] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1232; }","duration":"145.87392ms","start":"2026-06-11T06:58:39.888795Z","end":"2026-06-11T06:58:40.034669Z","steps":["trace[391534031] 'read index received' (duration: 9.497197ms)","trace[391534031] 'applied index is now lower than readState.Index' (duration: 136.375973ms)"],"step_count":2} {"level":"warn","ts":"2026-06-11T06:58:40.034773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.964782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-11T06:58:40.0348Z","caller":"traceutil/trace.go:171","msg":"trace[1301383795] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1188; }","duration":"146.042263ms","start":"2026-06-11T06:58:39.888749Z","end":"2026-06-11T06:58:40.034791Z","steps":["trace[1301383795] 'agreement among raft nodes before linearized reading' (duration: 145.968692ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:58:40.034925Z","caller":"traceutil/trace.go:171","msg":"trace[759560879] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"218.057323ms","start":"2026-06-11T06:58:39.816843Z","end":"2026-06-11T06:58:40.034901Z","steps":["trace[759560879] 'process raft request' (duration: 217.704975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:58:41.857944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555606419352031584,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-11T06:58:41.880118Z","caller":"traceutil/trace.go:171","msg":"trace[2095496054] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1238; }","duration":"522.264747ms","start":"2026-06-11T06:58:41.357835Z","end":"2026-06-11T06:58:41.8801Z","steps":["trace[2095496054] 'read index received' (duration: 522.103013ms)","trace[2095496054] 'applied index is now lower than readState.Index' (duration: 161.334µs)"],"step_count":2} {"level":"warn","ts":"2026-06-11T06:58:41.880224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.374309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-11T06:58:41.880247Z","caller":"traceutil/trace.go:171","msg":"trace[30339130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1193; }","duration":"522.483163ms","start":"2026-06-11T06:58:41.357758Z","end":"2026-06-11T06:58:41.880241Z","steps":["trace[30339130] 'agreement among raft nodes before linearized reading' (duration: 522.3995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:58:41.880269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:58:41.357728Z","time spent":"522.537014ms","remote":"127.0.0.1:52574","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-11T06:58:41.88043Z","caller":"traceutil/trace.go:171","msg":"trace[1062995023] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"643.678257ms","start":"2026-06-11T06:58:41.236746Z","end":"2026-06-11T06:58:41.880424Z","steps":["trace[1062995023] 'process raft request' (duration: 643.245137ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:58:41.88049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:58:41.23673Z","time spent":"643.717789ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-11T06:58:42.277645Z","caller":"traceutil/trace.go:171","msg":"trace[678435275] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1239; }","duration":"161.952587ms","start":"2026-06-11T06:58:42.115651Z","end":"2026-06-11T06:58:42.277603Z","steps":["trace[678435275] 'read index received' (duration: 159.598004ms)","trace[678435275] 'applied index is now lower than readState.Index' (duration: 2.353133ms)"],"step_count":2} {"level":"warn","ts":"2026-06-11T06:58:42.277824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.131101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-11T06:58:42.277862Z","caller":"traceutil/trace.go:171","msg":"trace[1372887119] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1194; }","duration":"162.231673ms","start":"2026-06-11T06:58:42.115621Z","end":"2026-06-11T06:58:42.277853Z","steps":["trace[1372887119] 'agreement among raft nodes before linearized reading' (duration: 162.12047ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:58:42.278099Z","caller":"traceutil/trace.go:171","msg":"trace[832963659] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"238.983182ms","start":"2026-06-11T06:58:42.039102Z","end":"2026-06-11T06:58:42.278086Z","steps":["trace[832963659] 'process raft request' (duration: 236.174078ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:58:56.875611Z","caller":"traceutil/trace.go:171","msg":"trace[370332767] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"103.853748ms","start":"2026-06-11T06:58:56.77173Z","end":"2026-06-11T06:58:56.875584Z","steps":["trace[370332767] 'process raft request' (duration: 103.707205ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:58:57.055919Z","caller":"traceutil/trace.go:171","msg":"trace[12923391] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"179.620302ms","start":"2026-06-11T06:58:56.876269Z","end":"2026-06-11T06:58:57.055889Z","steps":["trace[12923391] 'process raft request' (duration: 177.049973ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:58:57.119161Z","caller":"traceutil/trace.go:171","msg":"trace[11960438] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"237.077788ms","start":"2026-06-11T06:58:56.882027Z","end":"2026-06-11T06:58:57.119105Z","steps":["trace[11960438] 'process raft request' (duration: 236.787861ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:58:57.119358Z","caller":"traceutil/trace.go:171","msg":"trace[246456054] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"237.136059ms","start":"2026-06-11T06:58:56.882204Z","end":"2026-06-11T06:58:57.11934Z","steps":["trace[246456054] 'process raft request' (duration: 236.807632ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:58:57.119493Z","caller":"traceutil/trace.go:171","msg":"trace[756552240] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"223.575018ms","start":"2026-06-11T06:58:56.895876Z","end":"2026-06-11T06:58:57.119451Z","steps":["trace[756552240] 'process raft request' (duration: 223.395484ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:59:00.670538Z","caller":"traceutil/trace.go:171","msg":"trace[1932928991] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"177.488163ms","start":"2026-06-11T06:59:00.49303Z","end":"2026-06-11T06:59:00.670518Z","steps":["trace[1932928991] 'process raft request' (duration: 177.36076ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:59:01.068179Z","caller":"traceutil/trace.go:171","msg":"trace[1658951045] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1337; }","duration":"530.886175ms","start":"2026-06-11T06:59:00.53727Z","end":"2026-06-11T06:59:01.068156Z","steps":["trace[1658951045] 'read index received' (duration: 133.20636ms)","trace[1658951045] 'applied index is now lower than readState.Index' (duration: 397.678805ms)"],"step_count":2} {"level":"info","ts":"2026-06-11T06:59:01.068284Z","caller":"traceutil/trace.go:171","msg":"trace[447461420] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"571.334661ms","start":"2026-06-11T06:59:00.496913Z","end":"2026-06-11T06:59:01.068247Z","steps":["trace[447461420] 'process raft request' (duration: 544.759384ms)","trace[447461420] 'compare' (duration: 26.23112ms)"],"step_count":2} {"level":"warn","ts":"2026-06-11T06:59:01.068415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.500435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-11T06:59:01.068415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.138131ms","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-11T06:59:01.068445Z","caller":"traceutil/trace.go:171","msg":"trace[396555550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1289; }","duration":"502.572367ms","start":"2026-06-11T06:59:00.565864Z","end":"2026-06-11T06:59:01.068436Z","steps":["trace[396555550] 'agreement among raft nodes before linearized reading' (duration: 502.485015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:59:01.068464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:59:00.565845Z","time spent":"502.614567ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-11T06:59:01.06846Z","caller":"traceutil/trace.go:171","msg":"trace[593304800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1289; }","duration":"531.239563ms","start":"2026-06-11T06:59:00.537208Z","end":"2026-06-11T06:59:01.068448Z","steps":["trace[593304800] 'agreement among raft nodes before linearized reading' (duration: 531.051089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:59:01.068456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:59:00.496899Z","time spent":"571.465824ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-11T06:59:01.068491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:59:00.537153Z","time spent":"531.331325ms","remote":"127.0.0.1:52852","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-11T06:59:01.06864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.123731ms","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-11T06:59:01.068917Z","caller":"traceutil/trace.go:171","msg":"trace[1731299798] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1289; }","duration":"507.443618ms","start":"2026-06-11T06:59:00.56146Z","end":"2026-06-11T06:59:01.068902Z","steps":["trace[1731299798] 'agreement among raft nodes before linearized reading' (duration: 506.827584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:59:01.069017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:59:00.561408Z","time spent":"507.594471ms","remote":"127.0.0.1:52852","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-11T06:59:02.375623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.486898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-11T06:59:02.375892Z","caller":"traceutil/trace.go:171","msg":"trace[901398964] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1345; }","duration":"372.928999ms","start":"2026-06-11T06:59:02.002943Z","end":"2026-06-11T06:59:02.375872Z","steps":["trace[901398964] 'read index received' (duration: 99.619411ms)","trace[901398964] 'applied index is now lower than readState.Index' (duration: 273.308278ms)"],"step_count":2} {"level":"info","ts":"2026-06-11T06:59:02.376094Z","caller":"traceutil/trace.go:171","msg":"trace[403102968] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"630.502865ms","start":"2026-06-11T06:59:01.745577Z","end":"2026-06-11T06:59:02.37608Z","steps":["trace[403102968] 'process raft request' (duration: 630.162488ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:59:02.37614Z","caller":"traceutil/trace.go:171","msg":"trace[1095854908] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"633.209417ms","start":"2026-06-11T06:59:01.742901Z","end":"2026-06-11T06:59:02.37611Z","steps":["trace[1095854908] 'process raft request' (duration: 198.177607ms)","trace[1095854908] 'compare' (duration: 434.358845ms)"],"step_count":2} {"level":"warn","ts":"2026-06-11T06:59:02.376171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:59:01.745563Z","time spent":"630.573837ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-11T06:59:02.37626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:59:01.742891Z","time spent":"633.30952ms","remote":"127.0.0.1:52632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-11T06:59:02.37631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.355558ms","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-11T06:59:02.376311Z","caller":"traceutil/trace.go:171","msg":"trace[342112974] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"626.498704ms","start":"2026-06-11T06:59:01.749791Z","end":"2026-06-11T06:59:02.37629Z","steps":["trace[342112974] 'process raft request' (duration: 626.036194ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:59:02.376349Z","caller":"traceutil/trace.go:171","msg":"trace[1663329989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1298; }","duration":"373.43177ms","start":"2026-06-11T06:59:02.002906Z","end":"2026-06-11T06:59:02.376337Z","steps":["trace[1663329989] 'agreement among raft nodes before linearized reading' (duration: 373.194665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:59:02.376376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:59:02.002874Z","time spent":"373.495871ms","remote":"127.0.0.1:52852","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-11T06:59:02.376409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:59:01.749765Z","time spent":"626.598577ms","remote":"127.0.0.1:52632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-11T06:59:02.910483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.912143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-11T06:59:02.910586Z","caller":"traceutil/trace.go:171","msg":"trace[182905544] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1348; }","duration":"527.056107ms","start":"2026-06-11T06:59:02.383514Z","end":"2026-06-11T06:59:02.91057Z","steps":["trace[182905544] 'read index received' (duration: 362.942999ms)","trace[182905544] 'applied index is now lower than readState.Index' (duration: 164.111838ms)"],"step_count":2} {"level":"info","ts":"2026-06-11T06:59:02.910653Z","caller":"traceutil/trace.go:171","msg":"trace[1256887288] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"527.443517ms","start":"2026-06-11T06:59:02.383198Z","end":"2026-06-11T06:59:02.910641Z","steps":["trace[1256887288] 'process raft request' (duration: 363.298898ms)","trace[1256887288] 'compare' (duration: 163.776551ms)"],"step_count":2} {"level":"warn","ts":"2026-06-11T06:59:02.910698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:59:02.383172Z","time spent":"527.503389ms","remote":"127.0.0.1:52632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-11T06:59:02.910933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.414005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-11T06:59:02.910963Z","caller":"traceutil/trace.go:171","msg":"trace[288379891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"527.471967ms","start":"2026-06-11T06:59:02.383481Z","end":"2026-06-11T06:59:02.910953Z","steps":["trace[288379891] 'agreement among raft nodes before linearized reading' (duration: 527.396775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:59:02.911061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:59:02.383466Z","time spent":"527.518219ms","remote":"127.0.0.1:52582","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-11T06:59:02.942392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.1445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-11T06:59:02.94248Z","caller":"traceutil/trace.go:171","msg":"trace[2127776539] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1301; }","duration":"147.283532ms","start":"2026-06-11T06:59:02.795177Z","end":"2026-06-11T06:59:02.942461Z","steps":["trace[2127776539] 'agreement among raft nodes before linearized reading' (duration: 147.061317ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:59:02.9425Z","caller":"traceutil/trace.go:171","msg":"trace[476294886] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"559.133772ms","start":"2026-06-11T06:59:02.383312Z","end":"2026-06-11T06:59:02.942445Z","steps":["trace[476294886] 'process raft request' (duration: 558.743694ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:59:02.942694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:59:02.383294Z","time spent":"559.305966ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-11T06:59:02.942722Z","caller":"traceutil/trace.go:171","msg":"trace[1256297427] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"267.099074ms","start":"2026-06-11T06:59:02.675612Z","end":"2026-06-11T06:59:02.942711Z","steps":["trace[1256297427] 'process raft request' (duration: 266.584023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:59:03.144493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.216975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-11T06:59:03.144769Z","caller":"traceutil/trace.go:171","msg":"trace[1363436035] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"197.501951ms","start":"2026-06-11T06:59:02.947246Z","end":"2026-06-11T06:59:03.144748Z","steps":["trace[1363436035] 'process raft request' (duration: 89.951769ms)","trace[1363436035] 'compare' (duration: 107.01518ms)"],"step_count":2} {"level":"warn","ts":"2026-06-11T06:59:03.362457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.145912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-11T06:59:03.362682Z","caller":"traceutil/trace.go:171","msg":"trace[914623764] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"210.05553ms","start":"2026-06-11T06:59:03.152608Z","end":"2026-06-11T06:59:03.362664Z","steps":["trace[914623764] 'process raft request' (duration: 210.014489ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:59:03.362773Z","caller":"traceutil/trace.go:171","msg":"trace[580722515] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"211.402649ms","start":"2026-06-11T06:59:03.151338Z","end":"2026-06-11T06:59:03.362741Z","steps":["trace[580722515] 'process raft request' (duration: 211.235056ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:59:03.36275Z","caller":"traceutil/trace.go:171","msg":"trace[827169376] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"211.621946ms","start":"2026-06-11T06:59:03.151018Z","end":"2026-06-11T06:59:03.36264Z","steps":["trace[827169376] 'process raft request' (duration: 94.180607ms)","trace[827169376] 'compare' (duration: 117.009869ms)"],"step_count":2} {"level":"warn","ts":"2026-06-11T06:59:03.999936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.967961ms","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-11T06:59:04.000111Z","caller":"traceutil/trace.go:171","msg":"trace[48117991] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1306; }","duration":"187.180426ms","start":"2026-06-11T06:59:03.812912Z","end":"2026-06-11T06:59:04.000092Z","steps":["trace[48117991] 'count revisions from in-memory index tree' (duration: 186.88653ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:59:04.275942Z","caller":"traceutil/trace.go:171","msg":"trace[1377352060] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"142.552885ms","start":"2026-06-11T06:59:04.133367Z","end":"2026-06-11T06:59:04.27592Z","steps":["trace[1377352060] 'process raft request' (duration: 142.400741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:59:04.558061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.846822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-11T06:59:04.558134Z","caller":"traceutil/trace.go:171","msg":"trace[1474031871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"201.964324ms","start":"2026-06-11T06:59:04.356157Z","end":"2026-06-11T06:59:04.558121Z","steps":["trace[1474031871] 'range keys from in-memory index tree' (duration: 201.79606ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:59:04.558155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.579358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-11T06:59:04.558238Z","caller":"traceutil/trace.go:171","msg":"trace[821768744] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:1307; }","duration":"219.68179ms","start":"2026-06-11T06:59:04.338535Z","end":"2026-06-11T06:59:04.558217Z","steps":["trace[821768744] 'range keys from in-memory index tree' (duration: 219.342053ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:59:12.417278Z","caller":"traceutil/trace.go:171","msg":"trace[767099706] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1411; }","duration":"332.137254ms","start":"2026-06-11T06:59:12.085125Z","end":"2026-06-11T06:59:12.417262Z","steps":["trace[767099706] 'read index received' (duration: 331.987231ms)","trace[767099706] 'applied index is now lower than readState.Index' (duration: 149.283µs)"],"step_count":2} {"level":"info","ts":"2026-06-11T06:59:12.417501Z","caller":"traceutil/trace.go:171","msg":"trace[866665045] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"511.279186ms","start":"2026-06-11T06:59:11.90621Z","end":"2026-06-11T06:59:12.41749Z","steps":["trace[866665045] 'process raft request' (duration: 510.947229ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:59:12.41759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:59:11.906007Z","time spent":"511.515541ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-11T06:59:12.417621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.447371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-11T06:59:12.417652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.338322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-11T06:59:12.417706Z","caller":"traceutil/trace.go:171","msg":"trace[363295956] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1360; }","duration":"332.616975ms","start":"2026-06-11T06:59:12.085075Z","end":"2026-06-11T06:59:12.417692Z","steps":["trace[363295956] 'agreement among raft nodes before linearized reading' (duration: 332.456731ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T06:59:12.417741Z","caller":"traceutil/trace.go:171","msg":"trace[1633140706] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1360; }","duration":"102.499777ms","start":"2026-06-11T06:59:12.315224Z","end":"2026-06-11T06:59:12.417724Z","steps":["trace[1633140706] 'agreement among raft nodes before linearized reading' (duration: 102.310532ms)"],"step_count":1} {"level":"warn","ts":"2026-06-11T06:59:12.417745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-11T06:59:12.085056Z","time spent":"332.678966ms","remote":"127.0.0.1:38330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-06-11T06:59:12.522199Z","caller":"traceutil/trace.go:171","msg":"trace[1445405013] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"101.501245ms","start":"2026-06-11T06:59:12.420668Z","end":"2026-06-11T06:59:12.52217Z","steps":["trace[1445405013] 'process raft request' (duration: 101.30679ms)"],"step_count":1} {"level":"info","ts":"2026-06-11T07:02:57.761546Z","caller":"traceutil/trace.go:171","msg":"trace[2008175188] transaction","detail":"{read_only:false; response_revision:2415; number_of_response:1; }","duration":"162.572488ms","start":"2026-06-11T07:02:57.59895Z","end":"2026-06-11T07:02:57.761522Z","steps":["trace[2008175188] 'process raft request' (duration: 162.370193ms)"],"step_count":1}