{"level":"warn","ts":"2026-05-24T07:04:03.15227Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-24T07:04:03.15235Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25: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.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-05-24T07:04:03.152406Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-24T07:04:03.152415Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-05-24T07:04:03.152441Z","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-05-24T07:04:03.153042Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-05-24T07:04:03.153113Z","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.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-05-24T07:04:03.168616Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"15.327424ms"} {"level":"info","ts":"2026-05-24T07:04:03.184662Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-05-24T07:04:03.18474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-05-24T07:04:03.184761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-05-24T07:04:03.184771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-24T07:04:03.184783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-05-24T07:04:03.185075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-05-24T07:04:03.208619Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-24T07:04:03.20987Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-24T07:04:03.211877Z","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-05-24T07:04:03.21463Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-24T07:04:03.214803Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-24T07:04:03.215107Z","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-05-24T07:04:03.215246Z","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-05-24T07:04:03.215264Z","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-05-24T07:04:03.216601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-05-24T07:04:03.216776Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-05-24T07:04:03.219389Z","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-05-24T07:04:03.219524Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-05-24T07:04:03.219678Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-05-24T07:04:03.219777Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-24T07:04:03.219829Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-24T07:04:03.485684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-05-24T07:04:03.485751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-24T07:04:03.485923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-05-24T07:04:03.485971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-05-24T07:04:03.486033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-05-24T07:04:03.486115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-05-24T07:04:03.486147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-05-24T07:04:03.488072Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T07:04:03.488899Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-05-24T07:04:03.490029Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T07:04:03.490148Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T07:04:03.490187Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T07:04:03.490287Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-24T07:04:03.490774Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-24T07:04:03.49378Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-24T07:04:03.49396Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-24T07:04:03.494031Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-24T07:04:03.496123Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-05-24T07:04:07.202114Z","caller":"traceutil/trace.go:171","msg":"trace[304197917] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"109.853614ms","start":"2026-05-24T07:04:07.092236Z","end":"2026-05-24T07:04:07.20209Z","steps":["trace[304197917] 'process raft request' (duration: 109.672302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:13.954834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.448293752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-24T07:04:13.954946Z","caller":"traceutil/trace.go:171","msg":"trace[2042760036] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:375; }","duration":"1.448434503s","start":"2026-05-24T07:04:12.506487Z","end":"2026-05-24T07:04:13.954922Z","steps":["trace[2042760036] 'range keys from in-memory index tree' (duration: 1.44822145s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:13.955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:12.506472Z","time spent":"1.448514253s","remote":"127.0.0.1:50188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-05-24T07:04:13.955402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39936132s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:04:13.955456Z","caller":"traceutil/trace.go:171","msg":"trace[258355340] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"1.393456471s","start":"2026-05-24T07:04:12.561989Z","end":"2026-05-24T07:04:13.955446Z","steps":["trace[258355340] 'read index received' (duration: 36.07µs)","trace[258355340] 'applied index is now lower than readState.Index' (duration: 1.393419341s)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:04:13.955521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.393528371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-24T07:04:13.955537Z","caller":"traceutil/trace.go:171","msg":"trace[112286635] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:376; }","duration":"1.393591042s","start":"2026-05-24T07:04:12.561941Z","end":"2026-05-24T07:04:13.955532Z","steps":["trace[112286635] 'agreement among raft nodes before linearized reading' (duration: 1.393551891s)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:13.955513Z","caller":"traceutil/trace.go:171","msg":"trace[871861083] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"1.408737609s","start":"2026-05-24T07:04:12.546725Z","end":"2026-05-24T07:04:13.955463Z","steps":["trace[871861083] 'compare' (duration: 1.399088388s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:13.955593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051596329s","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-05-24T07:04:13.955609Z","caller":"traceutil/trace.go:171","msg":"trace[1719911740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:376; }","duration":"1.051628519s","start":"2026-05-24T07:04:12.903976Z","end":"2026-05-24T07:04:13.955605Z","steps":["trace[1719911740] 'agreement among raft nodes before linearized reading' (duration: 1.051600609s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:13.955625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:12.903968Z","time spent":"1.051653789s","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-24T07:04:13.955653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055232453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-24T07:04:13.955675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:12.5467Z","time spent":"1.40890398s","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":777,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-24T07:04:13.955736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.26022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-24T07:04:13.955757Z","caller":"traceutil/trace.go:171","msg":"trace[1589445671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:376; }","duration":"685.31614ms","start":"2026-05-24T07:04:13.270436Z","end":"2026-05-24T07:04:13.955752Z","steps":["trace[1589445671] 'agreement among raft nodes before linearized reading' (duration: 685.28184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:13.955901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:13.270419Z","time spent":"685.474121ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-24T07:04:13.955811Z","caller":"traceutil/trace.go:171","msg":"trace[545220180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:376; }","duration":"1.055330573s","start":"2026-05-24T07:04:12.900361Z","end":"2026-05-24T07:04:13.955691Z","steps":["trace[545220180] 'agreement among raft nodes before linearized reading' (duration: 1.055187003s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:13.955557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:12.561919Z","time spent":"1.393634113s","remote":"127.0.0.1:50188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-05-24T07:04:13.956074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055638105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-24T07:04:13.956094Z","caller":"traceutil/trace.go:171","msg":"trace[131207946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:376; }","duration":"1.055681866s","start":"2026-05-24T07:04:12.900406Z","end":"2026-05-24T07:04:13.956088Z","steps":["trace[131207946] 'agreement among raft nodes before linearized reading' (duration: 1.055643305s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:13.956111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:12.900391Z","time spent":"1.055716066s","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-24T07:04:13.956586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:12.900338Z","time spent":"1.056210669s","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-24T07:04:14.175834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.261331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:04:14.176202Z","caller":"traceutil/trace.go:171","msg":"trace[365636955] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"215.651873ms","start":"2026-05-24T07:04:13.960503Z","end":"2026-05-24T07:04:14.176155Z","steps":["trace[365636955] 'process raft request' (duration: 100.773707ms)","trace[365636955] 'compare' (duration: 114.149521ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:04:14.176416Z","caller":"traceutil/trace.go:171","msg":"trace[1537160117] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"213.332408ms","start":"2026-05-24T07:04:13.963059Z","end":"2026-05-24T07:04:14.176392Z","steps":["trace[1537160117] 'process raft request' (duration: 213.235667ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:14.176662Z","caller":"traceutil/trace.go:171","msg":"trace[989769270] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"213.68766ms","start":"2026-05-24T07:04:13.962952Z","end":"2026-05-24T07:04:14.176639Z","steps":["trace[989769270] 'process raft request' (duration: 213.289748ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:14.176724Z","caller":"traceutil/trace.go:171","msg":"trace[511906504] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"216.182906ms","start":"2026-05-24T07:04:13.960502Z","end":"2026-05-24T07:04:14.176684Z","steps":["trace[511906504] 'process raft request' (duration: 215.44103ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:14.176438Z","caller":"traceutil/trace.go:171","msg":"trace[639474702] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"214.469915ms","start":"2026-05-24T07:04:13.961948Z","end":"2026-05-24T07:04:14.176418Z","steps":["trace[639474702] 'process raft request' (duration: 214.236904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:14.890134Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422724397855836,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-24T07:04:15.390574Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422724397855836,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-24T07:04:15.457248Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.274922153s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-24T07:04:15.879638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.06015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:04:15.879811Z","caller":"traceutil/trace.go:171","msg":"trace[1222228454] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"1.697577815s","start":"2026-05-24T07:04:14.182214Z","end":"2026-05-24T07:04:15.879792Z","steps":["trace[1222228454] 'process raft request' (duration: 1.275287594s)","trace[1222228454] 'compare' (duration: 421.570257ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:04:15.879912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:14.182191Z","time spent":"1.697692446s","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-24T07:04:16.389767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000626008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-24T07:04:16.389852Z","caller":"traceutil/trace.go:171","msg":"trace[668534832] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000761568s","start":"2026-05-24T07:04:14.389071Z","end":"2026-05-24T07:04:16.389833Z","steps":["trace[668534832] 'agreement among raft nodes before linearized reading' (duration: 2.000658788s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:16.3899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:14.389054Z","time spent":"2.000833949s","remote":"127.0.0.1:50014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-24T07:04:17.285741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128617229s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:04:17.286169Z","caller":"traceutil/trace.go:171","msg":"trace[913823341] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"3.103073257s","start":"2026-05-24T07:04:14.183063Z","end":"2026-05-24T07:04:17.286136Z","steps":["trace[913823341] 'process raft request' (duration: 3.102870986s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:17.286349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:14.183051Z","time spent":"3.103215478s","remote":"127.0.0.1:50180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-24T07:04:17.286422Z","caller":"traceutil/trace.go:171","msg":"trace[2109823657] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"2.377217366s","start":"2026-05-24T07:04:14.909181Z","end":"2026-05-24T07:04:17.286398Z","steps":["trace[2109823657] 'process raft request' (duration: 2.376917024s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:17.286526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:14.909155Z","time spent":"2.377310686s","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:04:17.286521Z","caller":"traceutil/trace.go:171","msg":"trace[1517992237] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:399; }","duration":"2.897374488s","start":"2026-05-24T07:04:14.389122Z","end":"2026-05-24T07:04:17.286497Z","steps":["trace[1517992237] 'read index received' (duration: 1.068350178s)","trace[1517992237] 'applied index is now lower than readState.Index' (duration: 1.82902324s)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:04:17.286529Z","caller":"traceutil/trace.go:171","msg":"trace[325063682] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"3.099336714s","start":"2026-05-24T07:04:14.187163Z","end":"2026-05-24T07:04:17.2865Z","steps":["trace[325063682] 'process raft request' (duration: 3.098836342s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:17.286715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:14.187157Z","time spent":"3.099450865s","remote":"127.0.0.1:50180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:04:17.286737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264048004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-24T07:04:17.286773Z","caller":"traceutil/trace.go:171","msg":"trace[1800003900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:388; }","duration":"1.264135535s","start":"2026-05-24T07:04:16.022627Z","end":"2026-05-24T07:04:17.286762Z","steps":["trace[1800003900] 'agreement among raft nodes before linearized reading' (duration: 1.264003144s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:17.286805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:16.022609Z","time spent":"1.264188295s","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-24T07:04:17.286865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103622712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-24T07:04:17.286899Z","caller":"traceutil/trace.go:171","msg":"trace[1609839680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:388; }","duration":"1.103677682s","start":"2026-05-24T07:04:16.183209Z","end":"2026-05-24T07:04:17.286887Z","steps":["trace[1609839680] 'agreement among raft nodes before linearized reading' (duration: 1.103613172s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:17.286926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:16.183198Z","time spent":"1.103722513s","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-24T07:04:17.286984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.664821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-24T07:04:17.287004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104270016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-24T07:04:17.287034Z","caller":"traceutil/trace.go:171","msg":"trace[1827703568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:388; }","duration":"1.104322726s","start":"2026-05-24T07:04:16.182703Z","end":"2026-05-24T07:04:17.287026Z","steps":["trace[1827703568] 'agreement among raft nodes before linearized reading' (duration: 1.104268586s)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:17.28704Z","caller":"traceutil/trace.go:171","msg":"trace[1522243061] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:388; }","duration":"877.751631ms","start":"2026-05-24T07:04:16.409273Z","end":"2026-05-24T07:04:17.287024Z","steps":["trace[1522243061] 'agreement among raft nodes before linearized reading' (duration: 877.637651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:17.28706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:16.182691Z","time spent":"1.104362976s","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-24T07:04:17.287085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:16.409252Z","time spent":"877.821681ms","remote":"127.0.0.1:50052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"warn","ts":"2026-05-24T07:04:17.287138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104859841s","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-05-24T07:04:17.287194Z","caller":"traceutil/trace.go:171","msg":"trace[212313252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:388; }","duration":"1.104942032s","start":"2026-05-24T07:04:16.182236Z","end":"2026-05-24T07:04:17.287178Z","steps":["trace[212313252] 'agreement among raft nodes before linearized reading' (duration: 1.10484139s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:17.287233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:16.182221Z","time spent":"1.105001172s","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-24T07:04:17.287188Z","caller":"traceutil/trace.go:171","msg":"trace[422057865] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"3.103585421s","start":"2026-05-24T07:04:14.182492Z","end":"2026-05-24T07:04:17.286077Z","steps":["trace[422057865] 'process raft request' (duration: 1.974568779s)","trace[422057865] 'compare' (duration: 1.128501388s)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:04:17.287436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:14.182472Z","time spent":"3.104907779s","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-24T07:04:17.286358Z","caller":"traceutil/trace.go:171","msg":"trace[1274190681] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"892.902716ms","start":"2026-05-24T07:04:16.393436Z","end":"2026-05-24T07:04:17.286338Z","steps":["trace[1274190681] 'process raft request' (duration: 892.751146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:17.287648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:16.393409Z","time spent":"894.185045ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-24T07:04:21.54933Z","caller":"traceutil/trace.go:171","msg":"trace[1331404534] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"167.227659ms","start":"2026-05-24T07:04:21.38208Z","end":"2026-05-24T07:04:21.549307Z","steps":["trace[1331404534] 'process raft request' (duration: 167.152909ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:21.549367Z","caller":"traceutil/trace.go:171","msg":"trace[263005730] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"169.800415ms","start":"2026-05-24T07:04:21.379533Z","end":"2026-05-24T07:04:21.549334Z","steps":["trace[263005730] 'process raft request' (duration: 101.944871ms)","trace[263005730] 'compare' (duration: 67.576243ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:04:21.549497Z","caller":"traceutil/trace.go:171","msg":"trace[481047272] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"167.37346ms","start":"2026-05-24T07:04:21.382113Z","end":"2026-05-24T07:04:21.549487Z","steps":["trace[481047272] 'process raft request' (duration: 167.165869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:21.882833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.262973ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-24T07:04:42.910042Z","caller":"traceutil/trace.go:171","msg":"trace[1429549209] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"170.01571ms","start":"2026-05-24T07:04:42.740014Z","end":"2026-05-24T07:04:42.910029Z","steps":["trace[1429549209] 'process raft request' (duration: 169.926116ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.013076Z","caller":"traceutil/trace.go:171","msg":"trace[501871238] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"259.25931ms","start":"2026-05-24T07:04:42.753787Z","end":"2026-05-24T07:04:43.013046Z","steps":["trace[501871238] 'process raft request' (duration: 259.089393ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.013228Z","caller":"traceutil/trace.go:171","msg":"trace[923240847] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"272.57645ms","start":"2026-05-24T07:04:42.740611Z","end":"2026-05-24T07:04:43.013187Z","steps":["trace[923240847] 'process raft request' (duration: 263.888616ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.013356Z","caller":"traceutil/trace.go:171","msg":"trace[1659828278] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"244.026822ms","start":"2026-05-24T07:04:42.769234Z","end":"2026-05-24T07:04:43.01326Z","steps":["trace[1659828278] 'process raft request' (duration: 243.765971ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.013501Z","caller":"traceutil/trace.go:171","msg":"trace[881162282] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"240.719068ms","start":"2026-05-24T07:04:42.772714Z","end":"2026-05-24T07:04:43.013433Z","steps":["trace[881162282] 'process raft request' (duration: 240.362644ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.013599Z","caller":"traceutil/trace.go:171","msg":"trace[1963681257] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"229.857655ms","start":"2026-05-24T07:04:42.783733Z","end":"2026-05-24T07:04:43.013591Z","steps":["trace[1963681257] 'process raft request' (duration: 229.462498ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.013578Z","caller":"traceutil/trace.go:171","msg":"trace[1368662446] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"255.721658ms","start":"2026-05-24T07:04:42.75771Z","end":"2026-05-24T07:04:43.013432Z","steps":["trace[1368662446] 'process raft request' (duration: 255.216008ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.013733Z","caller":"traceutil/trace.go:171","msg":"trace[937585754] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"209.243202ms","start":"2026-05-24T07:04:42.804483Z","end":"2026-05-24T07:04:43.013726Z","steps":["trace[937585754] 'process raft request' (duration: 208.767363ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.013742Z","caller":"traceutil/trace.go:171","msg":"trace[285061118] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"244.350664ms","start":"2026-05-24T07:04:42.769354Z","end":"2026-05-24T07:04:43.013704Z","steps":["trace[285061118] 'process raft request' (duration: 243.685667ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.01351Z","caller":"traceutil/trace.go:171","msg":"trace[1262859301] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"115.517259ms","start":"2026-05-24T07:04:42.897931Z","end":"2026-05-24T07:04:43.013448Z","steps":["trace[1262859301] 'process raft request' (duration: 115.455087ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.013567Z","caller":"traceutil/trace.go:171","msg":"trace[622551882] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"232.110115ms","start":"2026-05-24T07:04:42.781449Z","end":"2026-05-24T07:04:43.013559Z","steps":["trace[622551882] 'process raft request' (duration: 231.696158ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.013654Z","caller":"traceutil/trace.go:171","msg":"trace[1403021657] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"248.650885ms","start":"2026-05-24T07:04:42.76496Z","end":"2026-05-24T07:04:43.013611Z","steps":["trace[1403021657] 'process raft request' (duration: 248.004059ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.035639Z","caller":"traceutil/trace.go:171","msg":"trace[1982455113] transaction","detail":"{read_only:false; number_of_response:1; response_revision:545; }","duration":"117.930056ms","start":"2026-05-24T07:04:42.917694Z","end":"2026-05-24T07:04:43.035624Z","steps":["trace[1982455113] 'process raft request' (duration: 117.901054ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.035827Z","caller":"traceutil/trace.go:171","msg":"trace[939415398] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"118.170506ms","start":"2026-05-24T07:04:42.917646Z","end":"2026-05-24T07:04:43.035817Z","steps":["trace[939415398] 'process raft request' (duration: 117.905514ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.035838Z","caller":"traceutil/trace.go:171","msg":"trace[1377438319] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"119.489208ms","start":"2026-05-24T07:04:42.916333Z","end":"2026-05-24T07:04:43.035822Z","steps":["trace[1377438319] 'process raft request' (duration: 119.138473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:43.239464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.513079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:04:43.239711Z","caller":"traceutil/trace.go:171","msg":"trace[494881797] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"202.019041ms","start":"2026-05-24T07:04:43.037606Z","end":"2026-05-24T07:04:43.239625Z","steps":["trace[494881797] 'process raft request' (duration: 87.285654ms)","trace[494881797] 'compare' (duration: 114.397604ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:04:43.240014Z","caller":"traceutil/trace.go:171","msg":"trace[1458974776] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"202.038101ms","start":"2026-05-24T07:04:43.037958Z","end":"2026-05-24T07:04:43.239996Z","steps":["trace[1458974776] 'process raft request' (duration: 201.595924ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.240486Z","caller":"traceutil/trace.go:171","msg":"trace[41528009] transaction","detail":"{read_only:false; number_of_response:0; response_revision:551; }","duration":"202.219929ms","start":"2026-05-24T07:04:43.038218Z","end":"2026-05-24T07:04:43.240437Z","steps":["trace[41528009] 'process raft request' (duration: 201.74606ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.240632Z","caller":"traceutil/trace.go:171","msg":"trace[1356644962] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"194.302117ms","start":"2026-05-24T07:04:43.046321Z","end":"2026-05-24T07:04:43.240623Z","steps":["trace[1356644962] 'process raft request' (duration: 193.993235ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.240867Z","caller":"traceutil/trace.go:171","msg":"trace[1178135247] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"183.189168ms","start":"2026-05-24T07:04:43.057661Z","end":"2026-05-24T07:04:43.24085Z","steps":["trace[1178135247] 'process raft request' (duration: 182.892297ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.240885Z","caller":"traceutil/trace.go:171","msg":"trace[1268159988] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"190.090321ms","start":"2026-05-24T07:04:43.05078Z","end":"2026-05-24T07:04:43.24087Z","steps":["trace[1268159988] 'process raft request' (duration: 189.664885ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.240992Z","caller":"traceutil/trace.go:171","msg":"trace[1414426410] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"158.780275ms","start":"2026-05-24T07:04:43.082202Z","end":"2026-05-24T07:04:43.240982Z","steps":["trace[1414426410] 'process raft request' (duration: 158.537495ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.240945Z","caller":"traceutil/trace.go:171","msg":"trace[1597046914] transaction","detail":"{read_only:false; number_of_response:0; response_revision:557; }","duration":"155.696723ms","start":"2026-05-24T07:04:43.085225Z","end":"2026-05-24T07:04:43.240921Z","steps":["trace[1597046914] 'process raft request' (duration: 155.649201ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.241123Z","caller":"traceutil/trace.go:171","msg":"trace[664616245] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"171.445094ms","start":"2026-05-24T07:04:43.069661Z","end":"2026-05-24T07:04:43.241106Z","steps":["trace[664616245] 'process raft request' (duration: 171.020748ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.241215Z","caller":"traceutil/trace.go:171","msg":"trace[587225642] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"147.643495ms","start":"2026-05-24T07:04:43.093564Z","end":"2026-05-24T07:04:43.241208Z","steps":["trace[587225642] 'process raft request' (duration: 147.479909ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.241287Z","caller":"traceutil/trace.go:171","msg":"trace[1796228832] transaction","detail":"{read_only:false; number_of_response:0; response_revision:557; }","duration":"154.722554ms","start":"2026-05-24T07:04:43.08656Z","end":"2026-05-24T07:04:43.241283Z","steps":["trace[1796228832] 'process raft request' (duration: 154.336109ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.241328Z","caller":"traceutil/trace.go:171","msg":"trace[1637586670] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"156.976914ms","start":"2026-05-24T07:04:43.084334Z","end":"2026-05-24T07:04:43.241311Z","steps":["trace[1637586670] 'process raft request' (duration: 156.508836ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.607083Z","caller":"traceutil/trace.go:171","msg":"trace[1255385779] transaction","detail":"{read_only:false; number_of_response:1; response_revision:558; }","duration":"350.960508ms","start":"2026-05-24T07:04:43.256093Z","end":"2026-05-24T07:04:43.607054Z","steps":["trace[1255385779] 'process raft request' (duration: 322.727663ms)","trace[1255385779] 'compare' (duration: 28.155212ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:04:43.607234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:43.256077Z","time spent":"351.072433ms","remote":"127.0.0.1:50068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7375,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:04:43.608152Z","caller":"traceutil/trace.go:171","msg":"trace[81984222] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"346.849565ms","start":"2026-05-24T07:04:43.26129Z","end":"2026-05-24T07:04:43.608139Z","steps":["trace[81984222] 'process raft request' (duration: 346.164219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:43.608232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:43.261273Z","time spent":"346.922387ms","remote":"127.0.0.1:50068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:04:43.608272Z","caller":"traceutil/trace.go:171","msg":"trace[584776160] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"346.914707ms","start":"2026-05-24T07:04:43.261322Z","end":"2026-05-24T07:04:43.608236Z","steps":["trace[584776160] 'process raft request' (duration: 346.671828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:43.608464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:43.261305Z","time spent":"347.063444ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-24T07:04:43.608697Z","caller":"traceutil/trace.go:171","msg":"trace[877596302] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"346.932279ms","start":"2026-05-24T07:04:43.261729Z","end":"2026-05-24T07:04:43.608661Z","steps":["trace[877596302] 'process raft request' (duration: 346.388077ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.608874Z","caller":"traceutil/trace.go:171","msg":"trace[1272318585] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"345.337686ms","start":"2026-05-24T07:04:43.263522Z","end":"2026-05-24T07:04:43.60886Z","steps":["trace[1272318585] 'process raft request' (duration: 344.714741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:43.608881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:43.261722Z","time spent":"347.077275ms","remote":"127.0.0.1:50068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:04:43.60896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:43.263513Z","time spent":"345.395039ms","remote":"127.0.0.1:50068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:04:43.609026Z","caller":"traceutil/trace.go:171","msg":"trace[255114639] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"316.875503ms","start":"2026-05-24T07:04:43.292139Z","end":"2026-05-24T07:04:43.609015Z","steps":["trace[255114639] 'process raft request' (duration: 316.481368ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:43.609047Z","caller":"traceutil/trace.go:171","msg":"trace[1991646123] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"291.180039ms","start":"2026-05-24T07:04:43.317857Z","end":"2026-05-24T07:04:43.609037Z","steps":["trace[1991646123] 'process raft request' (duration: 290.94009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:43.60908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:43.292109Z","time spent":"316.940786ms","remote":"127.0.0.1:50068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:04:43.608914Z","caller":"traceutil/trace.go:171","msg":"trace[25589725] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:587; }","duration":"137.86207ms","start":"2026-05-24T07:04:43.471028Z","end":"2026-05-24T07:04:43.60889Z","steps":["trace[25589725] 'read index received' (duration: 107.819514ms)","trace[25589725] 'applied index is now lower than readState.Index' (duration: 30.041566ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:04:43.609755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.6173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-24T07:04:43.609808Z","caller":"traceutil/trace.go:171","msg":"trace[222203995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:564; }","duration":"138.88396ms","start":"2026-05-24T07:04:43.47091Z","end":"2026-05-24T07:04:43.609794Z","steps":["trace[222203995] 'agreement among raft nodes before linearized reading' (duration: 138.462823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:44.175223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.034818ms","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-05-24T07:04:44.175365Z","caller":"traceutil/trace.go:171","msg":"trace[1056068400] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:579; }","duration":"209.196667ms","start":"2026-05-24T07:04:43.966146Z","end":"2026-05-24T07:04:44.175343Z","steps":["trace[1056068400] 'range keys from in-memory index tree' (duration: 209.012148ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:44.175423Z","caller":"traceutil/trace.go:171","msg":"trace[2067257426] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"422.138198ms","start":"2026-05-24T07:04:43.753251Z","end":"2026-05-24T07:04:44.175389Z","steps":["trace[2067257426] 'process raft request' (duration: 407.676803ms)","trace[2067257426] 'compare' (duration: 14.248106ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:04:44.175602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:43.753231Z","time spent":"422.258743ms","remote":"127.0.0.1:50068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:04:44.178162Z","caller":"traceutil/trace.go:171","msg":"trace[1526707631] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"403.329195ms","start":"2026-05-24T07:04:43.774816Z","end":"2026-05-24T07:04:44.178145Z","steps":["trace[1526707631] 'process raft request' (duration: 403.268441ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:44.178187Z","caller":"traceutil/trace.go:171","msg":"trace[608057526] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"406.118455ms","start":"2026-05-24T07:04:43.772043Z","end":"2026-05-24T07:04:44.178161Z","steps":["trace[608057526] 'process raft request' (duration: 405.949968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:44.178279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:43.774795Z","time spent":"403.416648ms","remote":"127.0.0.1:50068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:04:44.178281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:43.772022Z","time spent":"406.211779ms","remote":"127.0.0.1:50068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-24T07:04:45.149706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422724397856187,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-24T07:04:45.432577Z","caller":"traceutil/trace.go:171","msg":"trace[299318490] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.006830923s","start":"2026-05-24T07:04:44.42572Z","end":"2026-05-24T07:04:45.432551Z","steps":["trace[299318490] 'process raft request' (duration: 982.785777ms)","trace[299318490] 'compare' (duration: 23.720544ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:04:45.432703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:44.425699Z","time spent":"1.006939108s","remote":"127.0.0.1:50068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:04:45.432792Z","caller":"traceutil/trace.go:171","msg":"trace[593320349] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"990.189995ms","start":"2026-05-24T07:04:44.442578Z","end":"2026-05-24T07:04:45.432768Z","steps":["trace[593320349] 'process raft request' (duration: 989.914493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:45.432919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:44.442561Z","time spent":"990.288319ms","remote":"127.0.0.1:50068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:04:45.433169Z","caller":"traceutil/trace.go:171","msg":"trace[822085727] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:623; }","duration":"783.950263ms","start":"2026-05-24T07:04:44.649192Z","end":"2026-05-24T07:04:45.433143Z","steps":["trace[822085727] 'read index received' (duration: 759.298162ms)","trace[822085727] 'applied index is now lower than readState.Index' (duration: 24.651201ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:04:45.433239Z","caller":"traceutil/trace.go:171","msg":"trace[545428912] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"902.727324ms","start":"2026-05-24T07:04:44.530489Z","end":"2026-05-24T07:04:45.433217Z","steps":["trace[545428912] 'process raft request' (duration: 902.393052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:45.433294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.092439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:04:45.433323Z","caller":"traceutil/trace.go:171","msg":"trace[48099013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"784.185421ms","start":"2026-05-24T07:04:44.649131Z","end":"2026-05-24T07:04:45.433316Z","steps":["trace[48099013] 'agreement among raft nodes before linearized reading' (duration: 784.103558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:45.43334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:44.53047Z","time spent":"902.820087ms","remote":"127.0.0.1:50068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-24T07:04:45.433347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:44.649113Z","time spent":"784.229503ms","remote":"127.0.0.1:50014","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-05-24T07:04:49.95666Z","caller":"traceutil/trace.go:171","msg":"trace[87428154] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"139.040353ms","start":"2026-05-24T07:04:49.817589Z","end":"2026-05-24T07:04:49.95663Z","steps":["trace[87428154] 'process raft request' (duration: 42.056836ms)","trace[87428154] 'compare' (duration: 96.8265ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:04:51.0532Z","caller":"traceutil/trace.go:171","msg":"trace[1226097412] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"201.39515ms","start":"2026-05-24T07:04:50.851778Z","end":"2026-05-24T07:04:51.053173Z","steps":["trace[1226097412] 'process raft request' (duration: 200.730626ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:52.107175Z","caller":"traceutil/trace.go:171","msg":"trace[1029098259] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"397.975305ms","start":"2026-05-24T07:04:51.709155Z","end":"2026-05-24T07:04:52.10713Z","steps":["trace[1029098259] 'process raft request' (duration: 297.954264ms)","trace[1029098259] 'compare' (duration: 99.842415ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:04:52.107366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:51.709133Z","time spent":"398.150673ms","remote":"127.0.0.1:50216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-24T07:04:52.107473Z","caller":"traceutil/trace.go:171","msg":"trace[396884405] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"370.857931ms","start":"2026-05-24T07:04:51.736584Z","end":"2026-05-24T07:04:52.107442Z","steps":["trace[396884405] 'read index received' (duration: 270.536638ms)","trace[396884405] 'applied index is now lower than readState.Index' (duration: 100.289002ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:04:52.122915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.31324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-24T07:04:52.122984Z","caller":"traceutil/trace.go:171","msg":"trace[487700242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"386.421414ms","start":"2026-05-24T07:04:51.736546Z","end":"2026-05-24T07:04:52.122967Z","steps":["trace[487700242] 'agreement among raft nodes before linearized reading' (duration: 371.017478ms)","trace[487700242] 'range keys from in-memory index tree' (duration: 15.152788ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:04:52.123002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.506914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-24T07:04:52.123052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.443296ms","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-05-24T07:04:52.123096Z","caller":"traceutil/trace.go:171","msg":"trace[1346369459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:644; }","duration":"318.638809ms","start":"2026-05-24T07:04:51.804443Z","end":"2026-05-24T07:04:52.123082Z","steps":["trace[1346369459] 'agreement among raft nodes before linearized reading' (duration: 318.38037ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:52.1231Z","caller":"traceutil/trace.go:171","msg":"trace[1940468826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:644; }","duration":"361.526599ms","start":"2026-05-24T07:04:51.761561Z","end":"2026-05-24T07:04:52.123088Z","steps":["trace[1940468826] 'agreement among raft nodes before linearized reading' (duration: 361.341363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:52.123132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:51.804433Z","time spent":"318.693511ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-24T07:04:52.123135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:51.76155Z","time spent":"361.578681ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-24T07:04:52.123041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:51.736523Z","time spent":"386.489407ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-24T07:04:52.122967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.212689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-24T07:04:52.12341Z","caller":"traceutil/trace.go:171","msg":"trace[274932276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:644; }","duration":"319.702347ms","start":"2026-05-24T07:04:51.803678Z","end":"2026-05-24T07:04:52.123381Z","steps":["trace[274932276] 'agreement among raft nodes before linearized reading' (duration: 319.096756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:52.123454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:51.803662Z","time spent":"319.781259ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-24T07:04:52.437147Z","caller":"traceutil/trace.go:171","msg":"trace[1106859115] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"309.183363ms","start":"2026-05-24T07:04:52.127944Z","end":"2026-05-24T07:04:52.437127Z","steps":["trace[1106859115] 'process raft request' (duration: 309.10786ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:52.437172Z","caller":"traceutil/trace.go:171","msg":"trace[998726706] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"309.281165ms","start":"2026-05-24T07:04:52.127837Z","end":"2026-05-24T07:04:52.437118Z","steps":["trace[998726706] 'process raft request' (duration: 309.077188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:52.437307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:52.127922Z","time spent":"309.283065ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:04:52.437355Z","caller":"traceutil/trace.go:171","msg":"trace[798842313] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:676; }","duration":"303.414756ms","start":"2026-05-24T07:04:52.133909Z","end":"2026-05-24T07:04:52.437324Z","steps":["trace[798842313] 'read index received' (duration: 41.93268ms)","trace[798842313] 'applied index is now lower than readState.Index' (duration: 261.480126ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:04:52.437499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.545561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:04:52.437523Z","caller":"traceutil/trace.go:171","msg":"trace[393036412] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"309.570486ms","start":"2026-05-24T07:04:52.127936Z","end":"2026-05-24T07:04:52.437506Z","steps":["trace[393036412] 'process raft request' (duration: 309.144141ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:52.437577Z","caller":"traceutil/trace.go:171","msg":"trace[662183259] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:648; }","duration":"303.674356ms","start":"2026-05-24T07:04:52.133882Z","end":"2026-05-24T07:04:52.437557Z","steps":["trace[662183259] 'agreement among raft nodes before linearized reading' (duration: 303.52977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:52.437677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:52.127923Z","time spent":"309.643648ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:04:52.437674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:52.133872Z","time spent":"303.743688ms","remote":"127.0.0.1:50132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-24T07:04:52.437752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:52.127815Z","time spent":"309.526614ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:04:52.466263Z","caller":"traceutil/trace.go:171","msg":"trace[1486373417] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"264.234636ms","start":"2026-05-24T07:04:52.202009Z","end":"2026-05-24T07:04:52.466243Z","steps":["trace[1486373417] 'process raft request' (duration: 264.095011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:52.466275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.898528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-24T07:04:52.466438Z","caller":"traceutil/trace.go:171","msg":"trace[1031332736] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:649; }","duration":"176.107645ms","start":"2026-05-24T07:04:52.290313Z","end":"2026-05-24T07:04:52.466421Z","steps":["trace[1031332736] 'agreement among raft nodes before linearized reading' (duration: 175.888017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:53.018371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.137472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:04:53.018601Z","caller":"traceutil/trace.go:171","msg":"trace[362261785] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"504.479403ms","start":"2026-05-24T07:04:52.514104Z","end":"2026-05-24T07:04:53.018583Z","steps":["trace[362261785] 'process raft request' (duration: 504.38398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:53.01866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:52.514086Z","time spent":"504.548875ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-24T07:04:53.018903Z","caller":"traceutil/trace.go:171","msg":"trace[1117099174] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"547.649888ms","start":"2026-05-24T07:04:52.471229Z","end":"2026-05-24T07:04:53.018879Z","steps":["trace[1117099174] 'read index received' (duration: 69.831901ms)","trace[1117099174] 'applied index is now lower than readState.Index' (duration: 477.815997ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:04:53.018936Z","caller":"traceutil/trace.go:171","msg":"trace[1425623650] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"578.899157ms","start":"2026-05-24T07:04:52.439963Z","end":"2026-05-24T07:04:53.018862Z","steps":["trace[1425623650] 'process raft request' (duration: 101.128862ms)","trace[1425623650] 'compare' (duration: 476.923865ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:04:53.019084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.453307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-24T07:04:53.019104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:52.439941Z","time spent":"579.070242ms","remote":"127.0.0.1:50180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-24T07:04:53.019128Z","caller":"traceutil/trace.go:171","msg":"trace[126239640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"369.568911ms","start":"2026-05-24T07:04:52.649549Z","end":"2026-05-24T07:04:53.019118Z","steps":["trace[126239640] 'agreement among raft nodes before linearized reading' (duration: 369.470897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:53.019172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:52.649528Z","time spent":"369.636373ms","remote":"127.0.0.1:50014","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-05-24T07:04:53.01905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.813663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-24T07:04:53.019271Z","caller":"traceutil/trace.go:171","msg":"trace[641908329] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:651; }","duration":"548.072172ms","start":"2026-05-24T07:04:52.471185Z","end":"2026-05-24T07:04:53.019257Z","steps":["trace[641908329] 'agreement among raft nodes before linearized reading' (duration: 547.760581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:53.019319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:52.471165Z","time spent":"548.136554ms","remote":"127.0.0.1:50098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6554,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"warn","ts":"2026-05-24T07:04:53.801693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.82031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:04:53.801803Z","caller":"traceutil/trace.go:171","msg":"trace[142748234] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"778.605959ms","start":"2026-05-24T07:04:53.023178Z","end":"2026-05-24T07:04:53.801784Z","steps":["trace[142748234] 'process raft request' (duration: 380.598764ms)","trace[142748234] 'compare' (duration: 397.666524ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:04:53.801873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:53.023162Z","time spent":"778.679241ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-24T07:04:54.136777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.487045ms","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-05-24T07:04:54.136925Z","caller":"traceutil/trace.go:171","msg":"trace[453616600] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:652; }","duration":"170.652551ms","start":"2026-05-24T07:04:53.966247Z","end":"2026-05-24T07:04:54.1369Z","steps":["trace[453616600] 'range keys from in-memory index tree' (duration: 170.465045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:54.137237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.012031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:04:54.137554Z","caller":"traceutil/trace.go:171","msg":"trace[1456243883] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:684; }","duration":"487.997302ms","start":"2026-05-24T07:04:53.64954Z","end":"2026-05-24T07:04:54.137537Z","steps":["trace[1456243883] 'read index received' (duration: 332.557016ms)","trace[1456243883] 'applied index is now lower than readState.Index' (duration: 155.437606ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:04:54.137673Z","caller":"traceutil/trace.go:171","msg":"trace[1674059404] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.10421499s","start":"2026-05-24T07:04:53.033437Z","end":"2026-05-24T07:04:54.137652Z","steps":["trace[1674059404] 'process raft request' (duration: 1.104052315s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:54.137693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.152798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:04:54.137763Z","caller":"traceutil/trace.go:171","msg":"trace[1755356292] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.11419399s","start":"2026-05-24T07:04:53.023528Z","end":"2026-05-24T07:04:54.137722Z","steps":["trace[1755356292] 'process raft request' (duration: 958.602468ms)","trace[1755356292] 'compare' (duration: 154.740392ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:04:54.137822Z","caller":"traceutil/trace.go:171","msg":"trace[1830663075] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.111955561s","start":"2026-05-24T07:04:53.025803Z","end":"2026-05-24T07:04:54.137759Z","steps":["trace[1830663075] 'process raft request' (duration: 1.111614908s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:54.137871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:53.033414Z","time spent":"1.104317304s","remote":"127.0.0.1:50180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:04:54.13783Z","caller":"traceutil/trace.go:171","msg":"trace[119140176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"488.281493ms","start":"2026-05-24T07:04:53.6495Z","end":"2026-05-24T07:04:54.137781Z","steps":["trace[119140176] 'agreement among raft nodes before linearized reading' (duration: 488.128448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:54.137923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:53.023514Z","time spent":"1.114344015s","remote":"127.0.0.1:50180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:04:54.138012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:53.649484Z","time spent":"488.487769ms","remote":"127.0.0.1:50014","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-05-24T07:04:54.138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:53.025784Z","time spent":"1.112122907s","remote":"127.0.0.1:50216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:04:54.138095Z","caller":"traceutil/trace.go:171","msg":"trace[1755013554] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.113802807s","start":"2026-05-24T07:04:53.024277Z","end":"2026-05-24T07:04:54.13808Z","steps":["trace[1755013554] 'process raft request' (duration: 1.113081041s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:54.138184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:53.024265Z","time spent":"1.113865959s","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-24T07:04:54.75764Z","caller":"traceutil/trace.go:171","msg":"trace[1263357707] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:690; }","duration":"107.549593ms","start":"2026-05-24T07:04:54.650069Z","end":"2026-05-24T07:04:54.757619Z","steps":["trace[1263357707] 'read index received' (duration: 107.237423ms)","trace[1263357707] 'applied index is now lower than readState.Index' (duration: 311.01µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:04:54.757717Z","caller":"traceutil/trace.go:171","msg":"trace[1432612207] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"312.477268ms","start":"2026-05-24T07:04:54.445209Z","end":"2026-05-24T07:04:54.757686Z","steps":["trace[1432612207] 'process raft request' (duration: 312.192108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:54.757922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.788622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:04:54.757985Z","caller":"traceutil/trace.go:171","msg":"trace[956702809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"107.952047ms","start":"2026-05-24T07:04:54.650019Z","end":"2026-05-24T07:04:54.757971Z","steps":["trace[956702809] 'agreement among raft nodes before linearized reading' (duration: 107.777521ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:54.758006Z","caller":"traceutil/trace.go:171","msg":"trace[1717288972] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"312.304592ms","start":"2026-05-24T07:04:54.445662Z","end":"2026-05-24T07:04:54.757966Z","steps":["trace[1717288972] 'process raft request' (duration: 311.912548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:54.758156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:54.445648Z","time spent":"312.430086ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:04:54.758139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:54.445191Z","time spent":"312.686846ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:04:57.019623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.966741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:04:57.019721Z","caller":"traceutil/trace.go:171","msg":"trace[1848030599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"370.102805ms","start":"2026-05-24T07:04:56.649596Z","end":"2026-05-24T07:04:57.019699Z","steps":["trace[1848030599] 'range keys from in-memory index tree' (duration: 369.864387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:57.019924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.516014ms","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-05-24T07:04:57.019982Z","caller":"traceutil/trace.go:171","msg":"trace[1794757021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"257.579947ms","start":"2026-05-24T07:04:56.762384Z","end":"2026-05-24T07:04:57.019964Z","steps":["trace[1794757021] 'range keys from in-memory index tree' (duration: 257.413952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:57.019623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.489427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-24T07:04:57.020367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.506237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-24T07:04:57.020437Z","caller":"traceutil/trace.go:171","msg":"trace[607268369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"528.339316ms","start":"2026-05-24T07:04:56.492079Z","end":"2026-05-24T07:04:57.020418Z","steps":["trace[607268369] 'range keys from in-memory index tree' (duration: 527.28558ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:04:57.020458Z","caller":"traceutil/trace.go:171","msg":"trace[340708835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"258.664324ms","start":"2026-05-24T07:04:56.76177Z","end":"2026-05-24T07:04:57.020435Z","steps":["trace[340708835] 'range keys from in-memory index tree' (duration: 258.344042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:57.01971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.88303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-24T07:04:57.020514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:56.492056Z","time spent":"528.429289ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-24T07:04:57.020566Z","caller":"traceutil/trace.go:171","msg":"trace[1554304295] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:661; }","duration":"609.79543ms","start":"2026-05-24T07:04:56.410757Z","end":"2026-05-24T07:04:57.020552Z","steps":["trace[1554304295] 'range keys from in-memory index tree' (duration: 608.693924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:57.020568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:56.649576Z","time spent":"370.969094ms","remote":"127.0.0.1:50014","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-05-24T07:04:57.020618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:56.410737Z","time spent":"609.866743ms","remote":"127.0.0.1:50052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"warn","ts":"2026-05-24T07:04:58.151032Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422724397856372,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-24T07:04:58.652009Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422724397856372,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-24T07:04:58.970185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.57011567s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:04:58.970445Z","caller":"traceutil/trace.go:171","msg":"trace[396469938] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.94439281s","start":"2026-05-24T07:04:57.026036Z","end":"2026-05-24T07:04:58.970428Z","steps":["trace[396469938] 'process raft request' (duration: 1.944334588s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:58.970508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:57.025997Z","time spent":"1.944481312s","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:04:58.970584Z","caller":"traceutil/trace.go:171","msg":"trace[59262839] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.946523072s","start":"2026-05-24T07:04:57.024018Z","end":"2026-05-24T07:04:58.970541Z","steps":["trace[59262839] 'process raft request' (duration: 375.957307ms)","trace[59262839] 'compare' (duration: 1.56980121s)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:04:58.970695Z","caller":"traceutil/trace.go:171","msg":"trace[673697243] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"1.945267979s","start":"2026-05-24T07:04:57.025417Z","end":"2026-05-24T07:04:58.970685Z","steps":["trace[673697243] 'process raft request' (duration: 1.944863196s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:58.970751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:57.025405Z","time spent":"1.9453142s","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:04:58.970747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:57.023995Z","time spent":"1.946656516s","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:04:59.010535Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.610570531s","expected-duration":"1s"} {"level":"info","ts":"2026-05-24T07:04:59.010901Z","caller":"traceutil/trace.go:171","msg":"trace[149932042] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.872386307s","start":"2026-05-24T07:04:57.138497Z","end":"2026-05-24T07:04:59.010884Z","steps":["trace[149932042] 'process raft request' (duration: 1.872274993s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:59.011009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:57.138476Z","time spent":"1.872467639s","remote":"127.0.0.1:50052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:04:59.208764Z","caller":"traceutil/trace.go:171","msg":"trace[593237552] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:697; }","duration":"1.557785482s","start":"2026-05-24T07:04:57.65096Z","end":"2026-05-24T07:04:59.208746Z","steps":["trace[593237552] 'read index received' (duration: 1.359738854s)","trace[593237552] 'applied index is now lower than readState.Index' (duration: 198.045468ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:04:59.208889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.557929257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:04:59.208896Z","caller":"traceutil/trace.go:171","msg":"trace[1300360343] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"1.060719416s","start":"2026-05-24T07:04:58.148149Z","end":"2026-05-24T07:04:59.208868Z","steps":["trace[1300360343] 'process raft request' (duration: 1.040389979s)","trace[1300360343] 'compare' (duration: 19.952754ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:04:59.208986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.576219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-24T07:04:59.209011Z","caller":"traceutil/trace.go:171","msg":"trace[2110700132] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:666; }","duration":"195.665812ms","start":"2026-05-24T07:04:59.013338Z","end":"2026-05-24T07:04:59.209004Z","steps":["trace[2110700132] 'agreement among raft nodes before linearized reading' (duration: 195.61311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:59.20905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:58.148119Z","time spent":"1.06083701s","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:04:59.208923Z","caller":"traceutil/trace.go:171","msg":"trace[1953136178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"1.55800393s","start":"2026-05-24T07:04:57.65091Z","end":"2026-05-24T07:04:59.208914Z","steps":["trace[1953136178] 'agreement among raft nodes before linearized reading' (duration: 1.557943948s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:59.209121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.356877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-24T07:04:59.209142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:57.650891Z","time spent":"1.558235128s","remote":"127.0.0.1:50014","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-05-24T07:04:59.209263Z","caller":"traceutil/trace.go:171","msg":"trace[488187514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:666; }","duration":"901.493282ms","start":"2026-05-24T07:04:58.307683Z","end":"2026-05-24T07:04:59.209176Z","steps":["trace[488187514] 'agreement among raft nodes before linearized reading' (duration: 901.255115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:04:59.209336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:04:58.307659Z","time spent":"901.642977ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-24T07:05:04.393562Z","caller":"traceutil/trace.go:171","msg":"trace[2011254683] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"190.56319ms","start":"2026-05-24T07:05:04.20295Z","end":"2026-05-24T07:05:04.393513Z","steps":["trace[2011254683] 'process raft request' (duration: 190.392745ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:10.567541Z","caller":"traceutil/trace.go:171","msg":"trace[525089540] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"226.964342ms","start":"2026-05-24T07:05:10.340552Z","end":"2026-05-24T07:05:10.567516Z","steps":["trace[525089540] 'process raft request' (duration: 226.826268ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:12.467063Z","caller":"traceutil/trace.go:171","msg":"trace[1131982516] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"163.512435ms","start":"2026-05-24T07:05:12.30353Z","end":"2026-05-24T07:05:12.467043Z","steps":["trace[1131982516] 'read index received' (duration: 158.144762ms)","trace[1131982516] 'applied index is now lower than readState.Index' (duration: 5.366213ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:05:12.467225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.669659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-24T07:05:12.467257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.69442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:05:12.467301Z","caller":"traceutil/trace.go:171","msg":"trace[375525257] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:805; }","duration":"163.769612ms","start":"2026-05-24T07:05:12.303517Z","end":"2026-05-24T07:05:12.467286Z","steps":["trace[375525257] 'agreement among raft nodes before linearized reading' (duration: 163.627848ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:12.467342Z","caller":"traceutil/trace.go:171","msg":"trace[1655497343] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:805; }","duration":"163.822763ms","start":"2026-05-24T07:05:12.303508Z","end":"2026-05-24T07:05:12.467331Z","steps":["trace[1655497343] 'agreement among raft nodes before linearized reading' (duration: 163.686729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:12.467491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.62801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:05:12.467556Z","caller":"traceutil/trace.go:171","msg":"trace[3459158] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:805; }","duration":"148.724253ms","start":"2026-05-24T07:05:12.318818Z","end":"2026-05-24T07:05:12.467543Z","steps":["trace[3459158] 'agreement among raft nodes before linearized reading' (duration: 148.62174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:12.467562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.33182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-24T07:05:12.467606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.25282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:05:12.467596Z","caller":"traceutil/trace.go:171","msg":"trace[85779737] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:805; }","duration":"163.379701ms","start":"2026-05-24T07:05:12.304207Z","end":"2026-05-24T07:05:12.467587Z","steps":["trace[85779737] 'agreement among raft nodes before linearized reading' (duration: 163.295739ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:12.467687Z","caller":"traceutil/trace.go:171","msg":"trace[1254808408] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:805; }","duration":"148.353542ms","start":"2026-05-24T07:05:12.319302Z","end":"2026-05-24T07:05:12.467655Z","steps":["trace[1254808408] 'agreement among raft nodes before linearized reading' (duration: 148.192268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:12.467928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.898388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:05:12.468002Z","caller":"traceutil/trace.go:171","msg":"trace[166376425] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:805; }","duration":"149.212296ms","start":"2026-05-24T07:05:12.318764Z","end":"2026-05-24T07:05:12.467977Z","steps":["trace[166376425] 'agreement among raft nodes before linearized reading' (duration: 148.700072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:16.912882Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422724397856768,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-24T07:05:16.931984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281948876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:05:16.932112Z","caller":"traceutil/trace.go:171","msg":"trace[332639878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:824; }","duration":"1.28213497s","start":"2026-05-24T07:05:15.649952Z","end":"2026-05-24T07:05:16.932087Z","steps":["trace[332639878] 'range keys from in-memory index tree' (duration: 1.281870853s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:16.932161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:05:15.649934Z","time spent":"1.282216302s","remote":"127.0.0.1:50014","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-05-24T07:05:16.932268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010668872s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T07:05:16.932374Z","caller":"traceutil/trace.go:171","msg":"trace[1111792283] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:824; }","duration":"1.010816366s","start":"2026-05-24T07:05:15.921535Z","end":"2026-05-24T07:05:16.932352Z","steps":["trace[1111792283] 'count revisions from in-memory index tree' (duration: 1.010546288s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:16.932428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:05:15.921475Z","time spent":"1.010939999s","remote":"127.0.0.1:50502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-24T07:05:16.93362Z","caller":"traceutil/trace.go:171","msg":"trace[619382873] linearizableReadLoop","detail":"{readStateIndex:863; appliedIndex:862; }","duration":"521.731664ms","start":"2026-05-24T07:05:16.411861Z","end":"2026-05-24T07:05:16.933592Z","steps":["trace[619382873] 'read index received' (duration: 520.617743ms)","trace[619382873] 'applied index is now lower than readState.Index' (duration: 1.112371ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:05:16.933693Z","caller":"traceutil/trace.go:171","msg":"trace[1704712668] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"782.76953ms","start":"2026-05-24T07:05:16.15089Z","end":"2026-05-24T07:05:16.933659Z","steps":["trace[1704712668] 'process raft request' (duration: 781.663529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:16.933847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.97534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-24T07:05:16.93389Z","caller":"traceutil/trace.go:171","msg":"trace[1004972675] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:825; }","duration":"522.069703ms","start":"2026-05-24T07:05:16.41181Z","end":"2026-05-24T07:05:16.93388Z","steps":["trace[1004972675] 'agreement among raft nodes before linearized reading' (duration: 521.888108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:16.933924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:05:16.411787Z","time spent":"522.127904ms","remote":"127.0.0.1:50052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"warn","ts":"2026-05-24T07:05:16.933925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:05:16.15086Z","time spent":"782.918413ms","remote":"127.0.0.1:50216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:05:18.071731Z","caller":"traceutil/trace.go:171","msg":"trace[633205795] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"131.613299ms","start":"2026-05-24T07:05:17.940095Z","end":"2026-05-24T07:05:18.071708Z","steps":["trace[633205795] 'process raft request' (duration: 131.481735ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:19.214007Z","caller":"traceutil/trace.go:171","msg":"trace[612855214] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"151.466913ms","start":"2026-05-24T07:05:19.062518Z","end":"2026-05-24T07:05:19.213985Z","steps":["trace[612855214] 'process raft request' (duration: 151.330329ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:19.244591Z","caller":"traceutil/trace.go:171","msg":"trace[1897415833] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:874; }","duration":"159.957418ms","start":"2026-05-24T07:05:19.084609Z","end":"2026-05-24T07:05:19.244566Z","steps":["trace[1897415833] 'read index received' (duration: 129.369746ms)","trace[1897415833] 'applied index is now lower than readState.Index' (duration: 30.586582ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:05:19.244729Z","caller":"traceutil/trace.go:171","msg":"trace[984259378] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"178.643414ms","start":"2026-05-24T07:05:19.066036Z","end":"2026-05-24T07:05:19.244679Z","steps":["trace[984259378] 'process raft request' (duration: 178.397677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:19.244932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.200912ms","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-05-24T07:05:19.244988Z","caller":"traceutil/trace.go:171","msg":"trace[1000770799] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:836; }","duration":"143.276335ms","start":"2026-05-24T07:05:19.101697Z","end":"2026-05-24T07:05:19.244973Z","steps":["trace[1000770799] 'agreement among raft nodes before linearized reading' (duration: 143.054299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:19.245068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.447631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-24T07:05:19.245132Z","caller":"traceutil/trace.go:171","msg":"trace[1473503028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:836; }","duration":"160.534874ms","start":"2026-05-24T07:05:19.084586Z","end":"2026-05-24T07:05:19.245121Z","steps":["trace[1473503028] 'agreement among raft nodes before linearized reading' (duration: 160.118892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:19.440665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.369263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:05:19.440829Z","caller":"traceutil/trace.go:171","msg":"trace[1338165886] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"190.53873ms","start":"2026-05-24T07:05:19.250263Z","end":"2026-05-24T07:05:19.440802Z","steps":["trace[1338165886] 'process raft request' (duration: 10.89959ms)","trace[1338165886] 'compare' (duration: 179.191419ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:05:19.441706Z","caller":"traceutil/trace.go:171","msg":"trace[21911910] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"191.618319ms","start":"2026-05-24T07:05:19.250069Z","end":"2026-05-24T07:05:19.441687Z","steps":["trace[21911910] 'process raft request' (duration: 191.453175ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:19.442063Z","caller":"traceutil/trace.go:171","msg":"trace[324331670] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"189.106812ms","start":"2026-05-24T07:05:19.252884Z","end":"2026-05-24T07:05:19.44199Z","steps":["trace[324331670] 'process raft request' (duration: 188.729522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:20.017334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.13977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:05:20.017407Z","caller":"traceutil/trace.go:171","msg":"trace[1117765465] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:840; }","duration":"436.279273ms","start":"2026-05-24T07:05:19.581111Z","end":"2026-05-24T07:05:20.017391Z","steps":["trace[1117765465] 'count revisions from in-memory index tree' (duration: 436.017156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:20.017592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:05:19.58109Z","time spent":"436.347194ms","remote":"127.0.0.1:50262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-24T07:05:20.018079Z","caller":"traceutil/trace.go:171","msg":"trace[75809092] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"403.861873ms","start":"2026-05-24T07:05:19.614169Z","end":"2026-05-24T07:05:20.018031Z","steps":["trace[75809092] 'process raft request' (duration: 394.673151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:20.018694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:05:19.614145Z","time spent":"404.417187ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:05:20.019412Z","caller":"traceutil/trace.go:171","msg":"trace[1848550823] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"331.387347ms","start":"2026-05-24T07:05:19.688001Z","end":"2026-05-24T07:05:20.019389Z","steps":["trace[1848550823] 'process raft request' (duration: 331.275225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:20.01962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:05:19.687971Z","time spent":"331.515081ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:05:26.247956Z","caller":"traceutil/trace.go:171","msg":"trace[1161156486] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"150.873921ms","start":"2026-05-24T07:05:26.097051Z","end":"2026-05-24T07:05:26.247925Z","steps":["trace[1161156486] 'process raft request' (duration: 150.652995ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:27.524268Z","caller":"traceutil/trace.go:171","msg":"trace[1074222369] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"182.740438ms","start":"2026-05-24T07:05:27.34151Z","end":"2026-05-24T07:05:27.52425Z","steps":["trace[1074222369] 'read index received' (duration: 182.563974ms)","trace[1074222369] 'applied index is now lower than readState.Index' (duration: 175.994µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:05:27.524528Z","caller":"traceutil/trace.go:171","msg":"trace[1672370921] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"239.821838ms","start":"2026-05-24T07:05:27.2847Z","end":"2026-05-24T07:05:27.524522Z","steps":["trace[1672370921] 'process raft request' (duration: 239.431679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:27.524735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.206259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-24T07:05:27.524758Z","caller":"traceutil/trace.go:171","msg":"trace[98157637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:944; }","duration":"183.26956ms","start":"2026-05-24T07:05:27.341482Z","end":"2026-05-24T07:05:27.524752Z","steps":["trace[98157637] 'agreement among raft nodes before linearized reading' (duration: 183.166797ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:27.766232Z","caller":"traceutil/trace.go:171","msg":"trace[1047954007] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"133.297133ms","start":"2026-05-24T07:05:27.632894Z","end":"2026-05-24T07:05:27.766191Z","steps":["trace[1047954007] 'process raft request' (duration: 132.33693ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:39.814057Z","caller":"traceutil/trace.go:171","msg":"trace[1857646141] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"124.463157ms","start":"2026-05-24T07:05:39.689543Z","end":"2026-05-24T07:05:39.814007Z","steps":["trace[1857646141] 'process raft request' (duration: 95.111071ms)","trace[1857646141] 'compare' (duration: 29.142801ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:05:39.81416Z","caller":"traceutil/trace.go:171","msg":"trace[772194494] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"124.484507ms","start":"2026-05-24T07:05:39.689619Z","end":"2026-05-24T07:05:39.814104Z","steps":["trace[772194494] 'process raft request' (duration: 124.339474ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:39.814355Z","caller":"traceutil/trace.go:171","msg":"trace[300573040] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"124.496047ms","start":"2026-05-24T07:05:39.689567Z","end":"2026-05-24T07:05:39.814063Z","steps":["trace[300573040] 'process raft request' (duration: 124.353014ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:45.896728Z","caller":"traceutil/trace.go:171","msg":"trace[1299704869] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"101.778005ms","start":"2026-05-24T07:05:45.794908Z","end":"2026-05-24T07:05:45.896686Z","steps":["trace[1299704869] 'process raft request' (duration: 32.029369ms)","trace[1299704869] 'compare' (duration: 69.530971ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:05:50.23319Z","caller":"traceutil/trace.go:171","msg":"trace[1030451174] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"311.540103ms","start":"2026-05-24T07:05:49.921615Z","end":"2026-05-24T07:05:50.233155Z","steps":["trace[1030451174] 'process raft request' (duration: 311.356859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:50.233409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:05:49.921597Z","time spent":"311.698595ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:05:50.283452Z","caller":"traceutil/trace.go:171","msg":"trace[1758691266] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1104; }","duration":"284.680323ms","start":"2026-05-24T07:05:49.99875Z","end":"2026-05-24T07:05:50.28343Z","steps":["trace[1758691266] 'read index received' (duration: 234.253753ms)","trace[1758691266] 'applied index is now lower than readState.Index' (duration: 50.425ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:05:50.283536Z","caller":"traceutil/trace.go:171","msg":"trace[887456424] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"313.836591ms","start":"2026-05-24T07:05:49.969649Z","end":"2026-05-24T07:05:50.283485Z","steps":["trace[887456424] 'process raft request' (duration: 312.530344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:50.28371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.60027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"warn","ts":"2026-05-24T07:05:50.283774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:05:49.96962Z","time spent":"314.025434ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:05:50.283795Z","caller":"traceutil/trace.go:171","msg":"trace[1948729026] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1061; }","duration":"280.770403ms","start":"2026-05-24T07:05:50.002979Z","end":"2026-05-24T07:05:50.283749Z","steps":["trace[1948729026] 'agreement among raft nodes before linearized reading' (duration: 280.590649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:50.283784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.022349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-24T07:05:50.283861Z","caller":"traceutil/trace.go:171","msg":"trace[169503624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1061; }","duration":"285.146852ms","start":"2026-05-24T07:05:49.998694Z","end":"2026-05-24T07:05:50.283841Z","steps":["trace[169503624] 'agreement among raft nodes before linearized reading' (duration: 284.870286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:50.283837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.803983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-24T07:05:50.283981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.381424ms","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-05-24T07:05:50.284124Z","caller":"traceutil/trace.go:171","msg":"trace[1694349911] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1061; }","duration":"267.617259ms","start":"2026-05-24T07:05:50.016491Z","end":"2026-05-24T07:05:50.284108Z","steps":["trace[1694349911] 'agreement among raft nodes before linearized reading' (duration: 267.1883ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:50.284136Z","caller":"traceutil/trace.go:171","msg":"trace[1869359860] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1061; }","duration":"266.059828ms","start":"2026-05-24T07:05:50.017971Z","end":"2026-05-24T07:05:50.284031Z","steps":["trace[1869359860] 'agreement among raft nodes before linearized reading' (duration: 265.62433ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:51.927726Z","caller":"traceutil/trace.go:171","msg":"trace[409728845] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"113.956543ms","start":"2026-05-24T07:05:51.81374Z","end":"2026-05-24T07:05:51.927696Z","steps":["trace[409728845] 'process raft request' (duration: 113.810991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:52.106264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.450668ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-24T07:05:56.68716Z","caller":"traceutil/trace.go:171","msg":"trace[882503569] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"189.847344ms","start":"2026-05-24T07:05:56.497288Z","end":"2026-05-24T07:05:56.687135Z","steps":["trace[882503569] 'process raft request' (duration: 189.62447ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:56.695662Z","caller":"traceutil/trace.go:171","msg":"trace[40232055] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"195.211736ms","start":"2026-05-24T07:05:56.50042Z","end":"2026-05-24T07:05:56.695632Z","steps":["trace[40232055] 'process raft request' (duration: 194.961201ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:56.695768Z","caller":"traceutil/trace.go:171","msg":"trace[820732286] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"194.658916ms","start":"2026-05-24T07:05:56.501076Z","end":"2026-05-24T07:05:56.695735Z","steps":["trace[820732286] 'process raft request' (duration: 194.457782ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:05:56.696237Z","caller":"traceutil/trace.go:171","msg":"trace[1723199578] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1133; }","duration":"143.342827ms","start":"2026-05-24T07:05:56.552869Z","end":"2026-05-24T07:05:56.696212Z","steps":["trace[1723199578] 'read index received' (duration: 134.0959ms)","trace[1723199578] 'applied index is now lower than readState.Index' (duration: 9.245937ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:05:56.696317Z","caller":"traceutil/trace.go:171","msg":"trace[1123186437] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"195.059813ms","start":"2026-05-24T07:05:56.501247Z","end":"2026-05-24T07:05:56.696307Z","steps":["trace[1123186437] 'process raft request' (duration: 194.801849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:05:56.696386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.495199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T07:05:56.697099Z","caller":"traceutil/trace.go:171","msg":"trace[864617472] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1089; }","duration":"144.232804ms","start":"2026-05-24T07:05:56.552846Z","end":"2026-05-24T07:05:56.697079Z","steps":["trace[864617472] 'agreement among raft nodes before linearized reading' (duration: 143.47841ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:06:42.426178Z","caller":"traceutil/trace.go:171","msg":"trace[824125835] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"126.051973ms","start":"2026-05-24T07:06:42.300102Z","end":"2026-05-24T07:06:42.426154Z","steps":["trace[824125835] 'process raft request' (duration: 125.909151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:06:50.686578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.932086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:06:50.686716Z","caller":"traceutil/trace.go:171","msg":"trace[493383999] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1319; }","duration":"139.54766ms","start":"2026-05-24T07:06:50.547144Z","end":"2026-05-24T07:06:50.686692Z","steps":["trace[493383999] 'read index received' (duration: 26.27877ms)","trace[493383999] 'applied index is now lower than readState.Index' (duration: 113.26685ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:06:50.686821Z","caller":"traceutil/trace.go:171","msg":"trace[2061382334] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"152.189933ms","start":"2026-05-24T07:06:50.534618Z","end":"2026-05-24T07:06:50.686808Z","steps":["trace[2061382334] 'process raft request' (duration: 38.928863ms)","trace[2061382334] 'compare' (duration: 112.772854ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:06:50.686936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.786762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-24T07:06:50.686997Z","caller":"traceutil/trace.go:171","msg":"trace[1748827640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1262; }","duration":"139.853553ms","start":"2026-05-24T07:06:50.547129Z","end":"2026-05-24T07:06:50.686982Z","steps":["trace[1748827640] 'agreement among raft nodes before linearized reading' (duration: 139.718522ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:07:02.698624Z","caller":"traceutil/trace.go:171","msg":"trace[1849654123] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"145.981795ms","start":"2026-05-24T07:07:02.552601Z","end":"2026-05-24T07:07:02.698583Z","steps":["trace[1849654123] 'process raft request' (duration: 145.721602ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:07:09.220575Z","caller":"traceutil/trace.go:171","msg":"trace[1518103154] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"488.205894ms","start":"2026-05-24T07:07:08.732304Z","end":"2026-05-24T07:07:09.22051Z","steps":["trace[1518103154] 'process raft request' (duration: 487.84161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:07:09.221856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:07:08.732283Z","time spent":"488.477687ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:07:09.22336Z","caller":"traceutil/trace.go:171","msg":"trace[2060877270] linearizableReadLoop","detail":"{readStateIndex:1424; appliedIndex:1422; }","duration":"369.240675ms","start":"2026-05-24T07:07:08.854086Z","end":"2026-05-24T07:07:09.223327Z","steps":["trace[2060877270] 'read index received' (duration: 366.031414ms)","trace[2060877270] 'applied index is now lower than readState.Index' (duration: 3.208311ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:07:09.223766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.65944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-24T07:07:09.223848Z","caller":"traceutil/trace.go:171","msg":"trace[882685181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1360; }","duration":"369.797521ms","start":"2026-05-24T07:07:08.854031Z","end":"2026-05-24T07:07:09.223829Z","steps":["trace[882685181] 'agreement among raft nodes before linearized reading' (duration: 369.390866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:07:09.224004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:07:08.854013Z","time spent":"369.980424ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-24T07:07:09.223789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.238705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-24T07:07:09.224235Z","caller":"traceutil/trace.go:171","msg":"trace[928642685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1360; }","duration":"111.807613ms","start":"2026-05-24T07:07:09.112399Z","end":"2026-05-24T07:07:09.224207Z","steps":["trace[928642685] 'agreement among raft nodes before linearized reading' (duration: 111.124504ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:07:09.223423Z","caller":"traceutil/trace.go:171","msg":"trace[2079162520] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"420.558384ms","start":"2026-05-24T07:07:08.802814Z","end":"2026-05-24T07:07:09.223373Z","steps":["trace[2079162520] 'process raft request' (duration: 420.361102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:07:09.224975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:07:08.80279Z","time spent":"422.068974ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}