{"level":"warn","ts":"2026-07-24T05:20:50.813113Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T05:20:50.813214Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-07-24T05:20:50.813296Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T05:20:50.813308Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-07-24T05:20:50.813328Z","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-07-24T05:20:50.814187Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-07-24T05:20:50.814332Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-07-24T05:20:50.818728Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.96189ms"} {"level":"info","ts":"2026-07-24T05:20:50.824437Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-07-24T05:20:50.824586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T05:20:50.824627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-07-24T05:20:50.824647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T05:20:50.824841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-07-24T05:20:50.824924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-07-24T05:20:50.859612Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T05:20:50.861779Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T05:20:50.863279Z","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-07-24T05:20:50.865801Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T05:20:50.868421Z","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-07-24T05:20:50.868692Z","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-07-24T05:20:50.868718Z","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-07-24T05:20:50.868595Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T05:20:50.869983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-07-24T05:20:50.871347Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-07-24T05:20:50.872038Z","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-07-24T05:20:50.872057Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-07-24T05:20:50.87243Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-07-24T05:20:50.873523Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T05:20:50.873551Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T05:20:51.727913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T05:20:51.727975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T05:20:51.728026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-07-24T05:20:51.728049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-07-24T05:20:51.728061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-07-24T05:20:51.728078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-07-24T05:20:51.728093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-07-24T05:20:51.72893Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T05:20:51.729758Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T05:20:51.729817Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T05:20:51.730002Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T05:20:51.730359Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T05:20:51.730397Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T05:20:51.730803Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T05:20:51.730917Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T05:20:51.730956Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T05:20:51.734305Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-24T05:20:51.74106Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-07-24T05:21:17.600403Z","caller":"traceutil/trace.go:171","msg":"trace[1004309416] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"387.341117ms","start":"2026-07-24T05:21:17.213035Z","end":"2026-07-24T05:21:17.600376Z","steps":["trace[1004309416] 'read index received' (duration: 387.09366ms)","trace[1004309416] 'applied index is now lower than readState.Index' (duration: 246.567µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:21:17.600543Z","caller":"traceutil/trace.go:171","msg":"trace[1352886418] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"444.678447ms","start":"2026-07-24T05:21:17.155799Z","end":"2026-07-24T05:21:17.600478Z","steps":["trace[1352886418] 'process raft request' (duration: 444.42604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:21:17.600633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.557602ms","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-07-24T05:21:17.600697Z","caller":"traceutil/trace.go:171","msg":"trace[903655167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:396; }","duration":"387.709656ms","start":"2026-07-24T05:21:17.212974Z","end":"2026-07-24T05:21:17.600684Z","steps":["trace[903655167] 'agreement among raft nodes before linearized reading' (duration: 387.569913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:21:17.600738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.357298ms","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-07-24T05:21:17.600769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:17.212948Z","time spent":"387.802009ms","remote":"127.0.0.1:45252","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-07-24T05:21:17.600811Z","caller":"traceutil/trace.go:171","msg":"trace[1296288060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:396; }","duration":"387.483581ms","start":"2026-07-24T05:21:17.213311Z","end":"2026-07-24T05:21:17.600795Z","steps":["trace[1296288060] 'agreement among raft nodes before linearized reading' (duration: 387.376578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:21:17.600851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:17.213283Z","time spent":"387.559913ms","remote":"127.0.0.1:45252","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-07-24T05:21:17.600965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.192931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-24T05:21:17.601002Z","caller":"traceutil/trace.go:171","msg":"trace[1440609672] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:396; }","duration":"277.283623ms","start":"2026-07-24T05:21:17.32371Z","end":"2026-07-24T05:21:17.600994Z","steps":["trace[1440609672] 'agreement among raft nodes before linearized reading' (duration: 277.227981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:21:17.601091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.447718ms","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-07-24T05:21:17.60113Z","caller":"traceutil/trace.go:171","msg":"trace[1848044153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:396; }","duration":"328.512299ms","start":"2026-07-24T05:21:17.272609Z","end":"2026-07-24T05:21:17.601121Z","steps":["trace[1848044153] 'agreement among raft nodes before linearized reading' (duration: 328.457438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:21:17.60116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:17.272596Z","time spent":"328.555862ms","remote":"127.0.0.1:45252","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":"warn","ts":"2026-07-24T05:21:17.601498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:17.15577Z","time spent":"444.924122ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:21:46.8676Z","caller":"traceutil/trace.go:171","msg":"trace[821658798] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"106.063714ms","start":"2026-07-24T05:21:46.761497Z","end":"2026-07-24T05:21:46.867561Z","steps":["trace[821658798] 'process raft request' (duration: 105.800247ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:21:47.509125Z","caller":"traceutil/trace.go:171","msg":"trace[1172735927] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"466.429614ms","start":"2026-07-24T05:21:47.042622Z","end":"2026-07-24T05:21:47.509052Z","steps":["trace[1172735927] 'process raft request' (duration: 464.71609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:21:47.509386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:47.042584Z","time spent":"466.66071ms","remote":"127.0.0.1:45086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:21:48.420324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.709138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:21:48.420474Z","caller":"traceutil/trace.go:171","msg":"trace[1797714677] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"574.947578ms","start":"2026-07-24T05:21:47.845501Z","end":"2026-07-24T05:21:48.420449Z","steps":["trace[1797714677] 'read index received' (duration: 58.792µs)","trace[1797714677] 'applied index is now lower than readState.Index' (duration: 574.886496ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:21:48.420593Z","caller":"traceutil/trace.go:171","msg":"trace[451074964] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"837.224744ms","start":"2026-07-24T05:21:47.583327Z","end":"2026-07-24T05:21:48.420552Z","steps":["trace[451074964] 'process raft request' (duration: 21.926541ms)","trace[451074964] 'compare' (duration: 814.262717ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:21:48.42082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.313006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-24T05:21:48.420845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:47.58327Z","time spent":"837.47244ms","remote":"127.0.0.1:45514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T05:21:48.420871Z","caller":"traceutil/trace.go:171","msg":"trace[1420283076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"575.413809ms","start":"2026-07-24T05:21:47.845445Z","end":"2026-07-24T05:21:48.420859Z","steps":["trace[1420283076] 'agreement among raft nodes before linearized reading' (duration: 575.122142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:21:48.420912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.245759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-24T05:21:48.420822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.68203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-24T05:21:48.420961Z","caller":"traceutil/trace.go:171","msg":"trace[1459083671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"570.316901ms","start":"2026-07-24T05:21:47.850629Z","end":"2026-07-24T05:21:48.420946Z","steps":["trace[1459083671] 'agreement among raft nodes before linearized reading' (duration: 570.224339ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:21:48.420964Z","caller":"traceutil/trace.go:171","msg":"trace[702362148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"573.0643ms","start":"2026-07-24T05:21:47.847884Z","end":"2026-07-24T05:21:48.420948Z","steps":["trace[702362148] 'agreement among raft nodes before linearized reading' (duration: 572.812584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:21:48.421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:47.847857Z","time spent":"573.137041ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T05:21:48.420916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:47.845424Z","time spent":"575.482211ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T05:21:48.421001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:47.85062Z","time spent":"570.373062ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T05:21:48.421209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.889546ms","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-07-24T05:21:48.421243Z","caller":"traceutil/trace.go:171","msg":"trace[1705025938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"570.955167ms","start":"2026-07-24T05:21:47.85028Z","end":"2026-07-24T05:21:48.421236Z","steps":["trace[1705025938] 'agreement among raft nodes before linearized reading' (duration: 570.849223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:21:48.421276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:47.850267Z","time spent":"571.002069ms","remote":"127.0.0.1:45252","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-07-24T05:21:48.944719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.213697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:21:48.944829Z","caller":"traceutil/trace.go:171","msg":"trace[7084895] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"517.599937ms","start":"2026-07-24T05:21:48.42721Z","end":"2026-07-24T05:21:48.94481Z","steps":["trace[7084895] 'process raft request' (duration: 401.208685ms)","trace[7084895] 'compare' (duration: 116.103625ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:21:48.944888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:48.427185Z","time spent":"517.675458ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:21:48.947637Z","caller":"traceutil/trace.go:171","msg":"trace[1015260146] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"519.325701ms","start":"2026-07-24T05:21:48.428294Z","end":"2026-07-24T05:21:48.94762Z","steps":["trace[1015260146] 'process raft request' (duration: 519.262009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:21:48.947798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:48.42829Z","time spent":"519.466504ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:21:48.94782Z","caller":"traceutil/trace.go:171","msg":"trace[523225433] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:652; }","duration":"514.50278ms","start":"2026-07-24T05:21:48.433294Z","end":"2026-07-24T05:21:48.947797Z","steps":["trace[523225433] 'read index received' (duration: 395.147292ms)","trace[523225433] 'applied index is now lower than readState.Index' (duration: 119.354067ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:21:48.947968Z","caller":"traceutil/trace.go:171","msg":"trace[936485025] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"520.014239ms","start":"2026-07-24T05:21:48.42794Z","end":"2026-07-24T05:21:48.947954Z","steps":["trace[936485025] 'process raft request' (duration: 519.484115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:21:48.948066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:48.427915Z","time spent":"520.121971ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:21:48.948092Z","caller":"traceutil/trace.go:171","msg":"trace[1869198108] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"387.891931ms","start":"2026-07-24T05:21:48.560166Z","end":"2026-07-24T05:21:48.948058Z","steps":["trace[1869198108] 'process raft request' (duration: 387.424248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:21:48.948192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:48.560129Z","time spent":"388.011574ms","remote":"127.0.0.1:45252","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-07-24T05:21:48.948212Z","caller":"traceutil/trace.go:171","msg":"trace[1111028482] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"519.956567ms","start":"2026-07-24T05:21:48.428245Z","end":"2026-07-24T05:21:48.948202Z","steps":["trace[1111028482] 'process raft request' (duration: 519.270909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:21:48.948311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:48.428238Z","time spent":"520.039449ms","remote":"127.0.0.1:45252","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-07-24T05:21:48.948338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.917223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:21:48.94845Z","caller":"traceutil/trace.go:171","msg":"trace[2065165869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"447.098718ms","start":"2026-07-24T05:21:48.501323Z","end":"2026-07-24T05:21:48.948422Z","steps":["trace[2065165869] 'agreement among raft nodes before linearized reading' (duration: 446.905873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:21:48.948515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:48.501296Z","time spent":"447.208191ms","remote":"127.0.0.1:45014","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-07-24T05:21:48.948811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.475254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:21:48.948958Z","caller":"traceutil/trace.go:171","msg":"trace[1235705813] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:628; }","duration":"515.656919ms","start":"2026-07-24T05:21:48.433273Z","end":"2026-07-24T05:21:48.94893Z","steps":["trace[1235705813] 'agreement among raft nodes before linearized reading' (duration: 514.932651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:21:48.949039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:21:48.433263Z","time spent":"515.763211ms","remote":"127.0.0.1:45238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-07-24T05:22:02.253524Z","caller":"traceutil/trace.go:171","msg":"trace[1022615980] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"388.183301ms","start":"2026-07-24T05:22:01.86529Z","end":"2026-07-24T05:22:02.253473Z","steps":["trace[1022615980] 'process raft request' (duration: 387.817762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:02.253819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:01.865278Z","time spent":"388.380926ms","remote":"127.0.0.1:45192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4854,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:22:05.264599Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356766940767883764,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T05:22:05.455759Z","caller":"traceutil/trace.go:171","msg":"trace[353790767] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"691.586958ms","start":"2026-07-24T05:22:04.764148Z","end":"2026-07-24T05:22:05.455735Z","steps":["trace[353790767] 'read index received' (duration: 691.375373ms)","trace[353790767] 'applied index is now lower than readState.Index' (duration: 210.665µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:22:05.45593Z","caller":"traceutil/trace.go:171","msg":"trace[1355627229] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"924.141888ms","start":"2026-07-24T05:22:04.531777Z","end":"2026-07-24T05:22:05.455919Z","steps":["trace[1355627229] 'process raft request' (duration: 923.80538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:05.456188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:04.531756Z","time spent":"924.230221ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:22:05.456293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.174923ms","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-07-24T05:22:05.45633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.336342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-24T05:22:05.456383Z","caller":"traceutil/trace.go:171","msg":"trace[1171429784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"416.325708ms","start":"2026-07-24T05:22:05.040035Z","end":"2026-07-24T05:22:05.45636Z","steps":["trace[1171429784] 'agreement among raft nodes before linearized reading' (duration: 416.134123ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:22:05.4564Z","caller":"traceutil/trace.go:171","msg":"trace[755927658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:792; }","duration":"419.432686ms","start":"2026-07-24T05:22:05.036941Z","end":"2026-07-24T05:22:05.456374Z","steps":["trace[755927658] 'agreement among raft nodes before linearized reading' (duration: 419.312972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:05.456434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:05.036917Z","time spent":"419.509018ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T05:22:05.456428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:05.040003Z","time spent":"416.41619ms","remote":"127.0.0.1:45252","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-07-24T05:22:05.456586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.555864ms","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-07-24T05:22:05.456632Z","caller":"traceutil/trace.go:171","msg":"trace[1393856763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:792; }","duration":"424.646637ms","start":"2026-07-24T05:22:05.031972Z","end":"2026-07-24T05:22:05.456619Z","steps":["trace[1393856763] 'agreement among raft nodes before linearized reading' (duration: 424.528224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:05.45662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.775135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-z5tsj\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-07-24T05:22:05.456669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:05.03193Z","time spent":"424.731769ms","remote":"127.0.0.1:45252","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-07-24T05:22:05.456712Z","caller":"traceutil/trace.go:171","msg":"trace[1322799257] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-z5tsj; range_end:; response_count:1; response_revision:792; }","duration":"590.322219ms","start":"2026-07-24T05:22:04.866371Z","end":"2026-07-24T05:22:05.456693Z","steps":["trace[1322799257] 'agreement among raft nodes before linearized reading' (duration: 589.667862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:05.45681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:04.866346Z","time spent":"590.452813ms","remote":"127.0.0.1:45192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-z5tsj\" "} {"level":"warn","ts":"2026-07-24T05:22:05.456783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.605964ms","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-07-24T05:22:05.456893Z","caller":"traceutil/trace.go:171","msg":"trace[656460977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"416.748468ms","start":"2026-07-24T05:22:05.040119Z","end":"2026-07-24T05:22:05.456867Z","steps":["trace[656460977] 'agreement among raft nodes before linearized reading' (duration: 416.381059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:05.456957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:05.04006Z","time spent":"416.883682ms","remote":"127.0.0.1:45252","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-07-24T05:22:05.456472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.308547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-24T05:22:05.457053Z","caller":"traceutil/trace.go:171","msg":"trace[1175515089] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:792; }","duration":"692.938233ms","start":"2026-07-24T05:22:04.764091Z","end":"2026-07-24T05:22:05.45703Z","steps":["trace[1175515089] 'agreement among raft nodes before linearized reading' (duration: 692.150772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:05.457107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:04.764066Z","time spent":"693.028484ms","remote":"127.0.0.1:45036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-07-24T05:22:05.813667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:05.458861Z","time spent":"354.796165ms","remote":"127.0.0.1:45036","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-24T05:22:05.831222Z","caller":"traceutil/trace.go:171","msg":"trace[337695116] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"370.422716ms","start":"2026-07-24T05:22:05.460772Z","end":"2026-07-24T05:22:05.831194Z","steps":["trace[337695116] 'process raft request' (duration: 370.16499ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:22:05.831361Z","caller":"traceutil/trace.go:171","msg":"trace[13089643] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"369.550335ms","start":"2026-07-24T05:22:05.461786Z","end":"2026-07-24T05:22:05.831337Z","steps":["trace[13089643] 'process raft request' (duration: 369.36597ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:22:05.831379Z","caller":"traceutil/trace.go:171","msg":"trace[261155630] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:826; }","duration":"329.965851ms","start":"2026-07-24T05:22:05.501327Z","end":"2026-07-24T05:22:05.831292Z","steps":["trace[261155630] 'read index received' (duration: 305.273733ms)","trace[261155630] 'applied index is now lower than readState.Index' (duration: 24.690468ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:22:05.83142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:05.460759Z","time spent":"370.532389ms","remote":"127.0.0.1:45252","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-07-24T05:22:05.831479Z","caller":"traceutil/trace.go:171","msg":"trace[719286500] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"366.391644ms","start":"2026-07-24T05:22:05.465077Z","end":"2026-07-24T05:22:05.831468Z","steps":["trace[719286500] 'process raft request' (duration: 366.153438ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:22:05.831452Z","caller":"traceutil/trace.go:171","msg":"trace[1496230875] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"368.721573ms","start":"2026-07-24T05:22:05.462659Z","end":"2026-07-24T05:22:05.831381Z","steps":["trace[1496230875] 'process raft request' (duration: 368.532708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:05.831508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:05.461754Z","time spent":"369.663008ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:22:05.831568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:05.465062Z","time spent":"366.455946ms","remote":"127.0.0.1:45192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:22:05.83167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:05.462628Z","time spent":"368.936919ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:22:05.831771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.451174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:22:05.831813Z","caller":"traceutil/trace.go:171","msg":"trace[363259386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"330.498715ms","start":"2026-07-24T05:22:05.501301Z","end":"2026-07-24T05:22:05.8318Z","steps":["trace[363259386] 'agreement among raft nodes before linearized reading' (duration: 330.377891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:05.831857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:05.501222Z","time spent":"330.627369ms","remote":"127.0.0.1:45014","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-07-24T05:22:05.831951Z","caller":"traceutil/trace.go:171","msg":"trace[1992829025] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"369.900114ms","start":"2026-07-24T05:22:05.461351Z","end":"2026-07-24T05:22:05.831251Z","steps":["trace[1992829025] 'process raft request' (duration: 369.75772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:05.8323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:05.461343Z","time spent":"370.765496ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:22:42.51249Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356766940767884227,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T05:22:42.561966Z","caller":"traceutil/trace.go:171","msg":"trace[992392795] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"552.110741ms","start":"2026-07-24T05:22:42.009808Z","end":"2026-07-24T05:22:42.561919Z","steps":["trace[992392795] 'process raft request' (duration: 551.897756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:42.562211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:42.009775Z","time spent":"552.304006ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:22:43.538887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.932706ms","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-07-24T05:22:43.539078Z","caller":"traceutil/trace.go:171","msg":"trace[1497717274] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1010; }","duration":"745.145522ms","start":"2026-07-24T05:22:42.793906Z","end":"2026-07-24T05:22:43.539051Z","steps":["trace[1497717274] 'range keys from in-memory index tree' (duration: 744.909165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:43.539713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.299468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:22:43.539822Z","caller":"traceutil/trace.go:171","msg":"trace[921104499] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1052; }","duration":"1.52812012s","start":"2026-07-24T05:22:42.011679Z","end":"2026-07-24T05:22:43.539799Z","steps":["trace[921104499] 'read index received' (duration: 550.001849ms)","trace[921104499] 'applied index is now lower than readState.Index' (duration: 978.116261ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:22:43.539971Z","caller":"traceutil/trace.go:171","msg":"trace[1676559775] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.316663589s","start":"2026-07-24T05:22:42.223251Z","end":"2026-07-24T05:22:43.539915Z","steps":["trace[1676559775] 'process raft request' (duration: 827.012323ms)","trace[1676559775] 'compare' (duration: 489.01809ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:22:43.540059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.528382686s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-24T05:22:43.540099Z","caller":"traceutil/trace.go:171","msg":"trace[266026555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"1.528464698s","start":"2026-07-24T05:22:42.011623Z","end":"2026-07-24T05:22:43.540088Z","steps":["trace[266026555] 'agreement among raft nodes before linearized reading' (duration: 1.528296084s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:43.540136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:42.011607Z","time spent":"1.528520319s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T05:22:43.540187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:42.223212Z","time spent":"1.316860964s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:22:43.540573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038202827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T05:22:43.540609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.521851133s","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-07-24T05:22:43.540617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.347414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:22:43.540673Z","caller":"traceutil/trace.go:171","msg":"trace[1658983390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1011; }","duration":"1.521979216s","start":"2026-07-24T05:22:42.018675Z","end":"2026-07-24T05:22:43.540654Z","steps":["trace[1658983390] 'agreement among raft nodes before linearized reading' (duration: 1.521539235s)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:22:43.540674Z","caller":"traceutil/trace.go:171","msg":"trace[400918087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"1.038372022s","start":"2026-07-24T05:22:42.502271Z","end":"2026-07-24T05:22:43.540643Z","steps":["trace[400918087] 'agreement among raft nodes before linearized reading' (duration: 1.037969022s)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:22:43.540687Z","caller":"traceutil/trace.go:171","msg":"trace[1935726832] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1011; }","duration":"932.475758ms","start":"2026-07-24T05:22:42.608195Z","end":"2026-07-24T05:22:43.540671Z","steps":["trace[1935726832] 'agreement among raft nodes before linearized reading' (duration: 932.339934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:43.540716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:42.018646Z","time spent":"1.522060098s","remote":"127.0.0.1:45252","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-07-24T05:22:43.540719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:42.502225Z","time spent":"1.038485615s","remote":"127.0.0.1:45014","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-07-24T05:22:43.540727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:42.608167Z","time spent":"932.550409ms","remote":"127.0.0.1:45158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-07-24T05:22:43.540773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.519975486s","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-07-24T05:22:43.540802Z","caller":"traceutil/trace.go:171","msg":"trace[210382879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"1.520019847s","start":"2026-07-24T05:22:42.020772Z","end":"2026-07-24T05:22:43.540792Z","steps":["trace[210382879] 'agreement among raft nodes before linearized reading' (duration: 1.519938945s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:43.540829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:42.020763Z","time spent":"1.520059397s","remote":"127.0.0.1:45252","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-07-24T05:22:43.901957Z","caller":"traceutil/trace.go:171","msg":"trace[1651795877] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"356.909847ms","start":"2026-07-24T05:22:43.54501Z","end":"2026-07-24T05:22:43.901919Z","steps":["trace[1651795877] 'process raft request' (duration: 356.701452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:43.902163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:43.544991Z","time spent":"357.061911ms","remote":"127.0.0.1:45252","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-07-24T05:22:43.902951Z","caller":"traceutil/trace.go:171","msg":"trace[1167473255] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1054; }","duration":"352.501207ms","start":"2026-07-24T05:22:43.550403Z","end":"2026-07-24T05:22:43.902904Z","steps":["trace[1167473255] 'read index received' (duration: 351.384979ms)","trace[1167473255] 'applied index is now lower than readState.Index' (duration: 1.114868ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:22:43.903285Z","caller":"traceutil/trace.go:171","msg":"trace[98759687] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"357.834441ms","start":"2026-07-24T05:22:43.545431Z","end":"2026-07-24T05:22:43.903266Z","steps":["trace[98759687] 'process raft request' (duration: 357.261957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:43.903593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:43.545397Z","time spent":"358.005746ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:22:43.903906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.948699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T05:22:43.903965Z","caller":"traceutil/trace.go:171","msg":"trace[1673246948] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1014; }","duration":"219.068972ms","start":"2026-07-24T05:22:43.684883Z","end":"2026-07-24T05:22:43.903952Z","steps":["trace[1673246948] 'agreement among raft nodes before linearized reading' (duration: 218.865877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:43.904005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.605834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:22:43.904043Z","caller":"traceutil/trace.go:171","msg":"trace[1963503969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"353.673686ms","start":"2026-07-24T05:22:43.550359Z","end":"2026-07-24T05:22:43.904033Z","steps":["trace[1963503969] 'agreement among raft nodes before linearized reading' (duration: 353.605154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:43.904073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:43.550345Z","time spent":"353.722957ms","remote":"127.0.0.1:45014","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-07-24T05:22:43.904169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.047827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T05:22:43.904208Z","caller":"traceutil/trace.go:171","msg":"trace[2065306422] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1014; }","duration":"157.142519ms","start":"2026-07-24T05:22:43.747055Z","end":"2026-07-24T05:22:43.904197Z","steps":["trace[2065306422] 'agreement among raft nodes before linearized reading' (duration: 157.072498ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:22:43.904274Z","caller":"traceutil/trace.go:171","msg":"trace[125700753] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"358.692493ms","start":"2026-07-24T05:22:43.545531Z","end":"2026-07-24T05:22:43.904224Z","steps":["trace[125700753] 'process raft request' (duration: 357.306589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:22:43.90452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:22:43.545507Z","time spent":"358.900197ms","remote":"127.0.0.1:45252","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-07-24T05:23:07.866915Z","caller":"traceutil/trace.go:171","msg":"trace[1584483149] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"246.587709ms","start":"2026-07-24T05:23:07.620291Z","end":"2026-07-24T05:23:07.866878Z","steps":["trace[1584483149] 'process raft request' (duration: 246.364373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:10.060833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258044713s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T05:23:10.061162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.022454677s","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-07-24T05:23:10.061228Z","caller":"traceutil/trace.go:171","msg":"trace[919942587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1091; }","duration":"2.022567779s","start":"2026-07-24T05:23:08.038639Z","end":"2026-07-24T05:23:10.061207Z","steps":["trace[919942587] 'range keys from in-memory index tree' (duration: 2.0221484s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:10.06129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:08.038609Z","time spent":"2.022660692s","remote":"127.0.0.1:45252","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-07-24T05:23:10.061175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.01657519s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-24T05:23:10.061473Z","caller":"traceutil/trace.go:171","msg":"trace[1252217161] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1091; }","duration":"2.016911048s","start":"2026-07-24T05:23:08.044501Z","end":"2026-07-24T05:23:10.061412Z","steps":["trace[1252217161] 'range keys from in-memory index tree' (duration: 2.016232691s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:10.061575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:08.044489Z","time spent":"2.017071222s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T05:23:10.061678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.021169215s","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-07-24T05:23:10.061735Z","caller":"traceutil/trace.go:171","msg":"trace[1418801328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1091; }","duration":"2.021271217s","start":"2026-07-24T05:23:08.040449Z","end":"2026-07-24T05:23:10.06172Z","steps":["trace[1418801328] 'range keys from in-memory index tree' (duration: 2.021026921s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:10.061774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:08.040425Z","time spent":"2.021339849s","remote":"127.0.0.1:45252","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-07-24T05:23:10.061731Z","caller":"traceutil/trace.go:171","msg":"trace[845434401] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1091; }","duration":"1.258982947s","start":"2026-07-24T05:23:08.802683Z","end":"2026-07-24T05:23:10.061666Z","steps":["trace[845434401] 'count revisions from in-memory index tree' (duration: 1.257903279s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:10.061933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.354510093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-24T05:23:10.061877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.560234482s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:23:10.062111Z","caller":"traceutil/trace.go:171","msg":"trace[727684293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1091; }","duration":"1.560534609s","start":"2026-07-24T05:23:08.501549Z","end":"2026-07-24T05:23:10.062083Z","steps":["trace[727684293] 'range keys from in-memory index tree' (duration: 1.560103728s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:10.062073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:08.802653Z","time spent":"1.259341536s","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-07-24T05:23:10.062163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:08.501522Z","time spent":"1.560629522s","remote":"127.0.0.1:45014","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-07-24T05:23:10.062039Z","caller":"traceutil/trace.go:171","msg":"trace[1864056254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1091; }","duration":"1.354728958s","start":"2026-07-24T05:23:08.707286Z","end":"2026-07-24T05:23:10.062015Z","steps":["trace[1864056254] 'range keys from in-memory index tree' (duration: 1.354249287s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:10.062051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.01894745s","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-07-24T05:23:10.062324Z","caller":"traceutil/trace.go:171","msg":"trace[903925613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1091; }","duration":"2.019323738s","start":"2026-07-24T05:23:08.042986Z","end":"2026-07-24T05:23:10.06231Z","steps":["trace[903925613] 'range keys from in-memory index tree' (duration: 2.018771045s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:10.062424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:08.042975Z","time spent":"2.019435691s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-24T05:23:10.062255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:08.707256Z","time spent":"1.354982795s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-24T05:23:10.065536Z","caller":"traceutil/trace.go:171","msg":"trace[1175279768] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"190.60048ms","start":"2026-07-24T05:23:09.874918Z","end":"2026-07-24T05:23:10.065518Z","steps":["trace[1175279768] 'process raft request' (duration: 190.438106ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:23:11.182622Z","caller":"traceutil/trace.go:171","msg":"trace[905404375] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"151.230248ms","start":"2026-07-24T05:23:11.031354Z","end":"2026-07-24T05:23:11.182585Z","steps":["trace[905404375] 'process raft request' (duration: 150.996632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:11.689686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.451953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:23:11.689797Z","caller":"traceutil/trace.go:171","msg":"trace[1934346322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"187.639767ms","start":"2026-07-24T05:23:11.502132Z","end":"2026-07-24T05:23:11.689771Z","steps":["trace[1934346322] 'range keys from in-memory index tree' (duration: 187.331019ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:23:12.193258Z","caller":"traceutil/trace.go:171","msg":"trace[1673820238] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"121.242728ms","start":"2026-07-24T05:23:12.071976Z","end":"2026-07-24T05:23:12.193219Z","steps":["trace[1673820238] 'process raft request' (duration: 120.91878ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:23:14.460124Z","caller":"traceutil/trace.go:171","msg":"trace[1967082486] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1153; }","duration":"214.332044ms","start":"2026-07-24T05:23:14.245762Z","end":"2026-07-24T05:23:14.460094Z","steps":["trace[1967082486] 'read index received' (duration: 214.138448ms)","trace[1967082486] 'applied index is now lower than readState.Index' (duration: 192.356µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:23:14.460229Z","caller":"traceutil/trace.go:171","msg":"trace[848948740] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"259.708148ms","start":"2026-07-24T05:23:14.200494Z","end":"2026-07-24T05:23:14.460202Z","steps":["trace[848948740] 'process raft request' (duration: 259.443141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:14.460518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.775995ms","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-07-24T05:23:14.460531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.793831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-24T05:23:14.460626Z","caller":"traceutil/trace.go:171","msg":"trace[938657163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1105; }","duration":"184.930219ms","start":"2026-07-24T05:23:14.275642Z","end":"2026-07-24T05:23:14.460572Z","steps":["trace[938657163] 'agreement among raft nodes before linearized reading' (duration: 184.612451ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:23:14.460649Z","caller":"traceutil/trace.go:171","msg":"trace[1973522339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1105; }","duration":"183.963145ms","start":"2026-07-24T05:23:14.27666Z","end":"2026-07-24T05:23:14.460624Z","steps":["trace[1973522339] 'agreement among raft nodes before linearized reading' (duration: 183.636877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:14.460672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.058288ms","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-07-24T05:23:14.460718Z","caller":"traceutil/trace.go:171","msg":"trace[117657415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1105; }","duration":"184.114959ms","start":"2026-07-24T05:23:14.276588Z","end":"2026-07-24T05:23:14.460703Z","steps":["trace[117657415] 'agreement among raft nodes before linearized reading' (duration: 184.020247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:14.460699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.989596ms","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-07-24T05:23:14.460531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.723933ms","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-07-24T05:23:14.460819Z","caller":"traceutil/trace.go:171","msg":"trace[1679175899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1105; }","duration":"184.15903ms","start":"2026-07-24T05:23:14.276637Z","end":"2026-07-24T05:23:14.460796Z","steps":["trace[1679175899] 'agreement among raft nodes before linearized reading' (duration: 183.875513ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:23:14.46085Z","caller":"traceutil/trace.go:171","msg":"trace[1045108678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1105; }","duration":"215.115213ms","start":"2026-07-24T05:23:14.24571Z","end":"2026-07-24T05:23:14.460825Z","steps":["trace[1045108678] 'agreement among raft nodes before linearized reading' (duration: 214.540259ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:23:14.580773Z","caller":"traceutil/trace.go:171","msg":"trace[939832650] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"114.93496ms","start":"2026-07-24T05:23:14.465806Z","end":"2026-07-24T05:23:14.580741Z","steps":["trace[939832650] 'process raft request' (duration: 114.836538ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:23:14.581074Z","caller":"traceutil/trace.go:171","msg":"trace[312219461] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"115.064214ms","start":"2026-07-24T05:23:14.465994Z","end":"2026-07-24T05:23:14.581059Z","steps":["trace[312219461] 'process raft request' (duration: 95.972027ms)","trace[312219461] 'compare' (duration: 18.495823ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:23:14.581053Z","caller":"traceutil/trace.go:171","msg":"trace[814774686] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"114.741766ms","start":"2026-07-24T05:23:14.466294Z","end":"2026-07-24T05:23:14.581035Z","steps":["trace[814774686] 'process raft request' (duration: 114.407017ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:23:14.581344Z","caller":"traceutil/trace.go:171","msg":"trace[172007791] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"114.691615ms","start":"2026-07-24T05:23:14.466602Z","end":"2026-07-24T05:23:14.581293Z","steps":["trace[172007791] 'process raft request' (duration: 114.315836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:32.002472Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356766940767884722,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T05:23:32.360925Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.084483119s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T05:23:32.361471Z","caller":"traceutil/trace.go:171","msg":"trace[430105952] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1269; }","duration":"859.893578ms","start":"2026-07-24T05:23:31.50155Z","end":"2026-07-24T05:23:32.361443Z","steps":["trace[430105952] 'read index received' (duration: 859.602761ms)","trace[430105952] 'applied index is now lower than readState.Index' (duration: 289.387µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:23:32.361637Z","caller":"traceutil/trace.go:171","msg":"trace[950103147] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"1.085290659s","start":"2026-07-24T05:23:31.276331Z","end":"2026-07-24T05:23:32.361622Z","steps":["trace[950103147] 'process raft request' (duration: 1.084899669s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:32.361769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:31.276299Z","time spent":"1.085370931s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:23:32.361936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.400221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:23:32.361968Z","caller":"traceutil/trace.go:171","msg":"trace[1677251731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"860.474843ms","start":"2026-07-24T05:23:31.501483Z","end":"2026-07-24T05:23:32.361958Z","steps":["trace[1677251731] 'agreement among raft nodes before linearized reading' (duration: 860.421462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:32.362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:31.501464Z","time spent":"860.530254ms","remote":"127.0.0.1:45014","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-07-24T05:23:32.362418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.875104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T05:23:32.362557Z","caller":"traceutil/trace.go:171","msg":"trace[612451669] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1215; }","duration":"345.09132ms","start":"2026-07-24T05:23:32.017434Z","end":"2026-07-24T05:23:32.362526Z","steps":["trace[612451669] 'agreement among raft nodes before linearized reading' (duration: 344.863684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:32.362612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:32.017406Z","time spent":"345.191942ms","remote":"127.0.0.1:45048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-07-24T05:23:44.928855Z","caller":"traceutil/trace.go:171","msg":"trace[542613691] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"131.417012ms","start":"2026-07-24T05:23:44.797398Z","end":"2026-07-24T05:23:44.928815Z","steps":["trace[542613691] 'process raft request' (duration: 120.757096ms)","trace[542613691] 'compare' (duration: 10.331228ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:23:44.929173Z","caller":"traceutil/trace.go:171","msg":"trace[1489914814] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"131.669669ms","start":"2026-07-24T05:23:44.797478Z","end":"2026-07-24T05:23:44.929148Z","steps":["trace[1489914814] 'process raft request' (duration: 131.207517ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:23:44.929493Z","caller":"traceutil/trace.go:171","msg":"trace[1934224164] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"131.960356ms","start":"2026-07-24T05:23:44.79751Z","end":"2026-07-24T05:23:44.92947Z","steps":["trace[1934224164] 'process raft request' (duration: 131.599427ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:23:44.929797Z","caller":"traceutil/trace.go:171","msg":"trace[927642509] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"132.184411ms","start":"2026-07-24T05:23:44.797601Z","end":"2026-07-24T05:23:44.929785Z","steps":["trace[927642509] 'process raft request' (duration: 131.789942ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:23:44.929917Z","caller":"traceutil/trace.go:171","msg":"trace[754398392] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"108.611722ms","start":"2026-07-24T05:23:44.821291Z","end":"2026-07-24T05:23:44.929903Z","steps":["trace[754398392] 'process raft request' (duration: 108.440568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:47.455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.398895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:23:47.455235Z","caller":"traceutil/trace.go:171","msg":"trace[1461593606] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"515.137038ms","start":"2026-07-24T05:23:46.940075Z","end":"2026-07-24T05:23:47.455212Z","steps":["trace[1461593606] 'process raft request' (duration: 515.048756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:47.455361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:46.94004Z","time spent":"515.25339ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:23:47.455496Z","caller":"traceutil/trace.go:171","msg":"trace[723309305] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"514.459951ms","start":"2026-07-24T05:23:46.941005Z","end":"2026-07-24T05:23:47.455465Z","steps":["trace[723309305] 'process raft request' (duration: 514.168014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:47.455667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:46.94099Z","time spent":"514.576623ms","remote":"127.0.0.1:45252","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-07-24T05:23:47.455747Z","caller":"traceutil/trace.go:171","msg":"trace[1797341400] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"515.792154ms","start":"2026-07-24T05:23:46.939785Z","end":"2026-07-24T05:23:47.455577Z","steps":["trace[1797341400] 'process raft request' (duration: 53.710002ms)","trace[1797341400] 'compare' (duration: 461.270742ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:23:47.455896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:46.939771Z","time spent":"516.087961ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:23:48.711362Z","caller":"traceutil/trace.go:171","msg":"trace[147661592] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"126.690284ms","start":"2026-07-24T05:23:48.584639Z","end":"2026-07-24T05:23:48.711329Z","steps":["trace[147661592] 'process raft request' (duration: 38.317258ms)","trace[147661592] 'compare' (duration: 87.785852ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:23:50.076949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258231889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T05:23:50.077011Z","caller":"traceutil/trace.go:171","msg":"trace[1797082674] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1278; }","duration":"1.258351161s","start":"2026-07-24T05:23:48.818646Z","end":"2026-07-24T05:23:50.076997Z","steps":["trace[1797082674] 'range keys from in-memory index tree' (duration: 1.258055154s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:50.077037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:48.818623Z","time spent":"1.258408513s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-24T05:23:50.077028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.142197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:23:50.077105Z","caller":"traceutil/trace.go:171","msg":"trace[894460437] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1278; }","duration":"561.317252ms","start":"2026-07-24T05:23:49.515768Z","end":"2026-07-24T05:23:50.077086Z","steps":["trace[894460437] 'count revisions from in-memory index tree' (duration: 561.045305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:50.077147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:49.515744Z","time spent":"561.392903ms","remote":"127.0.0.1:45220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-24T05:23:50.077199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129899023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-24T05:23:50.077216Z","caller":"traceutil/trace.go:171","msg":"trace[1949655229] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1278; }","duration":"1.129965965s","start":"2026-07-24T05:23:48.947245Z","end":"2026-07-24T05:23:50.077211Z","steps":["trace[1949655229] 'range keys from in-memory index tree' (duration: 1.129691189s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:50.07723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:48.947218Z","time spent":"1.130008476s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T05:23:50.077285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.069518ms","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-07-24T05:23:50.077404Z","caller":"traceutil/trace.go:171","msg":"trace[1182894898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1278; }","duration":"616.257563ms","start":"2026-07-24T05:23:49.46112Z","end":"2026-07-24T05:23:50.077378Z","steps":["trace[1182894898] 'range keys from in-memory index tree' (duration: 615.72872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:50.077402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.854313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-24T05:23:50.077464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:49.461093Z","time spent":"616.358966ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-24T05:23:50.077498Z","caller":"traceutil/trace.go:171","msg":"trace[369353237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1278; }","duration":"616.015997ms","start":"2026-07-24T05:23:49.46146Z","end":"2026-07-24T05:23:50.077476Z","steps":["trace[369353237] 'range keys from in-memory index tree' (duration: 615.672119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:50.077554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:49.461439Z","time spent":"616.105299ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T05:23:50.077762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.474838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T05:23:50.07787Z","caller":"traceutil/trace.go:171","msg":"trace[1655995569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1278; }","duration":"616.664392ms","start":"2026-07-24T05:23:49.461181Z","end":"2026-07-24T05:23:50.077845Z","steps":["trace[1655995569] 'range keys from in-memory index tree' (duration: 616.159411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:50.077925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:49.46115Z","time spent":"616.763005ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T05:23:50.881282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.078935ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-24T05:23:50.904537Z","caller":"traceutil/trace.go:171","msg":"trace[1979919315] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"821.91184ms","start":"2026-07-24T05:23:50.082601Z","end":"2026-07-24T05:23:50.904513Z","steps":["trace[1979919315] 'process raft request' (duration: 821.843458ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:23:50.90457Z","caller":"traceutil/trace.go:171","msg":"trace[231141309] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"822.014762ms","start":"2026-07-24T05:23:50.082515Z","end":"2026-07-24T05:23:50.90453Z","steps":["trace[231141309] 'process raft request' (duration: 821.753416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:50.904705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:50.082574Z","time spent":"822.035372ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:23:50.904766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:50.082495Z","time spent":"822.162825ms","remote":"127.0.0.1:45252","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-07-24T05:23:50.904943Z","caller":"traceutil/trace.go:171","msg":"trace[1828644768] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"822.103054ms","start":"2026-07-24T05:23:50.082828Z","end":"2026-07-24T05:23:50.904931Z","steps":["trace[1828644768] 'process raft request' (duration: 821.871698ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:23:50.904936Z","caller":"traceutil/trace.go:171","msg":"trace[160981722] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1336; }","duration":"454.501332ms","start":"2026-07-24T05:23:50.450408Z","end":"2026-07-24T05:23:50.904909Z","steps":["trace[160981722] 'read index received' (duration: 303.118141ms)","trace[160981722] 'applied index is now lower than readState.Index' (duration: 151.381911ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:23:50.904997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:50.082823Z","time spent":"822.144345ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:23:50.905078Z","caller":"traceutil/trace.go:171","msg":"trace[642858621] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"821.423167ms","start":"2026-07-24T05:23:50.083639Z","end":"2026-07-24T05:23:50.905062Z","steps":["trace[642858621] 'process raft request' (duration: 821.161721ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:23:50.905105Z","caller":"traceutil/trace.go:171","msg":"trace[1112172553] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"822.371021ms","start":"2026-07-24T05:23:50.082721Z","end":"2026-07-24T05:23:50.905092Z","steps":["trace[1112172553] 'process raft request' (duration: 821.759466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:50.905203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:50.082712Z","time spent":"822.455293ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:23:50.905239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:50.083631Z","time spent":"821.477448ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:23:50.905339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.933863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:23:50.905377Z","caller":"traceutil/trace.go:171","msg":"trace[474395564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1284; }","duration":"455.023915ms","start":"2026-07-24T05:23:50.450342Z","end":"2026-07-24T05:23:50.905366Z","steps":["trace[474395564] 'agreement among raft nodes before linearized reading' (duration: 454.946833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:50.905398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:50.450316Z","time spent":"455.078536ms","remote":"127.0.0.1:45014","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-07-24T05:23:50.905523Z","caller":"traceutil/trace.go:171","msg":"trace[1435580174] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"187.917813ms","start":"2026-07-24T05:23:50.717598Z","end":"2026-07-24T05:23:50.905515Z","steps":["trace[1435580174] 'process raft request' (duration: 187.260707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:50.905517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.366415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:23:50.905564Z","caller":"traceutil/trace.go:171","msg":"trace[508587299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1284; }","duration":"403.445107ms","start":"2026-07-24T05:23:50.502106Z","end":"2026-07-24T05:23:50.905551Z","steps":["trace[508587299] 'agreement among raft nodes before linearized reading' (duration: 403.363895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:50.905594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:50.502089Z","time spent":"403.498948ms","remote":"127.0.0.1:45016","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-07-24T05:23:51.300094Z","caller":"traceutil/trace.go:171","msg":"trace[358074640] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"109.271979ms","start":"2026-07-24T05:23:51.190772Z","end":"2026-07-24T05:23:51.300044Z","steps":["trace[358074640] 'process raft request' (duration: 84.239344ms)","trace[358074640] 'compare' (duration: 24.774529ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:23:51.473967Z","caller":"traceutil/trace.go:171","msg":"trace[876548476] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"160.787756ms","start":"2026-07-24T05:23:51.313144Z","end":"2026-07-24T05:23:51.473932Z","steps":["trace[876548476] 'process raft request' (duration: 98.762997ms)","trace[876548476] 'compare' (duration: 61.852755ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:23:52.598669Z","caller":"traceutil/trace.go:171","msg":"trace[976371744] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"105.761862ms","start":"2026-07-24T05:23:52.492871Z","end":"2026-07-24T05:23:52.598633Z","steps":["trace[976371744] 'process raft request' (duration: 105.439805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:52.983723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.796311ms","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-07-24T05:23:52.983859Z","caller":"traceutil/trace.go:171","msg":"trace[628471540] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1291; }","duration":"189.957055ms","start":"2026-07-24T05:23:52.79388Z","end":"2026-07-24T05:23:52.983837Z","steps":["trace[628471540] 'range keys from in-memory index tree' (duration: 189.77006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:53.265461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.122924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:69708"} {"level":"info","ts":"2026-07-24T05:23:53.265599Z","caller":"traceutil/trace.go:171","msg":"trace[1309964887] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1297; }","duration":"181.292139ms","start":"2026-07-24T05:23:53.084278Z","end":"2026-07-24T05:23:53.265571Z","steps":["trace[1309964887] 'range keys from in-memory index tree' (duration: 180.695504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:53.265676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.222652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-24T05:23:53.265758Z","caller":"traceutil/trace.go:171","msg":"trace[1725201881] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1297; }","duration":"180.341654ms","start":"2026-07-24T05:23:53.085396Z","end":"2026-07-24T05:23:53.265738Z","steps":["trace[1725201881] 'range keys from in-memory index tree' (duration: 180.110208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:59.469487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.986202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:23:59.469619Z","caller":"traceutil/trace.go:171","msg":"trace[1788515342] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1399; }","duration":"365.875599ms","start":"2026-07-24T05:23:59.103721Z","end":"2026-07-24T05:23:59.469596Z","steps":["trace[1788515342] 'read index received' (duration: 236.712913ms)","trace[1788515342] 'applied index is now lower than readState.Index' (duration: 129.161636ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:23:59.469695Z","caller":"traceutil/trace.go:171","msg":"trace[1547144817] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"376.267518ms","start":"2026-07-24T05:23:59.093374Z","end":"2026-07-24T05:23:59.469641Z","steps":["trace[1547144817] 'process raft request' (duration: 247.014899ms)","trace[1547144817] 'compare' (duration: 128.827638ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:23:59.469864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.031733ms","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-07-24T05:23:59.469919Z","caller":"traceutil/trace.go:171","msg":"trace[1127085857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1340; }","duration":"366.129435ms","start":"2026-07-24T05:23:59.103771Z","end":"2026-07-24T05:23:59.469901Z","steps":["trace[1127085857] 'agreement among raft nodes before linearized reading' (duration: 365.960271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:59.469962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:59.103755Z","time spent":"366.193797ms","remote":"127.0.0.1:45252","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":"warn","ts":"2026-07-24T05:23:59.469832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.151696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-24T05:23:59.470095Z","caller":"traceutil/trace.go:171","msg":"trace[1762070622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1340; }","duration":"366.465034ms","start":"2026-07-24T05:23:59.103618Z","end":"2026-07-24T05:23:59.470083Z","steps":["trace[1762070622] 'agreement among raft nodes before linearized reading' (duration: 366.082464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:59.470106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.877379ms","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-07-24T05:23:59.470131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:59.103584Z","time spent":"366.535616ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T05:23:59.470143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:59.093354Z","time spent":"376.676869ms","remote":"127.0.0.1:45252","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-07-24T05:23:59.470202Z","caller":"traceutil/trace.go:171","msg":"trace[1667434751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1340; }","duration":"366.027583ms","start":"2026-07-24T05:23:59.104148Z","end":"2026-07-24T05:23:59.470176Z","steps":["trace[1667434751] 'agreement among raft nodes before linearized reading' (duration: 365.775817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:59.470204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.820313ms","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":"warn","ts":"2026-07-24T05:23:59.470256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:59.104122Z","time spent":"366.123996ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-24T05:23:59.470322Z","caller":"traceutil/trace.go:171","msg":"trace[393020995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1340; }","duration":"365.014038ms","start":"2026-07-24T05:23:59.105278Z","end":"2026-07-24T05:23:59.470292Z","steps":["trace[393020995] 'agreement among raft nodes before linearized reading' (duration: 364.602748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:59.47041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:59.105251Z","time spent":"365.146111ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-24T05:23:59.759142Z","caller":"traceutil/trace.go:171","msg":"trace[103709925] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"284.042795ms","start":"2026-07-24T05:23:59.475078Z","end":"2026-07-24T05:23:59.759121Z","steps":["trace[103709925] 'process raft request' (duration: 283.881731ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:23:59.818797Z","caller":"traceutil/trace.go:171","msg":"trace[788281467] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"343.487548ms","start":"2026-07-24T05:23:59.47527Z","end":"2026-07-24T05:23:59.818757Z","steps":["trace[788281467] 'process raft request' (duration: 343.238122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:59.819072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:59.475241Z","time spent":"343.667384ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:23:59.819515Z","caller":"traceutil/trace.go:171","msg":"trace[1099775503] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"343.478159ms","start":"2026-07-24T05:23:59.475988Z","end":"2026-07-24T05:23:59.819466Z","steps":["trace[1099775503] 'process raft request' (duration: 343.295404ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:23:59.819552Z","caller":"traceutil/trace.go:171","msg":"trace[138954698] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"343.911659ms","start":"2026-07-24T05:23:59.475598Z","end":"2026-07-24T05:23:59.819509Z","steps":["trace[138954698] 'process raft request' (duration: 343.100429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:23:59.81978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:59.475588Z","time spent":"344.094404ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:23:59.819782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:23:59.475973Z","time spent":"343.640732ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:24:31.391028Z","caller":"traceutil/trace.go:171","msg":"trace[1387392764] transaction","detail":"{read_only:false; response_revision:1504; number_of_response:1; }","duration":"159.345465ms","start":"2026-07-24T05:24:31.23165Z","end":"2026-07-24T05:24:31.390995Z","steps":["trace[1387392764] 'process raft request' (duration: 159.108219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:24:37.01217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.211683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:24:37.01236Z","caller":"traceutil/trace.go:171","msg":"trace[330341437] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"200.261157ms","start":"2026-07-24T05:24:36.812013Z","end":"2026-07-24T05:24:37.012274Z","steps":["trace[330341437] 'process raft request' (duration: 63.835669ms)","trace[330341437] 'compare' (duration: 136.045429ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:24:56.745272Z","caller":"traceutil/trace.go:171","msg":"trace[1959937688] transaction","detail":"{read_only:false; response_revision:1623; number_of_response:1; }","duration":"176.867207ms","start":"2026-07-24T05:24:56.568369Z","end":"2026-07-24T05:24:56.745236Z","steps":["trace[1959937688] 'process raft request' (duration: 176.5876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:24:57.010775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.833047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:24:57.010926Z","caller":"traceutil/trace.go:171","msg":"trace[2136604477] transaction","detail":"{read_only:false; response_revision:1624; number_of_response:1; }","duration":"262.084666ms","start":"2026-07-24T05:24:56.748818Z","end":"2026-07-24T05:24:57.010902Z","steps":["trace[2136604477] 'process raft request' (duration: 105.993062ms)","trace[2136604477] 'compare' (duration: 155.639682ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:24:58.321919Z","caller":"traceutil/trace.go:171","msg":"trace[1193975413] transaction","detail":"{read_only:false; response_revision:1630; number_of_response:1; }","duration":"114.427524ms","start":"2026-07-24T05:24:58.207427Z","end":"2026-07-24T05:24:58.321854Z","steps":["trace[1193975413] 'process raft request' (duration: 27.621257ms)","trace[1193975413] 'compare' (duration: 86.558321ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:25:02.195836Z","caller":"traceutil/trace.go:171","msg":"trace[1500414500] linearizableReadLoop","detail":"{readStateIndex:1717; appliedIndex:1716; }","duration":"178.920689ms","start":"2026-07-24T05:25:02.016885Z","end":"2026-07-24T05:25:02.195806Z","steps":["trace[1500414500] 'read index received' (duration: 178.659542ms)","trace[1500414500] 'applied index is now lower than readState.Index' (duration: 260.127µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:25:02.195934Z","caller":"traceutil/trace.go:171","msg":"trace[93873068] transaction","detail":"{read_only:false; response_revision:1644; number_of_response:1; }","duration":"207.293734ms","start":"2026-07-24T05:25:01.988585Z","end":"2026-07-24T05:25:02.195879Z","steps":["trace[93873068] 'process raft request' (duration: 207.038757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:02.196167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.254508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-24T05:25:02.196216Z","caller":"traceutil/trace.go:171","msg":"trace[1669122779] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1644; }","duration":"179.364401ms","start":"2026-07-24T05:25:02.016836Z","end":"2026-07-24T05:25:02.1962Z","steps":["trace[1669122779] 'agreement among raft nodes before linearized reading' (duration: 179.138765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:02.587288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.392045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:25:02.587585Z","caller":"traceutil/trace.go:171","msg":"trace[1423337598] linearizableReadLoop","detail":"{readStateIndex:1719; appliedIndex:1717; }","duration":"378.992552ms","start":"2026-07-24T05:25:02.208573Z","end":"2026-07-24T05:25:02.587566Z","steps":["trace[1423337598] 'read index received' (duration: 52.170965ms)","trace[1423337598] 'applied index is now lower than readState.Index' (duration: 326.820417ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:25:02.587853Z","caller":"traceutil/trace.go:171","msg":"trace[1429871861] transaction","detail":"{read_only:false; response_revision:1645; number_of_response:1; }","duration":"385.878595ms","start":"2026-07-24T05:25:02.20193Z","end":"2026-07-24T05:25:02.587809Z","steps":["trace[1429871861] 'process raft request' (duration: 58.853283ms)","trace[1429871861] 'compare' (duration: 326.074328ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:25:02.588013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.984795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-24T05:25:02.588007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.432293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-24T05:25:02.588068Z","caller":"traceutil/trace.go:171","msg":"trace[1973069593] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1646; }","duration":"247.124358ms","start":"2026-07-24T05:25:02.34093Z","end":"2026-07-24T05:25:02.588055Z","steps":["trace[1973069593] 'agreement among raft nodes before linearized reading' (duration: 246.943064ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:25:02.588073Z","caller":"traceutil/trace.go:171","msg":"trace[1354233534] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1646; }","duration":"379.539495ms","start":"2026-07-24T05:25:02.20852Z","end":"2026-07-24T05:25:02.588059Z","steps":["trace[1354233534] 'agreement among raft nodes before linearized reading' (duration: 379.366641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:02.588112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:25:02.208494Z","time spent":"379.608958ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-24T05:25:02.588275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.107907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T05:25:02.58831Z","caller":"traceutil/trace.go:171","msg":"trace[1538477546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1646; }","duration":"243.184058ms","start":"2026-07-24T05:25:02.345117Z","end":"2026-07-24T05:25:02.588301Z","steps":["trace[1538477546] 'agreement among raft nodes before linearized reading' (duration: 243.088516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:02.588313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:25:02.201901Z","time spent":"386.05441ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:25:02.588468Z","caller":"traceutil/trace.go:171","msg":"trace[765604416] transaction","detail":"{read_only:false; response_revision:1646; number_of_response:1; }","duration":"385.096615ms","start":"2026-07-24T05:25:02.202594Z","end":"2026-07-24T05:25:02.58769Z","steps":["trace[765604416] 'process raft request' (duration: 384.849568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:02.588545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:25:02.202567Z","time spent":"385.942627ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:25:02.588903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.223034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-24T05:25:02.588991Z","caller":"traceutil/trace.go:171","msg":"trace[1679901503] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1646; }","duration":"238.364557ms","start":"2026-07-24T05:25:02.350605Z","end":"2026-07-24T05:25:02.588969Z","steps":["trace[1679901503] 'agreement among raft nodes before linearized reading' (duration: 237.331301ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:25:06.890661Z","caller":"traceutil/trace.go:171","msg":"trace[399839039] transaction","detail":"{read_only:false; response_revision:1666; number_of_response:1; }","duration":"141.019695ms","start":"2026-07-24T05:25:06.749608Z","end":"2026-07-24T05:25:06.890628Z","steps":["trace[399839039] 'process raft request' (duration: 113.101472ms)","trace[399839039] 'compare' (duration: 27.79688ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:25:06.899907Z","caller":"traceutil/trace.go:171","msg":"trace[893625969] transaction","detail":"{read_only:false; response_revision:1668; number_of_response:1; }","duration":"149.715344ms","start":"2026-07-24T05:25:06.750144Z","end":"2026-07-24T05:25:06.899859Z","steps":["trace[893625969] 'process raft request' (duration: 149.58043ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:25:06.900023Z","caller":"traceutil/trace.go:171","msg":"trace[1754870735] transaction","detail":"{read_only:false; response_revision:1667; number_of_response:1; }","duration":"149.95455ms","start":"2026-07-24T05:25:06.75003Z","end":"2026-07-24T05:25:06.899985Z","steps":["trace[1754870735] 'process raft request' (duration: 149.56727ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:25:07.942042Z","caller":"traceutil/trace.go:171","msg":"trace[1801635243] transaction","detail":"{read_only:false; response_revision:1672; number_of_response:1; }","duration":"268.472336ms","start":"2026-07-24T05:25:07.673526Z","end":"2026-07-24T05:25:07.941999Z","steps":["trace[1801635243] 'process raft request' (duration: 188.210033ms)","trace[1801635243] 'compare' (duration: 80.13375ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:25:07.986337Z","caller":"traceutil/trace.go:171","msg":"trace[924766805] transaction","detail":"{read_only:false; response_revision:1673; number_of_response:1; }","duration":"269.291696ms","start":"2026-07-24T05:25:07.717026Z","end":"2026-07-24T05:25:07.986317Z","steps":["trace[924766805] 'process raft request' (duration: 269.186684ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:25:17.178872Z","caller":"traceutil/trace.go:171","msg":"trace[1905596490] linearizableReadLoop","detail":"{readStateIndex:1788; appliedIndex:1787; }","duration":"224.300586ms","start":"2026-07-24T05:25:16.954536Z","end":"2026-07-24T05:25:17.178836Z","steps":["trace[1905596490] 'read index received' (duration: 223.949408ms)","trace[1905596490] 'applied index is now lower than readState.Index' (duration: 349.648µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:25:17.179391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.931587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-24T05:25:17.179381Z","caller":"traceutil/trace.go:171","msg":"trace[456673922] transaction","detail":"{read_only:false; response_revision:1712; number_of_response:1; }","duration":"239.208261ms","start":"2026-07-24T05:25:16.940128Z","end":"2026-07-24T05:25:17.179336Z","steps":["trace[456673922] 'process raft request' (duration: 238.457912ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:25:17.179447Z","caller":"traceutil/trace.go:171","msg":"trace[807905962] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1712; }","duration":"216.030509ms","start":"2026-07-24T05:25:16.963402Z","end":"2026-07-24T05:25:17.179433Z","steps":["trace[807905962] 'agreement among raft nodes before linearized reading' (duration: 215.799043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:17.179405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.83423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T05:25:17.179611Z","caller":"traceutil/trace.go:171","msg":"trace[232050599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1712; }","duration":"225.101506ms","start":"2026-07-24T05:25:16.95447Z","end":"2026-07-24T05:25:17.179572Z","steps":["trace[232050599] 'agreement among raft nodes before linearized reading' (duration: 224.674576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:17.179637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.068829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-24T05:25:17.179753Z","caller":"traceutil/trace.go:171","msg":"trace[107645986] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1712; }","duration":"208.239833ms","start":"2026-07-24T05:25:16.971488Z","end":"2026-07-24T05:25:17.179728Z","steps":["trace[107645986] 'agreement among raft nodes before linearized reading' (duration: 207.864214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:18.029803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.592037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:25:18.029929Z","caller":"traceutil/trace.go:171","msg":"trace[1393674292] linearizableReadLoop","detail":"{readStateIndex:1793; appliedIndex:1792; }","duration":"105.016844ms","start":"2026-07-24T05:25:17.924893Z","end":"2026-07-24T05:25:18.029909Z","steps":["trace[1393674292] 'read index received' (duration: 53.021µs)","trace[1393674292] 'applied index is now lower than readState.Index' (duration: 104.962703ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:25:18.03016Z","caller":"traceutil/trace.go:171","msg":"trace[984803820] transaction","detail":"{read_only:false; response_revision:1717; number_of_response:1; }","duration":"321.157946ms","start":"2026-07-24T05:25:17.708987Z","end":"2026-07-24T05:25:18.030145Z","steps":["trace[984803820] 'process raft request' (duration: 117.119238ms)","trace[984803820] 'compare' (duration: 203.475174ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:25:18.030274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:25:17.708953Z","time spent":"321.28594ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:25:18.030394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.452336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:25:18.030512Z","caller":"traceutil/trace.go:171","msg":"trace[685291237] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1717; }","duration":"105.660141ms","start":"2026-07-24T05:25:17.924828Z","end":"2026-07-24T05:25:18.030488Z","steps":["trace[685291237] 'agreement among raft nodes before linearized reading' (duration: 105.429775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:18.069007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.742118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-07-24T05:25:18.069091Z","caller":"traceutil/trace.go:171","msg":"trace[1332324701] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1718; }","duration":"112.862251ms","start":"2026-07-24T05:25:17.956206Z","end":"2026-07-24T05:25:18.069068Z","steps":["trace[1332324701] 'agreement among raft nodes before linearized reading' (duration: 112.607705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:18.471555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.713427ms","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-07-24T05:25:18.471655Z","caller":"traceutil/trace.go:171","msg":"trace[152087832] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1719; }","duration":"332.894121ms","start":"2026-07-24T05:25:18.138737Z","end":"2026-07-24T05:25:18.471631Z","steps":["trace[152087832] 'count revisions from in-memory index tree' (duration: 332.617494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:18.472237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:25:18.138713Z","time spent":"333.340402ms","remote":"127.0.0.1:55838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-07-24T05:25:18.623086Z","caller":"traceutil/trace.go:171","msg":"trace[1361485636] linearizableReadLoop","detail":"{readStateIndex:1796; appliedIndex:1795; }","duration":"121.066908ms","start":"2026-07-24T05:25:18.501994Z","end":"2026-07-24T05:25:18.62306Z","steps":["trace[1361485636] 'read index received' (duration: 120.908604ms)","trace[1361485636] 'applied index is now lower than readState.Index' (duration: 157.334µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:25:18.623272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.252902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:25:18.623316Z","caller":"traceutil/trace.go:171","msg":"trace[1210579200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1720; }","duration":"121.350175ms","start":"2026-07-24T05:25:18.501941Z","end":"2026-07-24T05:25:18.623291Z","steps":["trace[1210579200] 'agreement among raft nodes before linearized reading' (duration: 121.211381ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:25:18.623611Z","caller":"traceutil/trace.go:171","msg":"trace[777493563] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"147.032869ms","start":"2026-07-24T05:25:18.476538Z","end":"2026-07-24T05:25:18.623571Z","steps":["trace[777493563] 'process raft request' (duration: 146.387333ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:25:19.423646Z","caller":"traceutil/trace.go:171","msg":"trace[1380178000] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"162.166147ms","start":"2026-07-24T05:25:19.261443Z","end":"2026-07-24T05:25:19.423609Z","steps":["trace[1380178000] 'process raft request' (duration: 87.405092ms)","trace[1380178000] 'compare' (duration: 74.637312ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:25:19.453236Z","caller":"traceutil/trace.go:171","msg":"trace[2111820485] linearizableReadLoop","detail":"{readStateIndex:1801; appliedIndex:1798; }","duration":"135.461928ms","start":"2026-07-24T05:25:19.317747Z","end":"2026-07-24T05:25:19.453209Z","steps":["trace[2111820485] 'read index received' (duration: 31.189383ms)","trace[2111820485] 'applied index is now lower than readState.Index' (duration: 104.271525ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:25:19.453373Z","caller":"traceutil/trace.go:171","msg":"trace[1829295759] transaction","detail":"{read_only:false; response_revision:1725; number_of_response:1; }","duration":"190.783155ms","start":"2026-07-24T05:25:19.262556Z","end":"2026-07-24T05:25:19.453339Z","steps":["trace[1829295759] 'process raft request' (duration: 190.610471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:19.453542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.727474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:25:19.453658Z","caller":"traceutil/trace.go:171","msg":"trace[1108218294] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1725; }","duration":"135.909459ms","start":"2026-07-24T05:25:19.317704Z","end":"2026-07-24T05:25:19.453613Z","steps":["trace[1108218294] 'agreement among raft nodes before linearized reading' (duration: 135.677243ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:25:19.453921Z","caller":"traceutil/trace.go:171","msg":"trace[360043831] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"191.34525ms","start":"2026-07-24T05:25:19.261923Z","end":"2026-07-24T05:25:19.453269Z","steps":["trace[360043831] 'process raft request' (duration: 191.079563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:20.772057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.188471ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-24T05:25:20.772199Z","caller":"traceutil/trace.go:171","msg":"trace[1217056206] linearizableReadLoop","detail":"{readStateIndex:1803; appliedIndex:1802; }","duration":"379.557842ms","start":"2026-07-24T05:25:20.39262Z","end":"2026-07-24T05:25:20.772178Z","steps":["trace[1217056206] 'read index received' (duration: 75.052323ms)","trace[1217056206] 'applied index is now lower than readState.Index' (duration: 304.503909ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:25:20.77248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.846688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-24T05:25:20.772529Z","caller":"traceutil/trace.go:171","msg":"trace[1674134731] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1726; }","duration":"379.971112ms","start":"2026-07-24T05:25:20.392544Z","end":"2026-07-24T05:25:20.772515Z","steps":["trace[1674134731] 'agreement among raft nodes before linearized reading' (duration: 379.769607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:20.772569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:25:20.392521Z","time spent":"380.038313ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-24T05:25:20.773168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.826064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:25:20.77321Z","caller":"traceutil/trace.go:171","msg":"trace[1407425335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1726; }","duration":"270.911296ms","start":"2026-07-24T05:25:20.502288Z","end":"2026-07-24T05:25:20.773199Z","steps":["trace[1407425335] 'agreement among raft nodes before linearized reading' (duration: 270.846304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:20.773446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.805058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T05:25:20.773483Z","caller":"traceutil/trace.go:171","msg":"trace[2039514935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1726; }","duration":"145.88302ms","start":"2026-07-24T05:25:20.627591Z","end":"2026-07-24T05:25:20.773474Z","steps":["trace[2039514935] 'agreement among raft nodes before linearized reading' (duration: 145.787437ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:25:21.005286Z","caller":"traceutil/trace.go:171","msg":"trace[13797372] linearizableReadLoop","detail":"{readStateIndex:1805; appliedIndex:1804; }","duration":"201.696629ms","start":"2026-07-24T05:25:20.803564Z","end":"2026-07-24T05:25:21.00526Z","steps":["trace[13797372] 'read index received' (duration: 201.420983ms)","trace[13797372] 'applied index is now lower than readState.Index' (duration: 274.296µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:25:21.005349Z","caller":"traceutil/trace.go:171","msg":"trace[873047436] transaction","detail":"{read_only:false; response_revision:1728; number_of_response:1; }","duration":"225.767284ms","start":"2026-07-24T05:25:20.77954Z","end":"2026-07-24T05:25:21.005307Z","steps":["trace[873047436] 'process raft request' (duration: 225.555208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:21.005681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.09875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-24T05:25:21.005776Z","caller":"traceutil/trace.go:171","msg":"trace[256904886] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1728; }","duration":"202.267394ms","start":"2026-07-24T05:25:20.803493Z","end":"2026-07-24T05:25:21.005761Z","steps":["trace[256904886] 'agreement among raft nodes before linearized reading' (duration: 201.908455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:25:21.189246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.851212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:25:21.189403Z","caller":"traceutil/trace.go:171","msg":"trace[1421505561] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"177.825481ms","start":"2026-07-24T05:25:21.011553Z","end":"2026-07-24T05:25:21.189379Z","steps":["trace[1421505561] 'process raft request' (duration: 58.688472ms)","trace[1421505561] 'compare' (duration: 118.591946ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:25:21.579184Z","caller":"traceutil/trace.go:171","msg":"trace[576328041] transaction","detail":"{read_only:false; response_revision:1733; number_of_response:1; }","duration":"117.325453ms","start":"2026-07-24T05:25:21.46182Z","end":"2026-07-24T05:25:21.579145Z","steps":["trace[576328041] 'process raft request' (duration: 33.402328ms)","trace[576328041] 'compare' (duration: 83.778992ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:25:21.618799Z","caller":"traceutil/trace.go:171","msg":"trace[1792008432] transaction","detail":"{read_only:false; response_revision:1734; number_of_response:1; }","duration":"155.795378ms","start":"2026-07-24T05:25:21.462689Z","end":"2026-07-24T05:25:21.618485Z","steps":["trace[1792008432] 'process raft request' (duration: 155.653505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:24.233825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.165643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T05:27:24.233979Z","caller":"traceutil/trace.go:171","msg":"trace[2137985084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2292; }","duration":"220.397538ms","start":"2026-07-24T05:27:24.013554Z","end":"2026-07-24T05:27:24.233951Z","steps":["trace[2137985084] 'range keys from in-memory index tree' (duration: 219.897234ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:27:24.600682Z","caller":"traceutil/trace.go:171","msg":"trace[351660430] linearizableReadLoop","detail":"{readStateIndex:2395; appliedIndex:2394; }","duration":"346.058646ms","start":"2026-07-24T05:27:24.254582Z","end":"2026-07-24T05:27:24.60064Z","steps":["trace[351660430] 'read index received' (duration: 345.660066ms)","trace[351660430] 'applied index is now lower than readState.Index' (duration: 396.96µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:27:24.601138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.521518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-24T05:27:24.601228Z","caller":"traceutil/trace.go:171","msg":"trace[642784481] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2294; }","duration":"346.662281ms","start":"2026-07-24T05:27:24.254539Z","end":"2026-07-24T05:27:24.601201Z","steps":["trace[642784481] 'agreement among raft nodes before linearized reading' (duration: 346.425026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:24.601269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:27:24.254523Z","time spent":"346.736963ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-24T05:27:24.601027Z","caller":"traceutil/trace.go:171","msg":"trace[1885751870] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"361.34914ms","start":"2026-07-24T05:27:24.239655Z","end":"2026-07-24T05:27:24.601004Z","steps":["trace[1885751870] 'process raft request' (duration: 360.568239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:24.60171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:27:24.239622Z","time spent":"362.008506ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:27:24.601791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.467167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T05:27:24.601843Z","caller":"traceutil/trace.go:171","msg":"trace[404140484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2295; }","duration":"346.544598ms","start":"2026-07-24T05:27:24.255282Z","end":"2026-07-24T05:27:24.601827Z","steps":["trace[404140484] 'agreement among raft nodes before linearized reading' (duration: 346.343923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:24.601874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:27:24.255269Z","time spent":"346.59807ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T05:27:31.625418Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356766940767887409,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T05:27:32.126184Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356766940767887409,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T05:27:32.139095Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.200217891s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-24T05:27:32.143877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.481730549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T05:27:32.143973Z","caller":"traceutil/trace.go:171","msg":"trace[1192426638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2321; }","duration":"1.481857072s","start":"2026-07-24T05:27:30.662094Z","end":"2026-07-24T05:27:32.143951Z","steps":["trace[1192426638] 'range keys from in-memory index tree' (duration: 1.481549365s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:32.144016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:27:30.662078Z","time spent":"1.481928034s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T05:27:32.144467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.484749045s","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-07-24T05:27:32.144499Z","caller":"traceutil/trace.go:171","msg":"trace[1384906682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2321; }","duration":"1.484829507s","start":"2026-07-24T05:27:30.65966Z","end":"2026-07-24T05:27:32.144489Z","steps":["trace[1384906682] 'range keys from in-memory index tree' (duration: 1.484583411s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:32.144528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:27:30.65963Z","time spent":"1.484891488s","remote":"127.0.0.1:45252","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-07-24T05:27:32.144507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482428917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-24T05:27:32.144578Z","caller":"traceutil/trace.go:171","msg":"trace[1808987631] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2321; }","duration":"1.482526189s","start":"2026-07-24T05:27:30.66203Z","end":"2026-07-24T05:27:32.144556Z","steps":["trace[1808987631] 'range keys from in-memory index tree' (duration: 1.482247052s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:32.144614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:27:30.662018Z","time spent":"1.482587011s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-24T05:27:32.144614Z","caller":"traceutil/trace.go:171","msg":"trace[1320841370] linearizableReadLoop","detail":"{readStateIndex:2425; appliedIndex:2424; }","duration":"1.019775657s","start":"2026-07-24T05:27:31.124785Z","end":"2026-07-24T05:27:32.144561Z","steps":["trace[1320841370] 'read index received' (duration: 1.014423744s)","trace[1320841370] 'applied index is now lower than readState.Index' (duration: 5.349953ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:27:32.144713Z","caller":"traceutil/trace.go:171","msg":"trace[1909597884] transaction","detail":"{read_only:false; response_revision:2322; number_of_response:1; }","duration":"1.205985785s","start":"2026-07-24T05:27:30.938692Z","end":"2026-07-24T05:27:32.144678Z","steps":["trace[1909597884] 'process raft request' (duration: 1.200672703s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:32.144787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019997763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-07-24T05:27:32.144825Z","caller":"traceutil/trace.go:171","msg":"trace[1941974087] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2322; }","duration":"1.020091087s","start":"2026-07-24T05:27:31.124719Z","end":"2026-07-24T05:27:32.14481Z","steps":["trace[1941974087] 'agreement among raft nodes before linearized reading' (duration: 1.019994243s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:32.144851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:27:31.124698Z","time spent":"1.020145777s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":547,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-24T05:27:32.1449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:27:30.937815Z","time spent":"1.206989782s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:27:32.144967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.451269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-24T05:27:32.144981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.501643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-24T05:27:32.145009Z","caller":"traceutil/trace.go:171","msg":"trace[1514829820] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2322; }","duration":"378.50942ms","start":"2026-07-24T05:27:31.766484Z","end":"2026-07-24T05:27:32.144994Z","steps":["trace[1514829820] 'agreement among raft nodes before linearized reading' (duration: 378.409218ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:27:32.145035Z","caller":"traceutil/trace.go:171","msg":"trace[236565856] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2322; }","duration":"698.618626ms","start":"2026-07-24T05:27:31.446396Z","end":"2026-07-24T05:27:32.145014Z","steps":["trace[236565856] 'agreement among raft nodes before linearized reading' (duration: 698.370249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:32.14504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:27:31.766471Z","time spent":"378.562972ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T05:27:32.145051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.474967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T05:27:32.145083Z","caller":"traceutil/trace.go:171","msg":"trace[1359553631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2322; }","duration":"431.55202ms","start":"2026-07-24T05:27:31.713523Z","end":"2026-07-24T05:27:32.145075Z","steps":["trace[1359553631] 'agreement among raft nodes before linearized reading' (duration: 431.461948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:32.145082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:27:31.446358Z","time spent":"698.714038ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-24T05:27:32.14511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:27:31.713504Z","time spent":"431.599921ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T05:27:32.145145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.650094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T05:27:32.145172Z","caller":"traceutil/trace.go:171","msg":"trace[1926064543] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2322; }","duration":"181.680855ms","start":"2026-07-24T05:27:31.963484Z","end":"2026-07-24T05:27:32.145165Z","steps":["trace[1926064543] 'agreement among raft nodes before linearized reading' (duration: 181.630464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:32.145248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.523749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T05:27:32.145241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.903712ms","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-07-24T05:27:32.145279Z","caller":"traceutil/trace.go:171","msg":"trace[938889790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2322; }","duration":"646.574181ms","start":"2026-07-24T05:27:31.498695Z","end":"2026-07-24T05:27:32.145269Z","steps":["trace[938889790] 'agreement among raft nodes before linearized reading' (duration: 646.51862ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:27:32.145291Z","caller":"traceutil/trace.go:171","msg":"trace[912994137] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2322; }","duration":"398.984874ms","start":"2026-07-24T05:27:31.746291Z","end":"2026-07-24T05:27:32.145276Z","steps":["trace[912994137] 'agreement among raft nodes before linearized reading' (duration: 398.909062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:32.145306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:27:31.498675Z","time spent":"646.613122ms","remote":"127.0.0.1:45014","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-07-24T05:27:32.145328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:27:31.746273Z","time spent":"399.046155ms","remote":"127.0.0.1:55838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-07-24T05:27:36.311642Z","caller":"traceutil/trace.go:171","msg":"trace[1723431207] transaction","detail":"{read_only:false; response_revision:2341; number_of_response:1; }","duration":"145.868167ms","start":"2026-07-24T05:27:36.165754Z","end":"2026-07-24T05:27:36.311622Z","steps":["trace[1723431207] 'process raft request' (duration: 145.429536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:58.736669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.357797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T05:27:58.736766Z","caller":"traceutil/trace.go:171","msg":"trace[342816175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2445; }","duration":"187.46489ms","start":"2026-07-24T05:27:58.54928Z","end":"2026-07-24T05:27:58.736745Z","steps":["trace[342816175] 'range keys from in-memory index tree' (duration: 187.244495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:58.736914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.781949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-24T05:27:58.737007Z","caller":"traceutil/trace.go:171","msg":"trace[1826063699] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2445; }","duration":"194.305332ms","start":"2026-07-24T05:27:58.542686Z","end":"2026-07-24T05:27:58.736992Z","steps":["trace[1826063699] 'range keys from in-memory index tree' (duration: 193.633934ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:28:04.93157Z","caller":"traceutil/trace.go:171","msg":"trace[1760990682] transaction","detail":"{read_only:false; response_revision:2473; number_of_response:1; }","duration":"114.703385ms","start":"2026-07-24T05:28:04.81684Z","end":"2026-07-24T05:28:04.931543Z","steps":["trace[1760990682] 'process raft request' (duration: 93.907104ms)","trace[1760990682] 'compare' (duration: 20.643328ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:28:04.931845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.210305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T05:28:04.931898Z","caller":"traceutil/trace.go:171","msg":"trace[25281969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2474; }","duration":"136.270676ms","start":"2026-07-24T05:28:04.795614Z","end":"2026-07-24T05:28:04.931885Z","steps":["trace[25281969] 'agreement among raft nodes before linearized reading' (duration: 136.186494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:28:04.931905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.096412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-24T05:28:04.931932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.169948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-24T05:28:04.931955Z","caller":"traceutil/trace.go:171","msg":"trace[1756268585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2474; }","duration":"136.181723ms","start":"2026-07-24T05:28:04.795761Z","end":"2026-07-24T05:28:04.931942Z","steps":["trace[1756268585] 'agreement among raft nodes before linearized reading' (duration: 136.027939ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:28:04.931996Z","caller":"traceutil/trace.go:171","msg":"trace[2131507848] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2474; }","duration":"135.253151ms","start":"2026-07-24T05:28:04.796725Z","end":"2026-07-24T05:28:04.931978Z","steps":["trace[2131507848] 'agreement among raft nodes before linearized reading' (duration: 135.075257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:28:04.931774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.876881ms","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-07-24T05:28:04.932359Z","caller":"traceutil/trace.go:171","msg":"trace[1765416544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2474; }","duration":"139.479857ms","start":"2026-07-24T05:28:04.792868Z","end":"2026-07-24T05:28:04.932348Z","steps":["trace[1765416544] 'agreement among raft nodes before linearized reading' (duration: 138.744988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:28:20.137664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.928626ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-24T05:28:29.336085Z","caller":"traceutil/trace.go:171","msg":"trace[959347445] linearizableReadLoop","detail":"{readStateIndex:2699; appliedIndex:2698; }","duration":"219.879521ms","start":"2026-07-24T05:28:29.116185Z","end":"2026-07-24T05:28:29.336064Z","steps":["trace[959347445] 'read index received' (duration: 219.609005ms)","trace[959347445] 'applied index is now lower than readState.Index' (duration: 269.686µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:28:29.33631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.106687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-24T05:28:29.33615Z","caller":"traceutil/trace.go:171","msg":"trace[1725218595] transaction","detail":"{read_only:false; response_revision:2585; number_of_response:1; }","duration":"225.786818ms","start":"2026-07-24T05:28:29.110324Z","end":"2026-07-24T05:28:29.336111Z","steps":["trace[1725218595] 'process raft request' (duration: 225.617884ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:28:29.336426Z","caller":"traceutil/trace.go:171","msg":"trace[1460861677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2585; }","duration":"220.21524ms","start":"2026-07-24T05:28:29.116144Z","end":"2026-07-24T05:28:29.336359Z","steps":["trace[1460861677] 'agreement among raft nodes before linearized reading' (duration: 220.026645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:28:29.33645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.505654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-07-24T05:28:29.336487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.246748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T05:28:29.336507Z","caller":"traceutil/trace.go:171","msg":"trace[583229125] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2585; }","duration":"138.598027ms","start":"2026-07-24T05:28:29.197891Z","end":"2026-07-24T05:28:29.336489Z","steps":["trace[583229125] 'agreement among raft nodes before linearized reading' (duration: 138.400382ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:28:29.336548Z","caller":"traceutil/trace.go:171","msg":"trace[1435939068] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2585; }","duration":"134.31208ms","start":"2026-07-24T05:28:29.202223Z","end":"2026-07-24T05:28:29.336535Z","steps":["trace[1435939068] 'agreement among raft nodes before linearized reading' (duration: 134.142956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:28:29.337099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.660808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-24T05:28:29.337192Z","caller":"traceutil/trace.go:171","msg":"trace[586124279] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2585; }","duration":"116.788581ms","start":"2026-07-24T05:28:29.220381Z","end":"2026-07-24T05:28:29.33717Z","steps":["trace[586124279] 'agreement among raft nodes before linearized reading' (duration: 116.527914ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:28:55.723782Z","caller":"traceutil/trace.go:171","msg":"trace[1488417844] transaction","detail":"{read_only:false; response_revision:2704; number_of_response:1; }","duration":"181.11184ms","start":"2026-07-24T05:28:55.542632Z","end":"2026-07-24T05:28:55.723744Z","steps":["trace[1488417844] 'process raft request' (duration: 180.957676ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:28:55.72381Z","caller":"traceutil/trace.go:171","msg":"trace[1943291073] linearizableReadLoop","detail":"{readStateIndex:2824; appliedIndex:2823; }","duration":"140.340481ms","start":"2026-07-24T05:28:55.583423Z","end":"2026-07-24T05:28:55.723763Z","steps":["trace[1943291073] 'read index received' (duration: 140.122675ms)","trace[1943291073] 'applied index is now lower than readState.Index' (duration: 210.055µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:28:55.745044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.029528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T05:28:55.745166Z","caller":"traceutil/trace.go:171","msg":"trace[554315494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2704; }","duration":"145.205403ms","start":"2026-07-24T05:28:55.599936Z","end":"2026-07-24T05:28:55.745141Z","steps":["trace[554315494] 'agreement among raft nodes before linearized reading' (duration: 124.240469ms)","trace[554315494] 'range keys from in-memory index tree' (duration: 20.664277ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:28:55.745187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.747267ms","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-07-24T05:28:55.745268Z","caller":"traceutil/trace.go:171","msg":"trace[2053248350] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2704; }","duration":"161.91872ms","start":"2026-07-24T05:28:55.583326Z","end":"2026-07-24T05:28:55.745245Z","steps":["trace[2053248350] 'agreement among raft nodes before linearized reading' (duration: 140.557346ms)","trace[2053248350] 'range keys from in-memory index tree' (duration: 21.113599ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:29:05.518847Z","caller":"traceutil/trace.go:171","msg":"trace[1176388316] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"137.405247ms","start":"2026-07-24T05:29:05.381393Z","end":"2026-07-24T05:29:05.518798Z","steps":["trace[1176388316] 'process raft request' (duration: 137.018558ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:29:12.129464Z","caller":"traceutil/trace.go:171","msg":"trace[1601789434] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"103.393897ms","start":"2026-07-24T05:29:12.026036Z","end":"2026-07-24T05:29:12.12943Z","steps":["trace[1601789434] 'process raft request' (duration: 102.803402ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:29:12.601469Z","caller":"traceutil/trace.go:171","msg":"trace[1917817799] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"243.235225ms","start":"2026-07-24T05:29:12.358174Z","end":"2026-07-24T05:29:12.60141Z","steps":["trace[1917817799] 'process raft request' (duration: 242.872426ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:29:12.636351Z","caller":"traceutil/trace.go:171","msg":"trace[1690164543] linearizableReadLoop","detail":"{readStateIndex:2909; appliedIndex:2906; }","duration":"193.772399ms","start":"2026-07-24T05:29:12.442554Z","end":"2026-07-24T05:29:12.636326Z","steps":["trace[1690164543] 'read index received' (duration: 158.522297ms)","trace[1690164543] 'applied index is now lower than readState.Index' (duration: 35.248732ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:29:12.63649Z","caller":"traceutil/trace.go:171","msg":"trace[1852648428] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"200.044095ms","start":"2026-07-24T05:29:12.436435Z","end":"2026-07-24T05:29:12.636479Z","steps":["trace[1852648428] 'process raft request' (duration: 199.824789ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:29:12.636654Z","caller":"traceutil/trace.go:171","msg":"trace[1343186061] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"278.239802ms","start":"2026-07-24T05:29:12.358391Z","end":"2026-07-24T05:29:12.63663Z","steps":["trace[1343186061] 'process raft request' (duration: 277.267797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:12.637131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.600692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-07-24T05:29:12.637152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.575379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-24T05:29:12.637186Z","caller":"traceutil/trace.go:171","msg":"trace[2063530042] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2785; }","duration":"163.725566ms","start":"2026-07-24T05:29:12.473446Z","end":"2026-07-24T05:29:12.637171Z","steps":["trace[2063530042] 'agreement among raft nodes before linearized reading' (duration: 163.542381ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:29:12.637204Z","caller":"traceutil/trace.go:171","msg":"trace[1301397096] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2785; }","duration":"194.65777ms","start":"2026-07-24T05:29:12.442528Z","end":"2026-07-24T05:29:12.637186Z","steps":["trace[1301397096] 'agreement among raft nodes before linearized reading' (duration: 194.24286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:12.640123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.686889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:29:12.640181Z","caller":"traceutil/trace.go:171","msg":"trace[1724778104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2785; }","duration":"138.790043ms","start":"2026-07-24T05:29:12.501378Z","end":"2026-07-24T05:29:12.640168Z","steps":["trace[1724778104] 'agreement among raft nodes before linearized reading' (duration: 135.932001ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:29:13.139862Z","caller":"traceutil/trace.go:171","msg":"trace[1794228245] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"103.613043ms","start":"2026-07-24T05:29:13.036207Z","end":"2026-07-24T05:29:13.139821Z","steps":["trace[1794228245] 'process raft request' (duration: 103.488209ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:29:13.378046Z","caller":"traceutil/trace.go:171","msg":"trace[223011008] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"232.599419ms","start":"2026-07-24T05:29:13.145401Z","end":"2026-07-24T05:29:13.378Z","steps":["trace[223011008] 'process raft request' (duration: 189.951092ms)","trace[223011008] 'compare' (duration: 42.443062ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:29:13.406124Z","caller":"traceutil/trace.go:171","msg":"trace[83237004] linearizableReadLoop","detail":"{readStateIndex:2916; appliedIndex:2914; }","duration":"258.905987ms","start":"2026-07-24T05:29:13.147194Z","end":"2026-07-24T05:29:13.4061Z","steps":["trace[83237004] 'read index received' (duration: 188.164167ms)","trace[83237004] 'applied index is now lower than readState.Index' (duration: 70.741ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:29:13.406264Z","caller":"traceutil/trace.go:171","msg":"trace[627681607] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"259.179534ms","start":"2026-07-24T05:29:13.147072Z","end":"2026-07-24T05:29:13.406252Z","steps":["trace[627681607] 'process raft request' (duration: 258.827125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:13.406681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.470462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1948"} {"level":"info","ts":"2026-07-24T05:29:13.406791Z","caller":"traceutil/trace.go:171","msg":"trace[379792995] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2792; }","duration":"259.619146ms","start":"2026-07-24T05:29:13.147155Z","end":"2026-07-24T05:29:13.406775Z","steps":["trace[379792995] 'agreement among raft nodes before linearized reading' (duration: 259.441641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:23.436046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356766940767888683,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T05:29:23.46619Z","caller":"traceutil/trace.go:171","msg":"trace[980985692] transaction","detail":"{read_only:false; response_revision:2851; number_of_response:1; }","duration":"781.371638ms","start":"2026-07-24T05:29:22.684798Z","end":"2026-07-24T05:29:23.46617Z","steps":["trace[980985692] 'process raft request' (duration: 781.235865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:23.466375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:22.684786Z","time spent":"781.517091ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:29:23.854376Z","caller":"traceutil/trace.go:171","msg":"trace[2047863178] transaction","detail":"{read_only:false; response_revision:2852; number_of_response:1; }","duration":"1.169067207s","start":"2026-07-24T05:29:22.685282Z","end":"2026-07-24T05:29:23.854349Z","steps":["trace[2047863178] 'process raft request' (duration: 1.153656932s)","trace[2047863178] 'compare' (duration: 15.236691ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:29:23.85458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:22.685266Z","time spent":"1.16921537s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:29:23.854688Z","caller":"traceutil/trace.go:171","msg":"trace[1103495881] transaction","detail":"{read_only:false; response_revision:2853; number_of_response:1; }","duration":"1.028375367s","start":"2026-07-24T05:29:22.826298Z","end":"2026-07-24T05:29:23.854673Z","steps":["trace[1103495881] 'process raft request' (duration: 1.028005138s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:23.854768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:22.826276Z","time spent":"1.028449419s","remote":"127.0.0.1:45252","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-07-24T05:29:23.855033Z","caller":"traceutil/trace.go:171","msg":"trace[1119871262] linearizableReadLoop","detail":"{readStateIndex:2980; appliedIndex:2976; }","duration":"919.085373ms","start":"2026-07-24T05:29:22.935926Z","end":"2026-07-24T05:29:23.855011Z","steps":["trace[1119871262] 'read index received' (duration: 530.175244ms)","trace[1119871262] 'applied index is now lower than readState.Index' (duration: 388.908909ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:29:23.855127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.203216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T05:29:23.855109Z","caller":"traceutil/trace.go:171","msg":"trace[762138466] transaction","detail":"{read_only:false; response_revision:2854; number_of_response:1; }","duration":"679.406826ms","start":"2026-07-24T05:29:23.175675Z","end":"2026-07-24T05:29:23.855082Z","steps":["trace[762138466] 'process raft request' (duration: 679.081428ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:29:23.855181Z","caller":"traceutil/trace.go:171","msg":"trace[989333531] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2854; }","duration":"919.290868ms","start":"2026-07-24T05:29:22.935878Z","end":"2026-07-24T05:29:23.855169Z","steps":["trace[989333531] 'agreement among raft nodes before linearized reading' (duration: 919.215366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:23.855224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:22.935861Z","time spent":"919.35201ms","remote":"127.0.0.1:45158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-07-24T05:29:23.855279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:23.175645Z","time spent":"679.55002ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:29:23.855437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.580845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:29:23.855521Z","caller":"traceutil/trace.go:171","msg":"trace[1354089416] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2854; }","duration":"843.720799ms","start":"2026-07-24T05:29:23.011785Z","end":"2026-07-24T05:29:23.855506Z","steps":["trace[1354089416] 'agreement among raft nodes before linearized reading' (duration: 843.597575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:23.855544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.338338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T05:29:23.855586Z","caller":"traceutil/trace.go:171","msg":"trace[2032206439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2854; }","duration":"231.4099ms","start":"2026-07-24T05:29:23.624165Z","end":"2026-07-24T05:29:23.855575Z","steps":["trace[2032206439] 'agreement among raft nodes before linearized reading' (duration: 231.287447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:23.855648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:23.011764Z","time spent":"843.805561ms","remote":"127.0.0.1:54226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-24T05:29:23.855737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.08062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-07-24T05:29:23.855779Z","caller":"traceutil/trace.go:171","msg":"trace[431099232] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2854; }","duration":"161.144672ms","start":"2026-07-24T05:29:23.694621Z","end":"2026-07-24T05:29:23.855766Z","steps":["trace[431099232] 'agreement among raft nodes before linearized reading' (duration: 161.05368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:23.855969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.003856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-24T05:29:23.856004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.966187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-07-24T05:29:23.856053Z","caller":"traceutil/trace.go:171","msg":"trace[1958027915] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2854; }","duration":"434.046009ms","start":"2026-07-24T05:29:23.421995Z","end":"2026-07-24T05:29:23.856041Z","steps":["trace[1958027915] 'agreement among raft nodes before linearized reading' (duration: 433.874565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:23.856091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:23.421981Z","time spent":"434.10255ms","remote":"127.0.0.1:45192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2746,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-07-24T05:29:23.856204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.450334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-24T05:29:23.856301Z","caller":"traceutil/trace.go:171","msg":"trace[2068510293] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2854; }","duration":"413.593318ms","start":"2026-07-24T05:29:23.442652Z","end":"2026-07-24T05:29:23.856245Z","steps":["trace[2068510293] 'agreement among raft nodes before linearized reading' (duration: 413.28243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:23.856348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:23.442634Z","time spent":"413.70187ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-24T05:29:23.856066Z","caller":"traceutil/trace.go:171","msg":"trace[255459821] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2854; }","duration":"585.137428ms","start":"2026-07-24T05:29:23.270891Z","end":"2026-07-24T05:29:23.856029Z","steps":["trace[255459821] 'agreement among raft nodes before linearized reading' (duration: 585.036226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:23.85738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:23.270873Z","time spent":"586.455932ms","remote":"127.0.0.1:45192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":14,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-07-24T05:29:24.217717Z","caller":"traceutil/trace.go:171","msg":"trace[1045202123] linearizableReadLoop","detail":"{readStateIndex:2983; appliedIndex:2981; }","duration":"193.422719ms","start":"2026-07-24T05:29:24.024269Z","end":"2026-07-24T05:29:24.217691Z","steps":["trace[1045202123] 'read index received' (duration: 132.859254ms)","trace[1045202123] 'applied index is now lower than readState.Index' (duration: 60.562495ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:29:24.217854Z","caller":"traceutil/trace.go:171","msg":"trace[2039494566] transaction","detail":"{read_only:false; response_revision:2856; number_of_response:1; }","duration":"357.098264ms","start":"2026-07-24T05:29:23.860739Z","end":"2026-07-24T05:29:24.217837Z","steps":["trace[2039494566] 'process raft request' (duration: 296.442356ms)","trace[2039494566] 'compare' (duration: 60.302929ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:29:24.218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:23.860719Z","time spent":"357.180196ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:29:24.218317Z","caller":"traceutil/trace.go:171","msg":"trace[1590684610] transaction","detail":"{read_only:false; response_revision:2857; number_of_response:1; }","duration":"357.216246ms","start":"2026-07-24T05:29:23.861076Z","end":"2026-07-24T05:29:24.218292Z","steps":["trace[1590684610] 'process raft request' (duration: 356.55535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:24.218343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.062144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-24T05:29:24.218568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.540756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-24T05:29:24.218657Z","caller":"traceutil/trace.go:171","msg":"trace[245098050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2857; }","duration":"180.687279ms","start":"2026-07-24T05:29:24.037948Z","end":"2026-07-24T05:29:24.218635Z","steps":["trace[245098050] 'agreement among raft nodes before linearized reading' (duration: 180.417163ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:29:24.218674Z","caller":"traceutil/trace.go:171","msg":"trace[717476734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2857; }","duration":"194.417813ms","start":"2026-07-24T05:29:24.024209Z","end":"2026-07-24T05:29:24.218626Z","steps":["trace[717476734] 'agreement among raft nodes before linearized reading' (duration: 193.87458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:24.218925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:23.861066Z","time spent":"357.346389ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:29:30.235986Z","caller":"traceutil/trace.go:171","msg":"trace[37480525] transaction","detail":"{read_only:false; response_revision:2890; number_of_response:1; }","duration":"361.514894ms","start":"2026-07-24T05:29:29.874442Z","end":"2026-07-24T05:29:30.235957Z","steps":["trace[37480525] 'process raft request' (duration: 361.36214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:30.23615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:29.874414Z","time spent":"361.651648ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:29:30.625193Z","caller":"traceutil/trace.go:171","msg":"trace[1227985350] linearizableReadLoop","detail":"{readStateIndex:3018; appliedIndex:3017; }","duration":"383.252559ms","start":"2026-07-24T05:29:30.241913Z","end":"2026-07-24T05:29:30.625165Z","steps":["trace[1227985350] 'read index received' (duration: 343.973716ms)","trace[1227985350] 'applied index is now lower than readState.Index' (duration: 39.277263ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:29:30.625536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.608586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-24T05:29:30.625553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.251731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T05:29:30.625591Z","caller":"traceutil/trace.go:171","msg":"trace[1255522384] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2890; }","duration":"327.731739ms","start":"2026-07-24T05:29:30.297843Z","end":"2026-07-24T05:29:30.625575Z","steps":["trace[1255522384] 'agreement among raft nodes before linearized reading' (duration: 327.480783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:30.625583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.514255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-24T05:29:30.625681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.163856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:29:30.625714Z","caller":"traceutil/trace.go:171","msg":"trace[2097558112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2890; }","duration":"124.238978ms","start":"2026-07-24T05:29:30.501466Z","end":"2026-07-24T05:29:30.625705Z","steps":["trace[2097558112] 'agreement among raft nodes before linearized reading' (duration: 124.189387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:30.625775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.14838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-24T05:29:30.62578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.089893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T05:29:30.62586Z","caller":"traceutil/trace.go:171","msg":"trace[1271107277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2890; }","duration":"320.252442ms","start":"2026-07-24T05:29:30.305575Z","end":"2026-07-24T05:29:30.625828Z","steps":["trace[1271107277] 'agreement among raft nodes before linearized reading' (duration: 320.110789ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:29:30.625878Z","caller":"traceutil/trace.go:171","msg":"trace[873593486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2890; }","duration":"319.197876ms","start":"2026-07-24T05:29:30.30665Z","end":"2026-07-24T05:29:30.625848Z","steps":["trace[873593486] 'agreement among raft nodes before linearized reading' (duration: 318.930999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:30.62594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:30.305547Z","time spent":"320.358414ms","remote":"127.0.0.1:45252","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-07-24T05:29:30.62595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:30.306596Z","time spent":"319.339329ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-24T05:29:30.625769Z","caller":"traceutil/trace.go:171","msg":"trace[232867837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2890; }","duration":"383.855103ms","start":"2026-07-24T05:29:30.241851Z","end":"2026-07-24T05:29:30.625706Z","steps":["trace[232867837] 'agreement among raft nodes before linearized reading' (duration: 383.410492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:30.626062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.37321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-24T05:29:30.626086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:30.241827Z","time spent":"384.244513ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T05:29:30.625659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:30.297817Z","time spent":"327.805711ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-24T05:29:30.626154Z","caller":"traceutil/trace.go:171","msg":"trace[106307216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2890; }","duration":"319.491713ms","start":"2026-07-24T05:29:30.30665Z","end":"2026-07-24T05:29:30.626142Z","steps":["trace[106307216] 'agreement among raft nodes before linearized reading' (duration: 319.155685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:30.626247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:30.306638Z","time spent":"319.596265ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-24T05:29:30.62563Z","caller":"traceutil/trace.go:171","msg":"trace[585473839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2890; }","duration":"318.380725ms","start":"2026-07-24T05:29:30.307229Z","end":"2026-07-24T05:29:30.62561Z","steps":["trace[585473839] 'agreement among raft nodes before linearized reading' (duration: 318.137879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:29:30.626394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:29:30.307211Z","time spent":"319.163925ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-24T05:29:30.735243Z","caller":"traceutil/trace.go:171","msg":"trace[1161815596] transaction","detail":"{read_only:false; response_revision:2892; number_of_response:1; }","duration":"104.472394ms","start":"2026-07-24T05:29:30.630742Z","end":"2026-07-24T05:29:30.735214Z","steps":["trace[1161815596] 'process raft request' (duration: 104.30807ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:29:30.735509Z","caller":"traceutil/trace.go:171","msg":"trace[687704607] transaction","detail":"{read_only:false; response_revision:2893; number_of_response:1; }","duration":"104.658038ms","start":"2026-07-24T05:29:30.630832Z","end":"2026-07-24T05:29:30.73549Z","steps":["trace[687704607] 'process raft request' (duration: 104.3404ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:29:30.735658Z","caller":"traceutil/trace.go:171","msg":"trace[1351629452] transaction","detail":"{read_only:false; response_revision:2894; number_of_response:1; }","duration":"104.629928ms","start":"2026-07-24T05:29:30.631001Z","end":"2026-07-24T05:29:30.735631Z","steps":["trace[1351629452] 'process raft request' (duration: 104.381001ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:29:30.736036Z","caller":"traceutil/trace.go:171","msg":"trace[285149229] transaction","detail":"{read_only:false; response_revision:2896; number_of_response:1; }","duration":"104.218338ms","start":"2026-07-24T05:29:30.631802Z","end":"2026-07-24T05:29:30.73602Z","steps":["trace[285149229] 'process raft request' (duration: 104.129975ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:29:30.736075Z","caller":"traceutil/trace.go:171","msg":"trace[74553821] transaction","detail":"{read_only:false; response_revision:2895; number_of_response:1; }","duration":"104.478935ms","start":"2026-07-24T05:29:30.631582Z","end":"2026-07-24T05:29:30.736061Z","steps":["trace[74553821] 'process raft request' (duration: 103.981851ms)"],"step_count":1}