{"level":"warn","ts":"2026-04-24T15:23:14.457599Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-24T15:23:14.457696Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-24T15:23:14.457774Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-24T15:23:14.457786Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-04-24T15:23:14.457805Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-24T15:23:14.458463Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-04-24T15:23:14.458541Z","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.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-24T15:23:14.464457Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.694458ms"} {"level":"info","ts":"2026-04-24T15:23:14.473697Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-04-24T15:23:14.473794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-04-24T15:23:14.473822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-04-24T15:23:14.473834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-24T15:23:14.473852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-04-24T15:23:14.473894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-04-24T15:23:14.483337Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-24T15:23:14.484842Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-24T15:23:14.4859Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-24T15:23:14.488147Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-24T15:23:14.489546Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-24T15:23:14.489351Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-24T15:23:14.489947Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-24T15:23:14.489963Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-24T15:23:14.49043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-04-24T15:23:14.490797Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-04-24T15:23:14.493051Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-24T15:23:14.493301Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-24T15:23:14.493337Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-24T15:23:14.493454Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-04-24T15:23:14.493473Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-04-24T15:23:15.27461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-04-24T15:23:15.274693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-24T15:23:15.274751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-04-24T15:23:15.274769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-04-24T15:23:15.274782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-04-24T15:23:15.274795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-04-24T15:23:15.274806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-04-24T15:23:15.275972Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-04-24T15:23:15.276021Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-24T15:23:15.276167Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T15:23:15.276255Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-24T15:23:15.277911Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T15:23:15.278029Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T15:23:15.27806Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T15:23:15.278719Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-04-24T15:23:15.279172Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-24T15:23:15.279348Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-24T15:23:15.279387Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-04-24T15:23:17.180919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710538088936417913,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-24T15:23:17.20362Z","caller":"traceutil/trace.go:171","msg":"trace[460356460] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"531.56689ms","start":"2026-04-24T15:23:16.672026Z","end":"2026-04-24T15:23:17.203593Z","steps":["trace[460356460] 'process raft request' (duration: 531.316899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.2042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.672011Z","time spent":"531.695787ms","remote":"127.0.0.1:48750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:23:17.977163Z","caller":"traceutil/trace.go:171","msg":"trace[163767784] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.303519196s","start":"2026-04-24T15:23:16.673605Z","end":"2026-04-24T15:23:17.977124Z","steps":["trace[163767784] 'process raft request' (duration: 1.303111026s)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:17.977313Z","caller":"traceutil/trace.go:171","msg":"trace[1342472457] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.294473009s","start":"2026-04-24T15:23:16.682832Z","end":"2026-04-24T15:23:17.977305Z","steps":["trace[1342472457] 'process raft request' (duration: 1.294129783s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.977418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.682816Z","time spent":"1.294534491s","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:17.977427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296809909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:17.977507Z","caller":"traceutil/trace.go:171","msg":"trace[294749309] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:0; response_revision:11; }","duration":"1.297003258s","start":"2026-04-24T15:23:16.680492Z","end":"2026-04-24T15:23:17.977495Z","steps":["trace[294749309] 'agreement among raft nodes before linearized reading' (duration: 1.296800398s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.977547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.680466Z","time spent":"1.297072952s","remote":"127.0.0.1:48724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.44\" "} {"level":"info","ts":"2026-04-24T15:23:17.97757Z","caller":"traceutil/trace.go:171","msg":"trace[956109081] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.294614195s","start":"2026-04-24T15:23:16.682946Z","end":"2026-04-24T15:23:17.977561Z","steps":["trace[956109081] 'process raft request' (duration: 1.294049619s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.977617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.682895Z","time spent":"1.29469899s","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:17.977486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.673599Z","time spent":"1.303720065s","remote":"127.0.0.1:48764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:23:17.977709Z","caller":"traceutil/trace.go:171","msg":"trace[1728995774] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.294673859s","start":"2026-04-24T15:23:16.683028Z","end":"2026-04-24T15:23:17.977702Z","steps":["trace[1728995774] 'process raft request' (duration: 1.294015698s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.977866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.683023Z","time spent":"1.294790644s","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:17.978044Z","caller":"traceutil/trace.go:171","msg":"trace[1929368966] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.261135835s","start":"2026-04-24T15:23:16.716895Z","end":"2026-04-24T15:23:17.978031Z","steps":["trace[1929368966] 'process raft request' (duration: 1.260237373s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.978169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.716878Z","time spent":"1.261238261s","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:17.978182Z","caller":"traceutil/trace.go:171","msg":"trace[589939580] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.26058444s","start":"2026-04-24T15:23:16.717584Z","end":"2026-04-24T15:23:17.978168Z","steps":["trace[589939580] 'process raft request' (duration: 1.259586872s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.978243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.717557Z","time spent":"1.260661733s","remote":"127.0.0.1:48906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:17.977196Z","caller":"traceutil/trace.go:171","msg":"trace[153911174] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.294211466s","start":"2026-04-24T15:23:16.682946Z","end":"2026-04-24T15:23:17.977157Z","steps":["trace[153911174] 'process raft request' (duration: 1.29407422s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.978416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.682913Z","time spent":"1.295458126s","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:17.977235Z","caller":"traceutil/trace.go:171","msg":"trace[427022244] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:4; }","duration":"1.296646261s","start":"2026-04-24T15:23:16.68056Z","end":"2026-04-24T15:23:17.977206Z","steps":["trace[427022244] 'read index received' (duration: 522.884981ms)","trace[427022244] 'applied index is now lower than readState.Index' (duration: 773.75997ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:23:17.977683Z","caller":"traceutil/trace.go:171","msg":"trace[993832949] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.274133218s","start":"2026-04-24T15:23:16.703541Z","end":"2026-04-24T15:23:17.977674Z","steps":["trace[993832949] 'process raft request' (duration: 1.27352897s)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:17.977278Z","caller":"traceutil/trace.go:171","msg":"trace[1795946226] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.294787024s","start":"2026-04-24T15:23:16.682453Z","end":"2026-04-24T15:23:17.97724Z","steps":["trace[1795946226] 'process raft request' (duration: 1.294468569s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.978605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.703519Z","time spent":"1.275059403s","remote":"127.0.0.1:49076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:17.978631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.682387Z","time spent":"1.296216572s","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:17.97875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295918817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:17.978797Z","caller":"traceutil/trace.go:171","msg":"trace[1332427248] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"1.29596762s","start":"2026-04-24T15:23:16.682809Z","end":"2026-04-24T15:23:17.978777Z","steps":["trace[1332427248] 'agreement among raft nodes before linearized reading' (duration: 1.295906567s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.978845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.156099118s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:17.97888Z","caller":"traceutil/trace.go:171","msg":"trace[597565214] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.156173991s","start":"2026-04-24T15:23:16.822695Z","end":"2026-04-24T15:23:17.978869Z","steps":["trace[597565214] 'agreement among raft nodes before linearized reading' (duration: 1.156122519s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.978907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.822677Z","time spent":"1.156223904s","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-24T15:23:17.979023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.876835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:17.979126Z","caller":"traceutil/trace.go:171","msg":"trace[600715398] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"364.043423ms","start":"2026-04-24T15:23:17.615067Z","end":"2026-04-24T15:23:17.979111Z","steps":["trace[600715398] 'agreement among raft nodes before linearized reading' (duration: 363.790401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.979221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:17.615056Z","time spent":"364.153108ms","remote":"127.0.0.1:49012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-24T15:23:17.979308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176713151s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:17.979344Z","caller":"traceutil/trace.go:171","msg":"trace[1205913489] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.176785724s","start":"2026-04-24T15:23:16.80255Z","end":"2026-04-24T15:23:17.979335Z","steps":["trace[1205913489] 'agreement among raft nodes before linearized reading' (duration: 1.176744962s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.979379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.802528Z","time spent":"1.176842277s","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-24T15:23:17.979487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219394585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:17.979521Z","caller":"traceutil/trace.go:171","msg":"trace[393632244] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.219455037s","start":"2026-04-24T15:23:16.760056Z","end":"2026-04-24T15:23:17.979511Z","steps":["trace[393632244] 'agreement among raft nodes before linearized reading' (duration: 1.219412725s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.979556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.760039Z","time spent":"1.21950445s","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-24T15:23:17.97905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.821574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-24T15:23:17.979223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.985236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:17.979807Z","caller":"traceutil/trace.go:171","msg":"trace[1710647897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"337.621205ms","start":"2026-04-24T15:23:17.642174Z","end":"2026-04-24T15:23:17.979796Z","steps":["trace[1710647897] 'agreement among raft nodes before linearized reading' (duration: 337.029727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.97985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:17.64215Z","time spent":"337.691489ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-24T15:23:17.979775Z","caller":"traceutil/trace.go:171","msg":"trace[2026770227] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"392.549739ms","start":"2026-04-24T15:23:17.587181Z","end":"2026-04-24T15:23:17.979731Z","steps":["trace[2026770227] 'agreement among raft nodes before linearized reading' (duration: 391.845456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.97928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253381418s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:17.979987Z","caller":"traceutil/trace.go:171","msg":"trace[1256086846] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.254108643s","start":"2026-04-24T15:23:16.725866Z","end":"2026-04-24T15:23:17.979974Z","steps":["trace[1256086846] 'agreement among raft nodes before linearized reading' (duration: 1.2534011s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.980029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.72585Z","time spent":"1.254170535s","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-24T15:23:17.979947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:17.58715Z","time spent":"392.781869ms","remote":"127.0.0.1:49002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-24T15:23:17.97909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090319882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:17.980285Z","caller":"traceutil/trace.go:171","msg":"trace[1943441945] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"1.091532419s","start":"2026-04-24T15:23:16.888743Z","end":"2026-04-24T15:23:17.980276Z","steps":["trace[1943441945] 'agreement among raft nodes before linearized reading' (duration: 1.090323373s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.98032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.888729Z","time spent":"1.091583022s","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-04-24T15:23:17.980609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270020884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:17.980747Z","caller":"traceutil/trace.go:171","msg":"trace[1799385604] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.270196693s","start":"2026-04-24T15:23:16.710538Z","end":"2026-04-24T15:23:17.980735Z","steps":["trace[1799385604] 'agreement among raft nodes before linearized reading' (duration: 1.270038795s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.981117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.710528Z","time spent":"1.270442005s","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-24T15:23:17.980659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295196523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-24T15:23:17.978829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.682799Z","time spent":"1.296022482s","remote":"127.0.0.1:48780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-04-24T15:23:17.982318Z","caller":"traceutil/trace.go:171","msg":"trace[1378809406] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"1.296865742s","start":"2026-04-24T15:23:16.685437Z","end":"2026-04-24T15:23:17.982303Z","steps":["trace[1378809406] 'agreement among raft nodes before linearized reading' (duration: 1.295192643s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:17.982703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:16.685429Z","time spent":"1.29724954s","remote":"127.0.0.1:49034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-24T15:23:18.606918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.798932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-24T15:23:18.607276Z","caller":"traceutil/trace.go:171","msg":"trace[199148922] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"623.354182ms","start":"2026-04-24T15:23:17.983861Z","end":"2026-04-24T15:23:18.607215Z","steps":["trace[199148922] 'process raft request' (duration: 301.92776ms)","trace[199148922] 'compare' (duration: 320.546181ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:23:18.607481Z","caller":"traceutil/trace.go:171","msg":"trace[769964767] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:14; }","duration":"623.52061ms","start":"2026-04-24T15:23:17.983931Z","end":"2026-04-24T15:23:18.607451Z","steps":["trace[769964767] 'read index received' (duration: 301.972773ms)","trace[769964767] 'applied index is now lower than readState.Index' (duration: 321.535647ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:23:18.607529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:17.98385Z","time spent":"623.592813ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:18.607752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.788863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:18.607804Z","caller":"traceutil/trace.go:171","msg":"trace[565074669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12; }","duration":"623.874366ms","start":"2026-04-24T15:23:17.983915Z","end":"2026-04-24T15:23:18.607789Z","steps":["trace[565074669] 'agreement among raft nodes before linearized reading' (duration: 623.680187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:18.607842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:17.983907Z","time spent":"623.927619ms","remote":"127.0.0.1:48700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-24T15:23:18.607829Z","caller":"traceutil/trace.go:171","msg":"trace[750289593] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12; }","duration":"621.257433ms","start":"2026-04-24T15:23:17.986526Z","end":"2026-04-24T15:23:18.607783Z","steps":["trace[750289593] 'process raft request' (duration: 620.540299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:18.607996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:17.986516Z","time spent":"621.41215ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:18.608856Z","caller":"traceutil/trace.go:171","msg":"trace[1352778816] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"620.9618ms","start":"2026-04-24T15:23:17.98788Z","end":"2026-04-24T15:23:18.608842Z","steps":["trace[1352778816] 'process raft request' (duration: 620.276677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:18.608933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:17.987873Z","time spent":"621.022243ms","remote":"127.0.0.1:49076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:18.608999Z","caller":"traceutil/trace.go:171","msg":"trace[1227920805] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"621.173509ms","start":"2026-04-24T15:23:17.98769Z","end":"2026-04-24T15:23:18.608864Z","steps":["trace[1227920805] 'process raft request' (duration: 619.726781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:18.60921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:17.987683Z","time spent":"621.434872ms","remote":"127.0.0.1:49012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:18.609344Z","caller":"traceutil/trace.go:171","msg":"trace[1406690389] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"618.232901ms","start":"2026-04-24T15:23:17.991099Z","end":"2026-04-24T15:23:18.609332Z","steps":["trace[1406690389] 'process raft request' (duration: 617.513508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:18.609444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:17.99109Z","time spent":"618.311775ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3478,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:23:18.609209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.507627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:18.60966Z","caller":"traceutil/trace.go:171","msg":"trace[1144060717] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:20; }","duration":"625.20336ms","start":"2026-04-24T15:23:17.984439Z","end":"2026-04-24T15:23:18.609643Z","steps":["trace[1144060717] 'agreement among raft nodes before linearized reading' (duration: 624.384451ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:18.609739Z","caller":"traceutil/trace.go:171","msg":"trace[337287721] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"619.823156ms","start":"2026-04-24T15:23:17.989907Z","end":"2026-04-24T15:23:18.60973Z","steps":["trace[337287721] 'process raft request' (duration: 618.564287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:18.609806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:17.989793Z","time spent":"619.976203ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:18.609892Z","caller":"traceutil/trace.go:171","msg":"trace[765469544] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"618.995197ms","start":"2026-04-24T15:23:17.990888Z","end":"2026-04-24T15:23:18.609883Z","steps":["trace[765469544] 'process raft request' (duration: 617.670015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:18.609936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:17.990877Z","time spent":"619.040629ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:18.609882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:17.98442Z","time spent":"625.336386ms","remote":"127.0.0.1:49010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-04-24T15:23:18.610083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.255072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-04-24T15:23:18.610143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.826602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:18.610186Z","caller":"traceutil/trace.go:171","msg":"trace[855160156] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"424.880115ms","start":"2026-04-24T15:23:18.185287Z","end":"2026-04-24T15:23:18.610167Z","steps":["trace[855160156] 'agreement among raft nodes before linearized reading' (duration: 424.829603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:18.61022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.185273Z","time spent":"424.941718ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-04-24T15:23:18.610077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.489779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-04-24T15:23:18.610272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.620825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:18.610148Z","caller":"traceutil/trace.go:171","msg":"trace[1947761520] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"407.313815ms","start":"2026-04-24T15:23:18.202812Z","end":"2026-04-24T15:23:18.610126Z","steps":["trace[1947761520] 'agreement among raft nodes before linearized reading' (duration: 406.903306ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:18.610318Z","caller":"traceutil/trace.go:171","msg":"trace[9458341] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"573.688478ms","start":"2026-04-24T15:23:18.036619Z","end":"2026-04-24T15:23:18.610307Z","steps":["trace[9458341] 'agreement among raft nodes before linearized reading' (duration: 573.623155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:18.610341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.202771Z","time spent":"407.562376ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-24T15:23:18.610347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.036607Z","time spent":"573.734571ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-04-24T15:23:18.609706Z","caller":"traceutil/trace.go:171","msg":"trace[1972916226] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"619.822915ms","start":"2026-04-24T15:23:17.989872Z","end":"2026-04-24T15:23:18.609694Z","steps":["trace[1972916226] 'process raft request' (duration: 618.562706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:18.610502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:17.989835Z","time spent":"620.631303ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:18.610314Z","caller":"traceutil/trace.go:171","msg":"trace[981548383] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"569.768983ms","start":"2026-04-24T15:23:18.040532Z","end":"2026-04-24T15:23:18.610301Z","steps":["trace[981548383] 'agreement among raft nodes before linearized reading' (duration: 569.50029ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:18.60967Z","caller":"traceutil/trace.go:171","msg":"trace[1418048481] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"619.930301ms","start":"2026-04-24T15:23:17.989728Z","end":"2026-04-24T15:23:18.609658Z","steps":["trace[1418048481] 'process raft request' (duration: 618.675662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:18.610707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:17.989701Z","time spent":"620.98455ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:18.610845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.7477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-04-24T15:23:18.610875Z","caller":"traceutil/trace.go:171","msg":"trace[1278100028] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20; }","duration":"623.974031ms","start":"2026-04-24T15:23:17.986893Z","end":"2026-04-24T15:23:18.610867Z","steps":["trace[1278100028] 'agreement among raft nodes before linearized reading' (duration: 623.892877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:18.610876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.040512Z","time spent":"570.154571ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-24T15:23:18.610898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:17.986885Z","time spent":"624.005192ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3514,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-24T15:23:18.609428Z","caller":"traceutil/trace.go:171","msg":"trace[1413793691] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"619.71411ms","start":"2026-04-24T15:23:17.989686Z","end":"2026-04-24T15:23:18.6094Z","steps":["trace[1413793691] 'process raft request' (duration: 618.564526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:18.611125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:17.989678Z","time spent":"621.41139ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:19.001216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.340949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-24T15:23:19.001458Z","caller":"traceutil/trace.go:171","msg":"trace[18906858] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"385.840002ms","start":"2026-04-24T15:23:18.615588Z","end":"2026-04-24T15:23:19.001428Z","steps":["trace[18906858] 'process raft request' (duration: 225.20676ms)","trace[18906858] 'compare' (duration: 160.135259ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:23:19.00159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.615577Z","time spent":"385.946736ms","remote":"127.0.0.1:49076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:19.001719Z","caller":"traceutil/trace.go:171","msg":"trace[688181886] linearizableReadLoop","detail":"{readStateIndex:34; appliedIndex:25; }","duration":"333.796345ms","start":"2026-04-24T15:23:18.667895Z","end":"2026-04-24T15:23:19.001692Z","steps":["trace[688181886] 'read index received' (duration: 172.844978ms)","trace[688181886] 'applied index is now lower than readState.Index' (duration: 160.950037ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:23:19.00186Z","caller":"traceutil/trace.go:171","msg":"trace[893783712] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"385.311297ms","start":"2026-04-24T15:23:18.616538Z","end":"2026-04-24T15:23:19.001849Z","steps":["trace[893783712] 'process raft request' (duration: 384.95644ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:19.001862Z","caller":"traceutil/trace.go:171","msg":"trace[1967506363] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"384.682066ms","start":"2026-04-24T15:23:18.617137Z","end":"2026-04-24T15:23:19.001819Z","steps":["trace[1967506363] 'process raft request' (duration: 384.436335ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:19.001871Z","caller":"traceutil/trace.go:171","msg":"trace[1271269418] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"385.310067ms","start":"2026-04-24T15:23:18.616502Z","end":"2026-04-24T15:23:19.001812Z","steps":["trace[1271269418] 'process raft request' (duration: 384.963131ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:19.001941Z","caller":"traceutil/trace.go:171","msg":"trace[936748127] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"384.235756ms","start":"2026-04-24T15:23:18.617697Z","end":"2026-04-24T15:23:19.001933Z","steps":["trace[936748127] 'process raft request' (duration: 383.942352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.002003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.617685Z","time spent":"384.285267ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:19.002052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.617112Z","time spent":"384.858544ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:19.001919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.616525Z","time spent":"385.360679ms","remote":"127.0.0.1:49034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:19.002012Z","caller":"traceutil/trace.go:171","msg":"trace[525027178] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"384.793092ms","start":"2026-04-24T15:23:18.617149Z","end":"2026-04-24T15:23:19.001942Z","steps":["trace[525027178] 'process raft request' (duration: 384.398784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.00225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.116253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:19.002293Z","caller":"traceutil/trace.go:171","msg":"trace[1212053670] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:30; }","duration":"333.182656ms","start":"2026-04-24T15:23:18.669097Z","end":"2026-04-24T15:23:19.00228Z","steps":["trace[1212053670] 'agreement among raft nodes before linearized reading' (duration: 333.019258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.002285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.617118Z","time spent":"385.027803ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:19.002322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.669087Z","time spent":"333.228829ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-04-24T15:23:19.002038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.616492Z","time spent":"385.472064ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:19.002449Z","caller":"traceutil/trace.go:171","msg":"trace[953801226] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"385.991468ms","start":"2026-04-24T15:23:18.616446Z","end":"2026-04-24T15:23:19.002437Z","steps":["trace[953801226] 'process raft request' (duration: 384.883856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.002478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.335371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:19.002511Z","caller":"traceutil/trace.go:171","msg":"trace[845026513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:30; }","duration":"291.386773ms","start":"2026-04-24T15:23:18.711116Z","end":"2026-04-24T15:23:19.002502Z","steps":["trace[845026513] 'agreement among raft nodes before linearized reading' (duration: 291.342111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.002521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.61615Z","time spent":"386.328774ms","remote":"127.0.0.1:48806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:19.002687Z","caller":"traceutil/trace.go:171","msg":"trace[881015426] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"386.225169ms","start":"2026-04-24T15:23:18.61645Z","end":"2026-04-24T15:23:19.002675Z","steps":["trace[881015426] 'process raft request' (duration: 384.9521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.002687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.554249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:19.00273Z","caller":"traceutil/trace.go:171","msg":"trace[1115616583] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:30; }","duration":"331.630522ms","start":"2026-04-24T15:23:18.671086Z","end":"2026-04-24T15:23:19.002717Z","steps":["trace[1115616583] 'agreement among raft nodes before linearized reading' (duration: 331.538808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.002749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.616358Z","time spent":"386.354296ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:19.002761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.671074Z","time spent":"331.680585ms","remote":"127.0.0.1:49002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"info","ts":"2026-04-24T15:23:19.001906Z","caller":"traceutil/trace.go:171","msg":"trace[2065033335] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"384.642695ms","start":"2026-04-24T15:23:18.617252Z","end":"2026-04-24T15:23:19.001894Z","steps":["trace[2065033335] 'process raft request' (duration: 384.354591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.002933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.617235Z","time spent":"385.654812ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:19.003235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.418705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:19.003276Z","caller":"traceutil/trace.go:171","msg":"trace[1119732529] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:30; }","duration":"387.474758ms","start":"2026-04-24T15:23:18.615791Z","end":"2026-04-24T15:23:19.003266Z","steps":["trace[1119732529] 'agreement among raft nodes before linearized reading' (duration: 386.223339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.003317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.615783Z","time spent":"387.527071ms","remote":"127.0.0.1:49012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-cluster-critical\" "} {"level":"warn","ts":"2026-04-24T15:23:19.003466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.948234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:19.003504Z","caller":"traceutil/trace.go:171","msg":"trace[1002294649] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:30; }","duration":"387.005278ms","start":"2026-04-24T15:23:18.616487Z","end":"2026-04-24T15:23:19.003492Z","steps":["trace[1002294649] 'agreement among raft nodes before linearized reading' (duration: 386.571166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.003536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.616474Z","time spent":"387.050669ms","remote":"127.0.0.1:48806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"warn","ts":"2026-04-24T15:23:19.003688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.454428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:19.003724Z","caller":"traceutil/trace.go:171","msg":"trace[1646857252] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:30; }","duration":"387.50106ms","start":"2026-04-24T15:23:18.616215Z","end":"2026-04-24T15:23:19.003716Z","steps":["trace[1646857252] 'agreement among raft nodes before linearized reading' (duration: 386.860479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.003753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:18.616208Z","time spent":"387.537483ms","remote":"127.0.0.1:48814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-04-24T15:23:19.741124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.228984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-24T15:23:19.741626Z","caller":"traceutil/trace.go:171","msg":"trace[1413254183] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"730.263369ms","start":"2026-04-24T15:23:19.011342Z","end":"2026-04-24T15:23:19.741606Z","steps":["trace[1413254183] 'process raft request' (duration: 730.166885ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:19.741595Z","caller":"traceutil/trace.go:171","msg":"trace[86637966] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"730.948011ms","start":"2026-04-24T15:23:19.010593Z","end":"2026-04-24T15:23:19.741541Z","steps":["trace[86637966] 'process raft request' (duration: 442.184182ms)","trace[86637966] 'compare' (duration: 284.813133ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:23:19.741707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.011328Z","time spent":"730.353733ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:19.741724Z","caller":"traceutil/trace.go:171","msg":"trace[302677017] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"730.617946ms","start":"2026-04-24T15:23:19.011085Z","end":"2026-04-24T15:23:19.741702Z","steps":["trace[302677017] 'process raft request' (duration: 730.351233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.741776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.010577Z","time spent":"731.115278ms","remote":"127.0.0.1:49012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:19.741822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.011074Z","time spent":"730.698769ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:19.742016Z","caller":"traceutil/trace.go:171","msg":"trace[1151740686] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"729.23918ms","start":"2026-04-24T15:23:19.012767Z","end":"2026-04-24T15:23:19.742006Z","steps":["trace[1151740686] 'process raft request' (duration: 728.866893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.742056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.012758Z","time spent":"729.281662ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":350,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:19.742097Z","caller":"traceutil/trace.go:171","msg":"trace[1877001144] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"730.395425ms","start":"2026-04-24T15:23:19.01169Z","end":"2026-04-24T15:23:19.742085Z","steps":["trace[1877001144] 'process raft request' (duration: 729.881511ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:19.742129Z","caller":"traceutil/trace.go:171","msg":"trace[965645874] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"730.675678ms","start":"2026-04-24T15:23:19.011441Z","end":"2026-04-24T15:23:19.742117Z","steps":["trace[965645874] 'process raft request' (duration: 730.088031ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:19.742153Z","caller":"traceutil/trace.go:171","msg":"trace[1039062135] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"730.368934ms","start":"2026-04-24T15:23:19.01177Z","end":"2026-04-24T15:23:19.742139Z","steps":["trace[1039062135] 'process raft request' (duration: 729.820118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.742161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.011679Z","time spent":"730.453848ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:19.742184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.011758Z","time spent":"730.413336ms","remote":"127.0.0.1:48806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:19.742189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.011409Z","time spent":"730.756811ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:19.742237Z","caller":"traceutil/trace.go:171","msg":"trace[1918835944] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"730.150613ms","start":"2026-04-24T15:23:19.012081Z","end":"2026-04-24T15:23:19.742231Z","steps":["trace[1918835944] 'process raft request' (duration: 729.539584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.742265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.012071Z","time spent":"730.183624ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:19.742284Z","caller":"traceutil/trace.go:171","msg":"trace[1683862884] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"727.364781ms","start":"2026-04-24T15:23:19.014898Z","end":"2026-04-24T15:23:19.742263Z","steps":["trace[1683862884] 'process raft request' (duration: 726.756233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.742333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.014878Z","time spent":"727.432464ms","remote":"127.0.0.1:48850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:19.742414Z","caller":"traceutil/trace.go:171","msg":"trace[1341807532] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"727.466176ms","start":"2026-04-24T15:23:19.014942Z","end":"2026-04-24T15:23:19.742408Z","steps":["trace[1341807532] 'process raft request' (duration: 727.000514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.742455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.014931Z","time spent":"727.503707ms","remote":"127.0.0.1:48850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:19.742531Z","caller":"traceutil/trace.go:171","msg":"trace[870007950] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"678.987658ms","start":"2026-04-24T15:23:19.063533Z","end":"2026-04-24T15:23:19.742521Z","steps":["trace[870007950] 'process raft request' (duration: 678.495795ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:19.74259Z","caller":"traceutil/trace.go:171","msg":"trace[1965964887] linearizableReadLoop","detail":"{readStateIndex:37; appliedIndex:34; }","duration":"731.266246ms","start":"2026-04-24T15:23:19.011316Z","end":"2026-04-24T15:23:19.742582Z","steps":["trace[1965964887] 'read index received' (duration: 48.524361ms)","trace[1965964887] 'applied index is now lower than readState.Index' (duration: 682.740985ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:23:19.742669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.063518Z","time spent":"679.084082ms","remote":"127.0.0.1:49076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":711,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:19.74274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.414633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:19.742789Z","caller":"traceutil/trace.go:171","msg":"trace[377390876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:42; }","duration":"731.486626ms","start":"2026-04-24T15:23:19.011291Z","end":"2026-04-24T15:23:19.742778Z","steps":["trace[377390876] 'agreement among raft nodes before linearized reading' (duration: 731.338899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.742824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.01128Z","time spent":"731.537748ms","remote":"127.0.0.1:48700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-24T15:23:19.742825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.230008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:19.742916Z","caller":"traceutil/trace.go:171","msg":"trace[1641023482] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:42; }","duration":"683.368484ms","start":"2026-04-24T15:23:19.059527Z","end":"2026-04-24T15:23:19.742896Z","steps":["trace[1641023482] 'agreement among raft nodes before linearized reading' (duration: 683.145973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.742757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.895938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-24T15:23:19.742967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.059511Z","time spent":"683.447848ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-04-24T15:23:19.742986Z","caller":"traceutil/trace.go:171","msg":"trace[1929893509] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:42; }","duration":"731.15791ms","start":"2026-04-24T15:23:19.011818Z","end":"2026-04-24T15:23:19.742976Z","steps":["trace[1929893509] 'agreement among raft nodes before linearized reading' (duration: 730.827604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:19.743018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.011808Z","time spent":"731.201613ms","remote":"127.0.0.1:49002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"warn","ts":"2026-04-24T15:23:20.418697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.638793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-24T15:23:20.418992Z","caller":"traceutil/trace.go:171","msg":"trace[1381252134] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"668.396039ms","start":"2026-04-24T15:23:19.750571Z","end":"2026-04-24T15:23:20.418967Z","steps":["trace[1381252134] 'process raft request' (duration: 668.321935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:20.419203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.750553Z","time spent":"668.553406ms","remote":"127.0.0.1:49076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":715,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:20.419396Z","caller":"traceutil/trace.go:171","msg":"trace[2006464360] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"669.759292ms","start":"2026-04-24T15:23:19.749557Z","end":"2026-04-24T15:23:20.419316Z","steps":["trace[2006464360] 'process raft request' (duration: 476.392205ms)","trace[2006464360] 'compare' (duration: 192.507937ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:23:20.419598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.074571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:20.419643Z","caller":"traceutil/trace.go:171","msg":"trace[205238252] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:44; }","duration":"669.187686ms","start":"2026-04-24T15:23:19.750443Z","end":"2026-04-24T15:23:20.419631Z","steps":["trace[205238252] 'agreement among raft nodes before linearized reading' (duration: 668.947935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:20.41968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.750427Z","time spent":"669.245219ms","remote":"127.0.0.1:49002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"warn","ts":"2026-04-24T15:23:20.41969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.749542Z","time spent":"669.955212ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:20.419338Z","caller":"traceutil/trace.go:171","msg":"trace[1455869176] linearizableReadLoop","detail":"{readStateIndex:47; appliedIndex:46; }","duration":"668.813538ms","start":"2026-04-24T15:23:19.750494Z","end":"2026-04-24T15:23:20.419308Z","steps":["trace[1455869176] 'read index received' (duration: 475.466532ms)","trace[1455869176] 'applied index is now lower than readState.Index' (duration: 193.343906ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:23:20.420256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.682909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:20.420303Z","caller":"traceutil/trace.go:171","msg":"trace[756595451] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:44; }","duration":"669.770483ms","start":"2026-04-24T15:23:19.750519Z","end":"2026-04-24T15:23:20.42029Z","steps":["trace[756595451] 'agreement among raft nodes before linearized reading' (duration: 669.70934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:20.420333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.750494Z","time spent":"669.831915ms","remote":"127.0.0.1:48780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" "} {"level":"warn","ts":"2026-04-24T15:23:20.420414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.899452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-24T15:23:20.420536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.11735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:20.421578Z","caller":"traceutil/trace.go:171","msg":"trace[357197020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:44; }","duration":"610.502356ms","start":"2026-04-24T15:23:19.811056Z","end":"2026-04-24T15:23:20.421559Z","steps":["trace[357197020] 'agreement among raft nodes before linearized reading' (duration: 609.184433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:20.421747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.811045Z","time spent":"610.641882ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-24T15:23:20.421128Z","caller":"traceutil/trace.go:171","msg":"trace[1173360639] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:44; }","duration":"619.671809ms","start":"2026-04-24T15:23:19.801437Z","end":"2026-04-24T15:23:20.421108Z","steps":["trace[1173360639] 'agreement among raft nodes before linearized reading' (duration: 618.892022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:20.422321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:19.801426Z","time spent":"620.877745ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-04-24T15:23:20.695628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.731447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-24T15:23:20.695911Z","caller":"traceutil/trace.go:171","msg":"trace[729962908] linearizableReadLoop","detail":"{readStateIndex:50; appliedIndex:49; }","duration":"213.650625ms","start":"2026-04-24T15:23:20.482241Z","end":"2026-04-24T15:23:20.695892Z","steps":["trace[729962908] 'read index received' (duration: 45.491068ms)","trace[729962908] 'applied index is now lower than readState.Index' (duration: 168.157647ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:23:20.696015Z","caller":"traceutil/trace.go:171","msg":"trace[1825758239] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"271.026762ms","start":"2026-04-24T15:23:20.424962Z","end":"2026-04-24T15:23:20.695988Z","steps":["trace[1825758239] 'process raft request' (duration: 102.823653ms)","trace[1825758239] 'compare' (duration: 167.552648ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:23:20.696113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.354224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a954473ca7bf25\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:20.696185Z","caller":"traceutil/trace.go:171","msg":"trace[1052736970] range","detail":"{range_begin:/registry/events/default/instance.18a954473ca7bf25; range_end:; response_count:0; response_revision:46; }","duration":"269.467029ms","start":"2026-04-24T15:23:20.426703Z","end":"2026-04-24T15:23:20.69617Z","steps":["trace[1052736970] 'agreement among raft nodes before linearized reading' (duration: 269.28068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:20.696122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.916166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:20.696382Z","caller":"traceutil/trace.go:171","msg":"trace[1380882076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:46; }","duration":"168.200469ms","start":"2026-04-24T15:23:20.528124Z","end":"2026-04-24T15:23:20.696324Z","steps":["trace[1380882076] 'agreement among raft nodes before linearized reading' (duration: 167.932577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:20.696312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.256724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:20.697171Z","caller":"traceutil/trace.go:171","msg":"trace[1134799351] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:0; response_revision:46; }","duration":"272.289002ms","start":"2026-04-24T15:23:20.424867Z","end":"2026-04-24T15:23:20.697156Z","steps":["trace[1134799351] 'agreement among raft nodes before linearized reading' (duration: 271.154919ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:21.171657Z","caller":"traceutil/trace.go:171","msg":"trace[1815271561] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"471.559087ms","start":"2026-04-24T15:23:20.700053Z","end":"2026-04-24T15:23:21.171612Z","steps":["trace[1815271561] 'process raft request' (duration: 403.560058ms)","trace[1815271561] 'compare' (duration: 66.95602ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:23:21.171836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:20.700029Z","time spent":"471.727366ms","remote":"127.0.0.1:49074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:21.171814Z","caller":"traceutil/trace.go:171","msg":"trace[2090078472] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"470.477696ms","start":"2026-04-24T15:23:20.701312Z","end":"2026-04-24T15:23:21.17179Z","steps":["trace[2090078472] 'process raft request' (duration: 470.184452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:21.172209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:20.701289Z","time spent":"470.645524ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:21.172537Z","caller":"traceutil/trace.go:171","msg":"trace[1612131425] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"470.057087ms","start":"2026-04-24T15:23:20.702463Z","end":"2026-04-24T15:23:21.17252Z","steps":["trace[1612131425] 'process raft request' (duration: 469.131742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:21.17263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:20.702449Z","time spent":"470.149411ms","remote":"127.0.0.1:49002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:21.172857Z","caller":"traceutil/trace.go:171","msg":"trace[45121432] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"469.935851ms","start":"2026-04-24T15:23:20.702907Z","end":"2026-04-24T15:23:21.172843Z","steps":["trace[45121432] 'process raft request' (duration: 468.724093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:21.173356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:20.702898Z","time spent":"470.291998ms","remote":"127.0.0.1:49074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1058,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:23:21.200237Z","caller":"traceutil/trace.go:171","msg":"trace[1225389080] linearizableReadLoop","detail":"{readStateIndex:56; appliedIndex:51; }","duration":"289.503366ms","start":"2026-04-24T15:23:20.910706Z","end":"2026-04-24T15:23:21.200209Z","steps":["trace[1225389080] 'read index received' (duration: 192.908986ms)","trace[1225389080] 'applied index is now lower than readState.Index' (duration: 96.5932ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:23:21.200264Z","caller":"traceutil/trace.go:171","msg":"trace[1733757704] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"293.428861ms","start":"2026-04-24T15:23:20.906797Z","end":"2026-04-24T15:23:21.200226Z","steps":["trace[1733757704] 'process raft request' (duration: 293.280884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:21.200572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.866132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:21.200624Z","caller":"traceutil/trace.go:171","msg":"trace[1922492396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:51; }","duration":"289.964237ms","start":"2026-04-24T15:23:20.910648Z","end":"2026-04-24T15:23:21.200612Z","steps":["trace[1922492396] 'agreement among raft nodes before linearized reading' (duration: 289.663833ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:45.80033Z","caller":"traceutil/trace.go:171","msg":"trace[182771310] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"283.415819ms","start":"2026-04-24T15:23:45.516883Z","end":"2026-04-24T15:23:45.800299Z","steps":["trace[182771310] 'read index received' (duration: 283.186345ms)","trace[182771310] 'applied index is now lower than readState.Index' (duration: 228.164µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:23:45.800476Z","caller":"traceutil/trace.go:171","msg":"trace[624554385] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"325.080131ms","start":"2026-04-24T15:23:45.475348Z","end":"2026-04-24T15:23:45.800428Z","steps":["trace[624554385] 'process raft request' (duration: 324.777684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:45.800598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.683831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:23:45.800675Z","caller":"traceutil/trace.go:171","msg":"trace[1514270521] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:496; }","duration":"283.814798ms","start":"2026-04-24T15:23:45.516839Z","end":"2026-04-24T15:23:45.800654Z","steps":["trace[1514270521] 'agreement among raft nodes before linearized reading' (duration: 283.687636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:45.80067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.909309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-24T15:23:45.800742Z","caller":"traceutil/trace.go:171","msg":"trace[778470656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"178.023134ms","start":"2026-04-24T15:23:45.622703Z","end":"2026-04-24T15:23:45.800726Z","steps":["trace[778470656] 'agreement among raft nodes before linearized reading' (duration: 177.912285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:45.800742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:45.475322Z","time spent":"325.289003ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:23:45.923801Z","caller":"traceutil/trace.go:171","msg":"trace[507683081] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"108.494041ms","start":"2026-04-24T15:23:45.815282Z","end":"2026-04-24T15:23:45.923776Z","steps":["trace[507683081] 'read index received' (duration: 108.354713ms)","trace[507683081] 'applied index is now lower than readState.Index' (duration: 138.778µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:23:45.923885Z","caller":"traceutil/trace.go:171","msg":"trace[451730689] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"117.515985ms","start":"2026-04-24T15:23:45.806359Z","end":"2026-04-24T15:23:45.923875Z","steps":["trace[451730689] 'process raft request' (duration: 117.324467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:45.924095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.797138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:23:45.924131Z","caller":"traceutil/trace.go:171","msg":"trace[1048330804] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:497; }","duration":"108.873539ms","start":"2026-04-24T15:23:45.815247Z","end":"2026-04-24T15:23:45.924121Z","steps":["trace[1048330804] 'agreement among raft nodes before linearized reading' (duration: 108.815491ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:50.919313Z","caller":"traceutil/trace.go:171","msg":"trace[1740008798] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"349.664191ms","start":"2026-04-24T15:23:50.569627Z","end":"2026-04-24T15:23:50.919292Z","steps":["trace[1740008798] 'read index received' (duration: 348.700588ms)","trace[1740008798] 'applied index is now lower than readState.Index' (duration: 962.983µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:23:50.919418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.778455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:23:50.919401Z","caller":"traceutil/trace.go:171","msg":"trace[1159196278] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"553.622493ms","start":"2026-04-24T15:23:50.365726Z","end":"2026-04-24T15:23:50.919348Z","steps":["trace[1159196278] 'process raft request' (duration: 552.826894ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:50.919444Z","caller":"traceutil/trace.go:171","msg":"trace[1552228550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"349.870517ms","start":"2026-04-24T15:23:50.569566Z","end":"2026-04-24T15:23:50.919436Z","steps":["trace[1552228550] 'agreement among raft nodes before linearized reading' (duration: 349.806955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:50.919566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:50.569547Z","time spent":"350.012299ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-24T15:23:50.919582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:50.365702Z","time spent":"553.777326ms","remote":"127.0.0.1:48794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:23:51.084011Z","caller":"traceutil/trace.go:171","msg":"trace[1888129584] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"113.309433ms","start":"2026-04-24T15:23:50.970375Z","end":"2026-04-24T15:23:51.083684Z","steps":["trace[1888129584] 'process raft request' (duration: 34.762633ms)","trace[1888129584] 'compare' (duration: 78.266734ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:23:51.843958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.585479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-24T15:23:51.844028Z","caller":"traceutil/trace.go:171","msg":"trace[1166729668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"403.701661ms","start":"2026-04-24T15:23:51.440308Z","end":"2026-04-24T15:23:51.84401Z","steps":["trace[1166729668] 'range keys from in-memory index tree' (duration: 403.362843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:51.844061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:51.440287Z","time spent":"403.766232ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-24T15:23:51.851233Z","caller":"traceutil/trace.go:171","msg":"trace[303028723] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"351.599388ms","start":"2026-04-24T15:23:51.49806Z","end":"2026-04-24T15:23:51.84966Z","steps":["trace[303028723] 'process raft request' (duration: 350.255076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:51.851367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:51.498043Z","time spent":"353.249806ms","remote":"127.0.0.1:48794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:23:52.35758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.473402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-24T15:23:52.357663Z","caller":"traceutil/trace.go:171","msg":"trace[194390787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"391.592795ms","start":"2026-04-24T15:23:51.966049Z","end":"2026-04-24T15:23:52.357642Z","steps":["trace[194390787] 'range keys from in-memory index tree' (duration: 391.300348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:52.357719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:51.966037Z","time spent":"391.662696ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-24T15:23:52.357872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.41676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-24T15:23:52.357946Z","caller":"traceutil/trace.go:171","msg":"trace[868431317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"399.540093ms","start":"2026-04-24T15:23:51.958386Z","end":"2026-04-24T15:23:52.357927Z","steps":["trace[868431317] 'range keys from in-memory index tree' (duration: 399.214664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:52.357986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:51.958367Z","time spent":"399.610164ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-24T15:23:52.357864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.912225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-24T15:23:52.358139Z","caller":"traceutil/trace.go:171","msg":"trace[800681628] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:594; }","duration":"344.197361ms","start":"2026-04-24T15:23:52.013903Z","end":"2026-04-24T15:23:52.358101Z","steps":["trace[800681628] 'range keys from in-memory index tree' (duration: 343.835913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:52.358258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:52.013892Z","time spent":"344.322694ms","remote":"127.0.0.1:48724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.44\" "} {"level":"info","ts":"2026-04-24T15:23:54.195269Z","caller":"traceutil/trace.go:171","msg":"trace[2036091565] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"242.307769ms","start":"2026-04-24T15:23:53.952933Z","end":"2026-04-24T15:23:54.195241Z","steps":["trace[2036091565] 'process raft request' (duration: 242.087443ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:06.199925Z","caller":"traceutil/trace.go:171","msg":"trace[609386178] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"268.56801ms","start":"2026-04-24T15:24:05.93133Z","end":"2026-04-24T15:24:06.199898Z","steps":["trace[609386178] 'process raft request' (duration: 268.384395ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:06.202037Z","caller":"traceutil/trace.go:171","msg":"trace[1421209676] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"269.674637ms","start":"2026-04-24T15:24:05.932337Z","end":"2026-04-24T15:24:06.202011Z","steps":["trace[1421209676] 'process raft request' (duration: 269.539574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:08.954927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.875769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-24T15:24:08.954983Z","caller":"traceutil/trace.go:171","msg":"trace[1596966450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:769; }","duration":"518.991241ms","start":"2026-04-24T15:24:08.435978Z","end":"2026-04-24T15:24:08.954969Z","steps":["trace[1596966450] 'range keys from in-memory index tree' (duration: 518.730375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:08.95501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:08.43595Z","time spent":"519.054493ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-24T15:24:08.955059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.202891ms","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-04-24T15:24:08.955044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.524845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-24T15:24:08.955175Z","caller":"traceutil/trace.go:171","msg":"trace[1085265595] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:769; }","duration":"416.72471ms","start":"2026-04-24T15:24:08.538424Z","end":"2026-04-24T15:24:08.955149Z","steps":["trace[1085265595] 'range keys from in-memory index tree' (duration: 416.30173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:08.955228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:08.538398Z","time spent":"416.817782ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-24T15:24:08.955121Z","caller":"traceutil/trace.go:171","msg":"trace[1501508378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"493.297453ms","start":"2026-04-24T15:24:08.461809Z","end":"2026-04-24T15:24:08.955106Z","steps":["trace[1501508378] 'range keys from in-memory index tree' (duration: 493.062618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:08.955347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:08.461798Z","time spent":"493.535349ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-24T15:27:33.512651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.415588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-24T15:27:33.512769Z","caller":"traceutil/trace.go:171","msg":"trace[1830451739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1541; }","duration":"132.547792ms","start":"2026-04-24T15:27:33.380199Z","end":"2026-04-24T15:27:33.512747Z","steps":["trace[1830451739] 'range keys from in-memory index tree' (duration: 132.178043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:27:33.512758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.922847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-24T15:27:33.512837Z","caller":"traceutil/trace.go:171","msg":"trace[1549767993] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1541; }","duration":"137.124073ms","start":"2026-04-24T15:27:33.375693Z","end":"2026-04-24T15:27:33.512817Z","steps":["trace[1549767993] 'range keys from in-memory index tree' (duration: 136.712042ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:27:33.789254Z","caller":"traceutil/trace.go:171","msg":"trace[1211423845] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"271.81794ms","start":"2026-04-24T15:27:33.517403Z","end":"2026-04-24T15:27:33.789221Z","steps":["trace[1211423845] 'process raft request' (duration: 271.634827ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:27:34.069757Z","caller":"traceutil/trace.go:171","msg":"trace[1568969435] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"166.254593ms","start":"2026-04-24T15:27:33.903482Z","end":"2026-04-24T15:27:34.069737Z","steps":["trace[1568969435] 'process raft request' (duration: 166.204372ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:27:34.07001Z","caller":"traceutil/trace.go:171","msg":"trace[1767941811] linearizableReadLoop","detail":"{readStateIndex:1615; appliedIndex:1613; }","duration":"501.41765ms","start":"2026-04-24T15:27:33.568571Z","end":"2026-04-24T15:27:34.069989Z","steps":["trace[1767941811] 'read index received' (duration: 220.487399ms)","trace[1767941811] 'applied index is now lower than readState.Index' (duration: 280.928231ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:27:34.07016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.584025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:27:34.070126Z","caller":"traceutil/trace.go:171","msg":"trace[1328899847] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"552.069357ms","start":"2026-04-24T15:27:33.518021Z","end":"2026-04-24T15:27:34.07009Z","steps":["trace[1328899847] 'process raft request' (duration: 532.413194ms)","trace[1328899847] 'compare' (duration: 18.826955ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:27:34.07019Z","caller":"traceutil/trace.go:171","msg":"trace[347974883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1544; }","duration":"501.676807ms","start":"2026-04-24T15:27:33.568504Z","end":"2026-04-24T15:27:34.070181Z","steps":["trace[347974883] 'agreement among raft nodes before linearized reading' (duration: 501.597474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:27:34.070217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:27:33.568477Z","time spent":"501.734298ms","remote":"127.0.0.1:48700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-24T15:27:34.070288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:27:33.518011Z","time spent":"552.191561ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:27:34.312083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.99954ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:27:34.312216Z","caller":"traceutil/trace.go:171","msg":"trace[636248603] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1544; }","duration":"142.151113ms","start":"2026-04-24T15:27:34.170046Z","end":"2026-04-24T15:27:34.312197Z","steps":["trace[636248603] 'range keys from in-memory index tree' (duration: 141.984879ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:27:34.312716Z","caller":"traceutil/trace.go:171","msg":"trace[1651015128] linearizableReadLoop","detail":"{readStateIndex:1617; appliedIndex:1616; }","duration":"203.027004ms","start":"2026-04-24T15:27:34.109676Z","end":"2026-04-24T15:27:34.312703Z","steps":["trace[1651015128] 'read index received' (duration: 198.550115ms)","trace[1651015128] 'applied index is now lower than readState.Index' (duration: 4.475529ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:27:34.312979Z","caller":"traceutil/trace.go:171","msg":"trace[1054416516] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"238.65816ms","start":"2026-04-24T15:27:34.074308Z","end":"2026-04-24T15:27:34.312966Z","steps":["trace[1054416516] 'process raft request' (duration: 234.140401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:27:34.313343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.644537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-24T15:27:34.313436Z","caller":"traceutil/trace.go:171","msg":"trace[1522227857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1545; }","duration":"203.78116ms","start":"2026-04-24T15:27:34.109631Z","end":"2026-04-24T15:27:34.313412Z","steps":["trace[1522227857] 'agreement among raft nodes before linearized reading' (duration: 203.509555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:27:34.313442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.427289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-24T15:27:34.313567Z","caller":"traceutil/trace.go:171","msg":"trace[1286658517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1545; }","duration":"137.608172ms","start":"2026-04-24T15:27:34.175927Z","end":"2026-04-24T15:27:34.313535Z","steps":["trace[1286658517] 'agreement among raft nodes before linearized reading' (duration: 137.165782ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:00.145361Z","caller":"traceutil/trace.go:171","msg":"trace[24690819] transaction","detail":"{read_only:false; response_revision:1627; number_of_response:1; }","duration":"215.834878ms","start":"2026-04-24T15:27:59.929497Z","end":"2026-04-24T15:28:00.145331Z","steps":["trace[24690819] 'process raft request' (duration: 215.643352ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:00.702551Z","caller":"traceutil/trace.go:171","msg":"trace[1382306948] transaction","detail":"{read_only:false; response_revision:1629; number_of_response:1; }","duration":"462.063684ms","start":"2026-04-24T15:28:00.240431Z","end":"2026-04-24T15:28:00.702495Z","steps":["trace[1382306948] 'process raft request' (duration: 460.360237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:00.702799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:00.240349Z","time spent":"462.316989ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:28:00.703347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.065292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-24T15:28:00.703457Z","caller":"traceutil/trace.go:171","msg":"trace[585801430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1629; }","duration":"255.244765ms","start":"2026-04-24T15:28:00.448185Z","end":"2026-04-24T15:28:00.70343Z","steps":["trace[585801430] 'agreement among raft nodes before linearized reading' (duration: 254.961469ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:00.70398Z","caller":"traceutil/trace.go:171","msg":"trace[627106566] linearizableReadLoop","detail":"{readStateIndex:1706; appliedIndex:1705; }","duration":"254.169171ms","start":"2026-04-24T15:28:00.448262Z","end":"2026-04-24T15:28:00.702431Z","steps":["trace[627106566] 'read index received' (duration: 252.407962ms)","trace[627106566] 'applied index is now lower than readState.Index' (duration: 1.760109ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:28:00.70461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.026424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-24T15:28:00.704671Z","caller":"traceutil/trace.go:171","msg":"trace[846156334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1629; }","duration":"252.122987ms","start":"2026-04-24T15:28:00.452532Z","end":"2026-04-24T15:28:00.704655Z","steps":["trace[846156334] 'agreement among raft nodes before linearized reading' (duration: 251.81887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:00.70495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.897276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-24T15:28:00.705002Z","caller":"traceutil/trace.go:171","msg":"trace[786687581] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1629; }","duration":"248.973447ms","start":"2026-04-24T15:28:00.456016Z","end":"2026-04-24T15:28:00.70499Z","steps":["trace[786687581] 'agreement among raft nodes before linearized reading' (duration: 248.811694ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:00.821521Z","caller":"traceutil/trace.go:171","msg":"trace[562378304] transaction","detail":"{read_only:false; response_revision:1631; number_of_response:1; }","duration":"111.113709ms","start":"2026-04-24T15:28:00.710348Z","end":"2026-04-24T15:28:00.821462Z","steps":["trace[562378304] 'process raft request' (duration: 102.842427ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:00.821597Z","caller":"traceutil/trace.go:171","msg":"trace[1684339953] transaction","detail":"{read_only:false; response_revision:1632; number_of_response:1; }","duration":"109.131235ms","start":"2026-04-24T15:28:00.712436Z","end":"2026-04-24T15:28:00.821567Z","steps":["trace[1684339953] 'process raft request' (duration: 108.958502ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:02.564247Z","caller":"traceutil/trace.go:171","msg":"trace[1691067632] transaction","detail":"{read_only:false; response_revision:1634; number_of_response:1; }","duration":"420.812224ms","start":"2026-04-24T15:28:02.143402Z","end":"2026-04-24T15:28:02.564215Z","steps":["trace[1691067632] 'process raft request' (duration: 387.802346ms)","trace[1691067632] 'compare' (duration: 32.611539ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:28:02.564433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:02.143382Z","time spent":"420.931877ms","remote":"127.0.0.1:48724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:28:02.564682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.579667ms","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-04-24T15:28:02.564762Z","caller":"traceutil/trace.go:171","msg":"trace[137934319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1634; }","duration":"414.700619ms","start":"2026-04-24T15:28:02.150052Z","end":"2026-04-24T15:28:02.564752Z","steps":["trace[137934319] 'agreement among raft nodes before linearized reading' (duration: 414.364552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:02.564835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:02.150039Z","time spent":"414.786651ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-24T15:28:02.564256Z","caller":"traceutil/trace.go:171","msg":"trace[716291331] linearizableReadLoop","detail":"{readStateIndex:1712; appliedIndex:1711; }","duration":"414.145257ms","start":"2026-04-24T15:28:02.150086Z","end":"2026-04-24T15:28:02.564231Z","steps":["trace[716291331] 'read index received' (duration: 381.067388ms)","trace[716291331] 'applied index is now lower than readState.Index' (duration: 33.075749ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:28:02.565466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.626582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:28:02.56552Z","caller":"traceutil/trace.go:171","msg":"trace[1074648170] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1634; }","duration":"372.712504ms","start":"2026-04-24T15:28:02.192795Z","end":"2026-04-24T15:28:02.565507Z","steps":["trace[1074648170] 'agreement among raft nodes before linearized reading' (duration: 372.621812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:02.565578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:02.192754Z","time spent":"372.796416ms","remote":"127.0.0.1:48826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-04-24T15:28:04.794959Z","caller":"traceutil/trace.go:171","msg":"trace[752737341] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"200.663641ms","start":"2026-04-24T15:28:04.594268Z","end":"2026-04-24T15:28:04.794931Z","steps":["trace[752737341] 'process raft request' (duration: 200.483518ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:05.099204Z","caller":"traceutil/trace.go:171","msg":"trace[73343421] linearizableReadLoop","detail":"{readStateIndex:1721; appliedIndex:1719; }","duration":"268.863306ms","start":"2026-04-24T15:28:04.830248Z","end":"2026-04-24T15:28:05.099111Z","steps":["trace[73343421] 'read index received' (duration: 10.764098ms)","trace[73343421] 'applied index is now lower than readState.Index' (duration: 258.097377ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:28:05.09922Z","caller":"traceutil/trace.go:171","msg":"trace[737589760] transaction","detail":"{read_only:false; response_revision:1643; number_of_response:1; }","duration":"299.533811ms","start":"2026-04-24T15:28:04.799655Z","end":"2026-04-24T15:28:05.099188Z","steps":["trace[737589760] 'process raft request' (duration: 299.048511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:05.099409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.153972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-24T15:28:05.099452Z","caller":"traceutil/trace.go:171","msg":"trace[706025134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1643; }","duration":"269.244014ms","start":"2026-04-24T15:28:04.830199Z","end":"2026-04-24T15:28:05.099443Z","steps":["trace[706025134] 'agreement among raft nodes before linearized reading' (duration: 269.110261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:05.099482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.270596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-24T15:28:05.099524Z","caller":"traceutil/trace.go:171","msg":"trace[1496797455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1643; }","duration":"260.355908ms","start":"2026-04-24T15:28:04.839158Z","end":"2026-04-24T15:28:05.099513Z","steps":["trace[1496797455] 'agreement among raft nodes before linearized reading' (duration: 260.178194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:05.099565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.56028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-24T15:28:05.099589Z","caller":"traceutil/trace.go:171","msg":"trace[1562772467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1643; }","duration":"254.597421ms","start":"2026-04-24T15:28:04.844986Z","end":"2026-04-24T15:28:05.099583Z","steps":["trace[1562772467] 'agreement among raft nodes before linearized reading' (duration: 254.54104ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:17.311099Z","caller":"traceutil/trace.go:171","msg":"trace[440457619] transaction","detail":"{read_only:false; response_revision:1734; number_of_response:1; }","duration":"157.666961ms","start":"2026-04-24T15:28:17.153406Z","end":"2026-04-24T15:28:17.311073Z","steps":["trace[440457619] 'process raft request' (duration: 157.539308ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:17.311388Z","caller":"traceutil/trace.go:171","msg":"trace[778403887] linearizableReadLoop","detail":"{readStateIndex:1816; appliedIndex:1816; }","duration":"140.620619ms","start":"2026-04-24T15:28:17.17073Z","end":"2026-04-24T15:28:17.311351Z","steps":["trace[778403887] 'read index received' (duration: 140.604838ms)","trace[778403887] 'applied index is now lower than readState.Index' (duration: 13.101µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:28:17.311697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.944036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-24T15:28:17.311779Z","caller":"traceutil/trace.go:171","msg":"trace[426718991] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1734; }","duration":"141.062669ms","start":"2026-04-24T15:28:17.170694Z","end":"2026-04-24T15:28:17.311757Z","steps":["trace[426718991] 'agreement among raft nodes before linearized reading' (duration: 140.786953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:17.328312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.455038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-24T15:28:17.328401Z","caller":"traceutil/trace.go:171","msg":"trace[336306772] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1735; }","duration":"141.55788ms","start":"2026-04-24T15:28:17.1868Z","end":"2026-04-24T15:28:17.328358Z","steps":["trace[336306772] 'agreement among raft nodes before linearized reading' (duration: 141.386706ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:17.328361Z","caller":"traceutil/trace.go:171","msg":"trace[696739300] transaction","detail":"{read_only:false; response_revision:1735; number_of_response:1; }","duration":"155.4849ms","start":"2026-04-24T15:28:17.172814Z","end":"2026-04-24T15:28:17.328299Z","steps":["trace[696739300] 'process raft request' (duration: 155.282675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:17.328468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.405424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-24T15:28:17.328531Z","caller":"traceutil/trace.go:171","msg":"trace[108885892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1735; }","duration":"140.504386ms","start":"2026-04-24T15:28:17.188012Z","end":"2026-04-24T15:28:17.328516Z","steps":["trace[108885892] 'agreement among raft nodes before linearized reading' (duration: 140.284351ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:17.532222Z","caller":"traceutil/trace.go:171","msg":"trace[704771301] transaction","detail":"{read_only:false; response_revision:1737; number_of_response:1; }","duration":"199.840949ms","start":"2026-04-24T15:28:17.332344Z","end":"2026-04-24T15:28:17.532185Z","steps":["trace[704771301] 'process raft request' (duration: 198.16257ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:17.532755Z","caller":"traceutil/trace.go:171","msg":"trace[364233706] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"197.72546ms","start":"2026-04-24T15:28:17.335004Z","end":"2026-04-24T15:28:17.532729Z","steps":["trace[364233706] 'process raft request' (duration: 197.134867ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:19.276656Z","caller":"traceutil/trace.go:171","msg":"trace[1234420665] transaction","detail":"{read_only:false; response_revision:1739; number_of_response:1; }","duration":"324.85063ms","start":"2026-04-24T15:28:18.951782Z","end":"2026-04-24T15:28:19.276632Z","steps":["trace[1234420665] 'process raft request' (duration: 324.658446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:19.276818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:18.951762Z","time spent":"324.969152ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:28:19.815715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.430326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:28:19.815792Z","caller":"traceutil/trace.go:171","msg":"trace[1044389485] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"497.835621ms","start":"2026-04-24T15:28:19.317942Z","end":"2026-04-24T15:28:19.815778Z","steps":["trace[1044389485] 'process raft request' (duration: 245.318343ms)","trace[1044389485] 'compare' (duration: 252.262922ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:28:19.815827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:19.317923Z","time spent":"497.888962ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:28:19.816189Z","caller":"traceutil/trace.go:171","msg":"trace[413798739] linearizableReadLoop","detail":"{readStateIndex:1824; appliedIndex:1822; }","duration":"471.21001ms","start":"2026-04-24T15:28:19.344971Z","end":"2026-04-24T15:28:19.816181Z","steps":["trace[413798739] 'read index received' (duration: 218.203611ms)","trace[413798739] 'applied index is now lower than readState.Index' (duration: 253.005199ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:28:19.816414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.72756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-24T15:28:19.816436Z","caller":"traceutil/trace.go:171","msg":"trace[1622805116] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1741; }","duration":"423.783921ms","start":"2026-04-24T15:28:19.392645Z","end":"2026-04-24T15:28:19.816429Z","steps":["trace[1622805116] 'agreement among raft nodes before linearized reading' (duration: 423.682148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:19.816452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:19.392626Z","time spent":"423.822512ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-24T15:28:19.816423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.427265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:28:19.8165Z","caller":"traceutil/trace.go:171","msg":"trace[1756499634] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1741; }","duration":"471.540568ms","start":"2026-04-24T15:28:19.344941Z","end":"2026-04-24T15:28:19.816481Z","steps":["trace[1756499634] 'agreement among raft nodes before linearized reading' (duration: 471.331253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:19.816565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.277427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-24T15:28:19.816558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:19.344927Z","time spent":"471.62075ms","remote":"127.0.0.1:48812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-04-24T15:28:19.81658Z","caller":"traceutil/trace.go:171","msg":"trace[2087093792] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1741; }","duration":"277.373249ms","start":"2026-04-24T15:28:19.539202Z","end":"2026-04-24T15:28:19.816575Z","steps":["trace[2087093792] 'agreement among raft nodes before linearized reading' (duration: 277.318868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:19.816681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.498072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-24T15:28:19.816697Z","caller":"traceutil/trace.go:171","msg":"trace[630718432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1741; }","duration":"277.577605ms","start":"2026-04-24T15:28:19.539115Z","end":"2026-04-24T15:28:19.816693Z","steps":["trace[630718432] 'agreement among raft nodes before linearized reading' (duration: 277.534544ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:19.816389Z","caller":"traceutil/trace.go:171","msg":"trace[194434377] transaction","detail":"{read_only:false; response_revision:1741; number_of_response:1; }","duration":"481.896526ms","start":"2026-04-24T15:28:19.334458Z","end":"2026-04-24T15:28:19.816355Z","steps":["trace[194434377] 'process raft request' (duration: 481.681861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:19.816842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:19.33443Z","time spent":"482.345557ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:28:19.817124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.722816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T15:28:19.817191Z","caller":"traceutil/trace.go:171","msg":"trace[109214007] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1741; }","duration":"219.87637ms","start":"2026-04-24T15:28:19.597301Z","end":"2026-04-24T15:28:19.817177Z","steps":["trace[109214007] 'agreement among raft nodes before linearized reading' (duration: 219.177073ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:32.062698Z","caller":"traceutil/trace.go:171","msg":"trace[1667139091] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"136.268259ms","start":"2026-04-24T15:28:31.926401Z","end":"2026-04-24T15:28:32.062669Z","steps":["trace[1667139091] 'process raft request' (duration: 136.201958ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:32.062773Z","caller":"traceutil/trace.go:171","msg":"trace[822428810] transaction","detail":"{read_only:false; response_revision:1781; number_of_response:1; }","duration":"136.32547ms","start":"2026-04-24T15:28:31.926394Z","end":"2026-04-24T15:28:32.06272Z","steps":["trace[822428810] 'process raft request' (duration: 135.975243ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:32.070595Z","caller":"traceutil/trace.go:171","msg":"trace[790925674] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"142.735727ms","start":"2026-04-24T15:28:31.927807Z","end":"2026-04-24T15:28:32.070542Z","steps":["trace[790925674] 'process raft request' (duration: 142.516012ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:32.07063Z","caller":"traceutil/trace.go:171","msg":"trace[2088709421] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"140.64523ms","start":"2026-04-24T15:28:31.929957Z","end":"2026-04-24T15:28:32.070602Z","steps":["trace[2088709421] 'process raft request' (duration: 140.515057ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:33.775001Z","caller":"traceutil/trace.go:171","msg":"trace[959119868] linearizableReadLoop","detail":"{readStateIndex:1872; appliedIndex:1871; }","duration":"198.469239ms","start":"2026-04-24T15:28:33.576508Z","end":"2026-04-24T15:28:33.774977Z","steps":["trace[959119868] 'read index received' (duration: 198.236394ms)","trace[959119868] 'applied index is now lower than readState.Index' (duration: 231.875µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:28:33.775123Z","caller":"traceutil/trace.go:171","msg":"trace[715752149] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"416.962156ms","start":"2026-04-24T15:28:33.358114Z","end":"2026-04-24T15:28:33.775076Z","steps":["trace[715752149] 'process raft request' (duration: 416.71281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:33.775366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:33.3581Z","time spent":"417.124499ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:28:33.775137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.606092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:28:33.775519Z","caller":"traceutil/trace.go:171","msg":"trace[371718703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1786; }","duration":"198.98243ms","start":"2026-04-24T15:28:33.576496Z","end":"2026-04-24T15:28:33.775479Z","steps":["trace[371718703] 'agreement among raft nodes before linearized reading' (duration: 198.581411ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:34.233012Z","caller":"traceutil/trace.go:171","msg":"trace[1078830812] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"234.70039ms","start":"2026-04-24T15:28:33.998274Z","end":"2026-04-24T15:28:34.232974Z","steps":["trace[1078830812] 'process raft request' (duration: 234.453864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:34.233238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.450066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-24T15:28:34.233359Z","caller":"traceutil/trace.go:171","msg":"trace[1256786224] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1787; }","duration":"164.563599ms","start":"2026-04-24T15:28:34.06871Z","end":"2026-04-24T15:28:34.233273Z","steps":["trace[1256786224] 'agreement among raft nodes before linearized reading' (duration: 164.386295ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:34.232887Z","caller":"traceutil/trace.go:171","msg":"trace[1353902228] linearizableReadLoop","detail":"{readStateIndex:1873; appliedIndex:1872; }","duration":"164.086408ms","start":"2026-04-24T15:28:34.068779Z","end":"2026-04-24T15:28:34.232866Z","steps":["trace[1353902228] 'read index received' (duration: 163.911644ms)","trace[1353902228] 'applied index is now lower than readState.Index' (duration: 173.854µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:28:34.233668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.805724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-24T15:28:34.233693Z","caller":"traceutil/trace.go:171","msg":"trace[331625019] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1787; }","duration":"164.869156ms","start":"2026-04-24T15:28:34.068817Z","end":"2026-04-24T15:28:34.233686Z","steps":["trace[331625019] 'agreement among raft nodes before linearized reading' (duration: 164.802785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:34.233803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.90166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-24T15:28:34.233826Z","caller":"traceutil/trace.go:171","msg":"trace[555957132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1787; }","duration":"158.979391ms","start":"2026-04-24T15:28:34.07484Z","end":"2026-04-24T15:28:34.233819Z","steps":["trace[555957132] 'agreement among raft nodes before linearized reading' (duration: 158.908299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:34.233909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.100464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-24T15:28:34.23393Z","caller":"traceutil/trace.go:171","msg":"trace[1817492751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1787; }","duration":"159.164325ms","start":"2026-04-24T15:28:34.07476Z","end":"2026-04-24T15:28:34.233925Z","steps":["trace[1817492751] 'agreement among raft nodes before linearized reading' (duration: 159.112334ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:34.762394Z","caller":"traceutil/trace.go:171","msg":"trace[587939643] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"522.946179ms","start":"2026-04-24T15:28:34.239409Z","end":"2026-04-24T15:28:34.762356Z","steps":["trace[587939643] 'process raft request' (duration: 429.585005ms)","trace[587939643] 'compare' (duration: 93.098748ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:28:34.762578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:34.239379Z","time spent":"523.099272ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:28:34.765903Z","caller":"traceutil/trace.go:171","msg":"trace[158657670] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"525.939358ms","start":"2026-04-24T15:28:34.239934Z","end":"2026-04-24T15:28:34.765873Z","steps":["trace[158657670] 'process raft request' (duration: 525.885947ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:34.76595Z","caller":"traceutil/trace.go:171","msg":"trace[1058271378] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"526.342877ms","start":"2026-04-24T15:28:34.239581Z","end":"2026-04-24T15:28:34.765924Z","steps":["trace[1058271378] 'process raft request' (duration: 526.04511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:34.766046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:34.239558Z","time spent":"526.438289ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:28:34.766168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:34.239911Z","time spent":"526.07531ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:28:34.766073Z","caller":"traceutil/trace.go:171","msg":"trace[1827004738] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"526.115252ms","start":"2026-04-24T15:28:34.239901Z","end":"2026-04-24T15:28:34.766017Z","steps":["trace[1827004738] 'process raft request' (duration: 525.879117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:34.76883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:34.239895Z","time spent":"528.859985ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:28:35.792185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.157774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:28:35.792271Z","caller":"traceutil/trace.go:171","msg":"trace[1571532872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1792; }","duration":"223.305917ms","start":"2026-04-24T15:28:35.568942Z","end":"2026-04-24T15:28:35.792248Z","steps":["trace[1571532872] 'range keys from in-memory index tree' (duration: 222.99747ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:36.153378Z","caller":"traceutil/trace.go:171","msg":"trace[1686466746] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"356.709751ms","start":"2026-04-24T15:28:35.796642Z","end":"2026-04-24T15:28:36.153352Z","steps":["trace[1686466746] 'process raft request' (duration: 356.534817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:36.153588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:35.796616Z","time spent":"356.880255ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:28:36.692229Z","caller":"traceutil/trace.go:171","msg":"trace[992488547] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"473.303849ms","start":"2026-04-24T15:28:36.218894Z","end":"2026-04-24T15:28:36.692198Z","steps":["trace[992488547] 'process raft request' (duration: 473.136565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:36.692458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:36.218868Z","time spent":"473.452852ms","remote":"127.0.0.1:48778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":839,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:28:36.789605Z","caller":"traceutil/trace.go:171","msg":"trace[1145812469] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"550.41509ms","start":"2026-04-24T15:28:36.239157Z","end":"2026-04-24T15:28:36.789572Z","steps":["trace[1145812469] 'process raft request' (duration: 508.002506ms)","trace[1145812469] 'compare' (duration: 42.086226ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:28:36.789802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:36.239122Z","time spent":"550.571974ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:28:36.950016Z","caller":"traceutil/trace.go:171","msg":"trace[1550780698] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"154.516209ms","start":"2026-04-24T15:28:36.795472Z","end":"2026-04-24T15:28:36.949988Z","steps":["trace[1550780698] 'process raft request' (duration: 154.431437ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:36.950034Z","caller":"traceutil/trace.go:171","msg":"trace[2086877980] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"154.663012ms","start":"2026-04-24T15:28:36.795328Z","end":"2026-04-24T15:28:36.949991Z","steps":["trace[2086877980] 'process raft request' (duration: 108.234985ms)","trace[2086877980] 'compare' (duration: 46.227483ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:28:36.950213Z","caller":"traceutil/trace.go:171","msg":"trace[1535766868] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"154.618872ms","start":"2026-04-24T15:28:36.79556Z","end":"2026-04-24T15:28:36.950179Z","steps":["trace[1535766868] 'process raft request' (duration: 154.382626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:38.013188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.57694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-04-24T15:28:38.013264Z","caller":"traceutil/trace.go:171","msg":"trace[1139873058] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1804; }","duration":"647.705193ms","start":"2026-04-24T15:28:37.365542Z","end":"2026-04-24T15:28:38.013247Z","steps":["trace[1139873058] 'range keys from in-memory index tree' (duration: 647.408157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:38.013297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:37.365521Z","time spent":"647.768445ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5354,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-24T15:28:38.207647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.892329ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-24T15:28:38.208047Z","caller":"traceutil/trace.go:171","msg":"trace[1405739329] linearizableReadLoop","detail":"{readStateIndex:1892; appliedIndex:1890; }","duration":"638.676927ms","start":"2026-04-24T15:28:37.56933Z","end":"2026-04-24T15:28:38.208007Z","steps":["trace[1405739329] 'read index received' (duration: 385.42208ms)","trace[1405739329] 'applied index is now lower than readState.Index' (duration: 253.252147ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:28:38.208133Z","caller":"traceutil/trace.go:171","msg":"trace[562190346] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"697.035477ms","start":"2026-04-24T15:28:37.51103Z","end":"2026-04-24T15:28:38.208066Z","steps":["trace[562190346] 'process raft request' (duration: 696.742841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:38.208396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:37.51101Z","time spent":"697.253853ms","remote":"127.0.0.1:48778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:28:38.219073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.468343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T15:28:38.219105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.933502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:28:38.219132Z","caller":"traceutil/trace.go:171","msg":"trace[1757359752] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1805; }","duration":"122.574205ms","start":"2026-04-24T15:28:38.096539Z","end":"2026-04-24T15:28:38.219113Z","steps":["trace[1757359752] 'agreement among raft nodes before linearized reading' (duration: 111.805778ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:38.219163Z","caller":"traceutil/trace.go:171","msg":"trace[2062918580] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:0; response_revision:1805; }","duration":"193.043234ms","start":"2026-04-24T15:28:38.026104Z","end":"2026-04-24T15:28:38.219147Z","steps":["trace[2062918580] 'agreement among raft nodes before linearized reading' (duration: 182.205945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:38.219164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.803272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:28:38.219266Z","caller":"traceutil/trace.go:171","msg":"trace[1920953205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1805; }","duration":"649.953596ms","start":"2026-04-24T15:28:37.569289Z","end":"2026-04-24T15:28:38.219243Z","steps":["trace[1920953205] 'agreement among raft nodes before linearized reading' (duration: 638.967694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:38.219317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:37.569271Z","time spent":"650.036339ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-24T15:28:38.564958Z","caller":"traceutil/trace.go:171","msg":"trace[323483341] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"339.092176ms","start":"2026-04-24T15:28:38.225832Z","end":"2026-04-24T15:28:38.564924Z","steps":["trace[323483341] 'process raft request' (duration: 338.921142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:38.565159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:38.225817Z","time spent":"339.25341ms","remote":"127.0.0.1:48850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1475,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:28:39.224172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.838832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-24T15:28:39.224271Z","caller":"traceutil/trace.go:171","msg":"trace[1933763038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1813; }","duration":"268.978316ms","start":"2026-04-24T15:28:38.955267Z","end":"2026-04-24T15:28:39.224246Z","steps":["trace[1933763038] 'range keys from in-memory index tree' (duration: 268.602917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:39.224546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.938715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-24T15:28:39.22464Z","caller":"traceutil/trace.go:171","msg":"trace[1638579913] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1813; }","duration":"269.12242ms","start":"2026-04-24T15:28:38.955495Z","end":"2026-04-24T15:28:39.224618Z","steps":["trace[1638579913] 'range keys from in-memory index tree' (duration: 268.623806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:28:39.22527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.460293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-24T15:28:39.225314Z","caller":"traceutil/trace.go:171","msg":"trace[1018498303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1813; }","duration":"268.580916ms","start":"2026-04-24T15:28:38.956722Z","end":"2026-04-24T15:28:39.225303Z","steps":["trace[1018498303] 'range keys from in-memory index tree' (duration: 266.974949ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:39.38521Z","caller":"traceutil/trace.go:171","msg":"trace[1940630216] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"155.44744ms","start":"2026-04-24T15:28:39.229687Z","end":"2026-04-24T15:28:39.385134Z","steps":["trace[1940630216] 'process raft request' (duration: 155.386639ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:28:39.385265Z","caller":"traceutil/trace.go:171","msg":"trace[1013199067] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"155.582673ms","start":"2026-04-24T15:28:39.229606Z","end":"2026-04-24T15:28:39.385189Z","steps":["trace[1013199067] 'process raft request' (duration: 133.433744ms)","trace[1013199067] 'compare' (duration: 21.860232ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:28:46.024395Z","caller":"traceutil/trace.go:171","msg":"trace[1114763134] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"133.549387ms","start":"2026-04-24T15:28:45.890796Z","end":"2026-04-24T15:28:46.024345Z","steps":["trace[1114763134] 'process raft request' (duration: 92.526344ms)","trace[1114763134] 'compare' (duration: 40.567242ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:29:00.035763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710538088936422367,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-24T15:29:00.043816Z","caller":"traceutil/trace.go:171","msg":"trace[2056813195] linearizableReadLoop","detail":"{readStateIndex:2069; appliedIndex:2068; }","duration":"508.696911ms","start":"2026-04-24T15:28:59.535091Z","end":"2026-04-24T15:29:00.043787Z","steps":["trace[2056813195] 'read index received' (duration: 508.452285ms)","trace[2056813195] 'applied index is now lower than readState.Index' (duration: 243.446µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:29:00.043901Z","caller":"traceutil/trace.go:171","msg":"trace[2019923862] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"511.849224ms","start":"2026-04-24T15:28:59.532015Z","end":"2026-04-24T15:29:00.043864Z","steps":["trace[2019923862] 'process raft request' (duration: 511.573187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:29:00.044081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:59.531982Z","time spent":"511.991657ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:29:00.044151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.004899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-24T15:29:00.044135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.086083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:29:00.044195Z","caller":"traceutil/trace.go:171","msg":"trace[1041357095] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1978; }","duration":"509.135841ms","start":"2026-04-24T15:28:59.535049Z","end":"2026-04-24T15:29:00.044184Z","steps":["trace[1041357095] 'agreement among raft nodes before linearized reading' (duration: 508.914166ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:29:00.044232Z","caller":"traceutil/trace.go:171","msg":"trace[893314528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1978; }","duration":"476.294318ms","start":"2026-04-24T15:28:59.567915Z","end":"2026-04-24T15:29:00.044209Z","steps":["trace[893314528] 'agreement among raft nodes before linearized reading' (duration: 476.064742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:29:00.0443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:59.567891Z","time spent":"476.398701ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-24T15:29:00.044239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:28:59.535034Z","time spent":"509.194952ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-24T15:29:00.655977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710538088936422371,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-24T15:29:00.78353Z","caller":"traceutil/trace.go:171","msg":"trace[1151323706] linearizableReadLoop","detail":"{readStateIndex:2071; appliedIndex:2070; }","duration":"628.095474ms","start":"2026-04-24T15:29:00.155402Z","end":"2026-04-24T15:29:00.783497Z","steps":["trace[1151323706] 'read index received' (duration: 627.860138ms)","trace[1151323706] 'applied index is now lower than readState.Index' (duration: 233.926µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:29:00.783692Z","caller":"traceutil/trace.go:171","msg":"trace[1079299690] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"645.481133ms","start":"2026-04-24T15:29:00.138198Z","end":"2026-04-24T15:29:00.783679Z","steps":["trace[1079299690] 'process raft request' (duration: 645.102284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:29:00.783993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.553325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T15:29:00.784185Z","caller":"traceutil/trace.go:171","msg":"trace[2084158659] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1980; }","duration":"628.832071ms","start":"2026-04-24T15:29:00.155329Z","end":"2026-04-24T15:29:00.784161Z","steps":["trace[2084158659] 'agreement among raft nodes before linearized reading' (duration: 628.518284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:29:00.784252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:29:00.155301Z","time spent":"628.937503ms","remote":"127.0.0.1:49104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-24T15:29:00.784039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:29:00.138167Z","time spent":"645.562264ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7167,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:29:00.784881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.738494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:29:00.784927Z","caller":"traceutil/trace.go:171","msg":"trace[92133570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1980; }","duration":"215.834226ms","start":"2026-04-24T15:29:00.569082Z","end":"2026-04-24T15:29:00.784916Z","steps":["trace[92133570] 'agreement among raft nodes before linearized reading' (duration: 215.766214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:29:00.78566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.293316ms","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-04-24T15:29:00.785706Z","caller":"traceutil/trace.go:171","msg":"trace[1699255785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1980; }","duration":"368.397019ms","start":"2026-04-24T15:29:00.417299Z","end":"2026-04-24T15:29:00.785697Z","steps":["trace[1699255785] 'agreement among raft nodes before linearized reading' (duration: 368.209455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:29:00.785737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:29:00.417274Z","time spent":"368.45644ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-24T15:29:00.786063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.706336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-24T15:29:00.786109Z","caller":"traceutil/trace.go:171","msg":"trace[957716071] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1980; }","duration":"530.750567ms","start":"2026-04-24T15:29:00.255346Z","end":"2026-04-24T15:29:00.786096Z","steps":["trace[957716071] 'agreement among raft nodes before linearized reading' (duration: 529.464048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:29:00.786138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:29:00.255268Z","time spent":"530.86397ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-24T15:29:00.950172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.229206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:29:00.950783Z","caller":"traceutil/trace.go:171","msg":"trace[595118024] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"160.324801ms","start":"2026-04-24T15:29:00.790408Z","end":"2026-04-24T15:29:00.950733Z","steps":["trace[595118024] 'process raft request' (duration: 44.42063ms)","trace[595118024] 'compare' (duration: 115.101803ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:29:00.950932Z","caller":"traceutil/trace.go:171","msg":"trace[1150592148] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"156.541205ms","start":"2026-04-24T15:29:00.794222Z","end":"2026-04-24T15:29:00.950763Z","steps":["trace[1150592148] 'process raft request' (duration: 156.060464ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:29:01.495895Z","caller":"traceutil/trace.go:171","msg":"trace[1108021760] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"117.044118ms","start":"2026-04-24T15:29:01.378817Z","end":"2026-04-24T15:29:01.495861Z","steps":["trace[1108021760] 'process raft request' (duration: 116.831243ms)"],"step_count":1}