{"level":"warn","ts":"2026-03-03T21:14:43.394641Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T21:14:43.394749Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244: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.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244: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-03-03T21:14:43.394817Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T21:14:43.394831Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-03-03T21:14:43.394848Z","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-03-03T21:14:43.395645Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-03-03T21:14:43.395746Z","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.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244: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-03-03T21:14:43.402366Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.379388ms"} {"level":"info","ts":"2026-03-03T21:14:43.420541Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-03-03T21:14:43.42065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-03-03T21:14:43.420691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-03-03T21:14:43.420718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-03T21:14:43.420735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-03-03T21:14:43.420843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-03-03T21:14:43.427877Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-03T21:14:43.429261Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-03T21:14:43.432538Z","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-03-03T21:14:43.434985Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-03T21:14:43.435279Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-03T21:14:43.435311Z","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-03-03T21:14:43.437116Z","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-03-03T21:14:43.437153Z","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-03-03T21:14:43.438056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-03-03T21:14:43.438198Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-03-03T21:14:43.440424Z","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-03-03T21:14:43.440524Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-03-03T21:14:43.440579Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-03-03T21:14:43.440832Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-03T21:14:43.440876Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-03T21:14:43.521412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-03-03T21:14:43.521488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-03-03T21:14:43.521527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-03-03T21:14:43.521554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-03-03T21:14:43.521571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-03-03T21:14:43.521593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-03-03T21:14:43.521612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-03-03T21:14:43.522954Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T21:14:43.523034Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T21:14:43.523046Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-03-03T21:14:43.523186Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T21:14:43.523759Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-03T21:14:43.523811Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-03T21:14:43.524057Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T21:14:43.524213Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T21:14:43.524259Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T21:14:43.52616Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-03T21:14:43.527718Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"warn","ts":"2026-03-03T21:14:47.196759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.526398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:14:47.196895Z","caller":"traceutil/trace.go:171","msg":"trace[825632599] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"419.320821ms","start":"2026-03-03T21:14:46.777557Z","end":"2026-03-03T21:14:47.196878Z","steps":["trace[825632599] 'compare' (duration: 413.364705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:47.196961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:46.777542Z","time spent":"419.395923ms","remote":"127.0.0.1:46188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":748,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T21:14:48.077298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.404592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:14:48.077411Z","caller":"traceutil/trace.go:171","msg":"trace[1082690543] linearizableReadLoop","detail":"{readStateIndex:222; appliedIndex:221; }","duration":"770.928141ms","start":"2026-03-03T21:14:47.306467Z","end":"2026-03-03T21:14:48.077395Z","steps":["trace[1082690543] 'read index received' (duration: 358.232252ms)","trace[1082690543] 'applied index is now lower than readState.Index' (duration: 412.693589ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:14:48.07748Z","caller":"traceutil/trace.go:171","msg":"trace[548846268] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"870.605212ms","start":"2026-03-03T21:14:47.206864Z","end":"2026-03-03T21:14:48.07747Z","steps":["trace[548846268] 'process raft request' (duration: 457.81025ms)","trace[548846268] 'compare' (duration: 412.269869ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:14:48.077531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:47.206847Z","time spent":"870.661874ms","remote":"127.0.0.1:46188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T21:14:48.082771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.346661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:14:48.082906Z","caller":"traceutil/trace.go:171","msg":"trace[1315265195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:217; }","duration":"776.453909ms","start":"2026-03-03T21:14:47.306419Z","end":"2026-03-03T21:14:48.082873Z","steps":["trace[1315265195] 'agreement among raft nodes before linearized reading' (duration: 771.217168ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:48.083037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:47.306393Z","time spent":"776.563831ms","remote":"127.0.0.1:45862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T21:14:48.578077Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198072164441587,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T21:14:48.627204Z","caller":"traceutil/trace.go:171","msg":"trace[728345066] linearizableReadLoop","detail":"{readStateIndex:223; appliedIndex:222; }","duration":"549.741003ms","start":"2026-03-03T21:14:48.077439Z","end":"2026-03-03T21:14:48.62718Z","steps":["trace[728345066] 'read index received' (duration: 549.490768ms)","trace[728345066] 'applied index is now lower than readState.Index' (duration: 249.295µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:14:48.627374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282457241s","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-03-03T21:14:48.627408Z","caller":"traceutil/trace.go:171","msg":"trace[127990088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:218; }","duration":"1.282564394s","start":"2026-03-03T21:14:47.344833Z","end":"2026-03-03T21:14:48.627397Z","steps":["trace[127990088] 'agreement among raft nodes before linearized reading' (duration: 1.282462442s)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:14:48.627376Z","caller":"traceutil/trace.go:171","msg":"trace[2035243253] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"1.035045359s","start":"2026-03-03T21:14:47.592252Z","end":"2026-03-03T21:14:48.627298Z","steps":["trace[2035243253] 'process raft request' (duration: 1.034798833s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:48.627512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282797869s","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-03-03T21:14:48.62754Z","caller":"traceutil/trace.go:171","msg":"trace[1778097114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:218; }","duration":"1.28284416s","start":"2026-03-03T21:14:47.344688Z","end":"2026-03-03T21:14:48.627532Z","steps":["trace[1778097114] 'agreement among raft nodes before linearized reading' (duration: 1.282784879s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:48.627566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:47.344677Z","time spent":"1.282883041s","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T21:14:48.627605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:47.592228Z","time spent":"1.035270295s","remote":"127.0.0.1:46080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T21:14:48.627658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.718904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T21:14:48.627447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:47.344789Z","time spent":"1.282650346s","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T21:14:48.627949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.952379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:14:48.627953Z","caller":"traceutil/trace.go:171","msg":"trace[1733733624] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:certificate-controller; range_end:; response_count:0; response_revision:218; }","duration":"544.01774ms","start":"2026-03-03T21:14:48.083867Z","end":"2026-03-03T21:14:48.627885Z","steps":["trace[1733733624] 'agreement among raft nodes before linearized reading' (duration: 543.688583ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:14:48.627992Z","caller":"traceutil/trace.go:171","msg":"trace[1563380743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:218; }","duration":"544.024161ms","start":"2026-03-03T21:14:48.083958Z","end":"2026-03-03T21:14:48.627983Z","steps":["trace[1563380743] 'agreement among raft nodes before linearized reading' (duration: 543.966219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:48.627995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.422795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T21:14:48.62802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:48.083941Z","time spent":"544.072832ms","remote":"127.0.0.1:45876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T21:14:48.628027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:48.083852Z","time spent":"544.157834ms","remote":"127.0.0.1:46188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/system:controller:certificate-controller\" "} {"level":"info","ts":"2026-03-03T21:14:48.628098Z","caller":"traceutil/trace.go:171","msg":"trace[561281664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:218; }","duration":"199.752642ms","start":"2026-03-03T21:14:48.428286Z","end":"2026-03-03T21:14:48.628039Z","steps":["trace[561281664] 'agreement among raft nodes before linearized reading' (duration: 199.610769ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:14:49.119722Z","caller":"traceutil/trace.go:171","msg":"trace[489184030] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"480.29802ms","start":"2026-03-03T21:14:48.639383Z","end":"2026-03-03T21:14:49.119681Z","steps":["trace[489184030] 'process raft request' (duration: 458.111307ms)","trace[489184030] 'compare' (duration: 21.895396ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:14:49.119881Z","caller":"traceutil/trace.go:171","msg":"trace[1698538271] transaction","detail":"{read_only:false; response_revision:220; number_of_response:1; }","duration":"480.28873ms","start":"2026-03-03T21:14:48.63956Z","end":"2026-03-03T21:14:49.119848Z","steps":["trace[1698538271] 'process raft request' (duration: 480.005203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:49.120009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:48.639555Z","time spent":"480.396282ms","remote":"127.0.0.1:46188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":748,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T21:14:49.12013Z","caller":"traceutil/trace.go:171","msg":"trace[2074321472] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"480.431043ms","start":"2026-03-03T21:14:48.639642Z","end":"2026-03-03T21:14:49.120073Z","steps":["trace[2074321472] 'process raft request' (duration: 480.137246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:49.12084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:48.639636Z","time spent":"480.537575ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:14:49.121914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:48.639358Z","time spent":"480.435043ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:14:53.619855Z","caller":"traceutil/trace.go:171","msg":"trace[977639113] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"118.984657ms","start":"2026-03-03T21:14:53.500845Z","end":"2026-03-03T21:14:53.619829Z","steps":["trace[977639113] 'process raft request' (duration: 118.830263ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:14:58.152948Z","caller":"traceutil/trace.go:171","msg":"trace[1444731274] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"112.302433ms","start":"2026-03-03T21:14:58.040626Z","end":"2026-03-03T21:14:58.152929Z","steps":["trace[1444731274] 'process raft request' (duration: 112.19054ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:01.611305Z","caller":"traceutil/trace.go:171","msg":"trace[2124541122] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"272.430729ms","start":"2026-03-03T21:15:01.338847Z","end":"2026-03-03T21:15:01.611278Z","steps":["trace[2124541122] 'read index received' (duration: 243.353962ms)","trace[2124541122] 'applied index is now lower than readState.Index' (duration: 29.075597ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:15:01.611421Z","caller":"traceutil/trace.go:171","msg":"trace[351476559] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"274.021795ms","start":"2026-03-03T21:15:01.337355Z","end":"2026-03-03T21:15:01.611377Z","steps":["trace[351476559] 'process raft request' (duration: 244.919768ms)","trace[351476559] 'compare' (duration: 28.893902ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:15:01.611548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.688734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-03-03T21:15:01.61161Z","caller":"traceutil/trace.go:171","msg":"trace[929736724] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:414; }","duration":"272.806678ms","start":"2026-03-03T21:15:01.338788Z","end":"2026-03-03T21:15:01.611595Z","steps":["trace[929736724] 'agreement among raft nodes before linearized reading' (duration: 272.663015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:01.611591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.697447ms","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-03-03T21:15:01.611678Z","caller":"traceutil/trace.go:171","msg":"trace[757480459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"269.84485ms","start":"2026-03-03T21:15:01.341815Z","end":"2026-03-03T21:15:01.61166Z","steps":["trace[757480459] 'agreement among raft nodes before linearized reading' (duration: 269.680117ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:14.567315Z","caller":"traceutil/trace.go:171","msg":"trace[1246900616] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:510; }","duration":"125.183415ms","start":"2026-03-03T21:15:14.442073Z","end":"2026-03-03T21:15:14.567257Z","steps":["trace[1246900616] 'read index received' (duration: 6.721651ms)","trace[1246900616] 'applied index is now lower than readState.Index' (duration: 118.460904ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:15:14.567408Z","caller":"traceutil/trace.go:171","msg":"trace[1443896317] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"123.723262ms","start":"2026-03-03T21:15:14.44363Z","end":"2026-03-03T21:15:14.567353Z","steps":["trace[1443896317] 'process raft request' (duration: 123.489597ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:14.567518Z","caller":"traceutil/trace.go:171","msg":"trace[425369454] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"126.28043ms","start":"2026-03-03T21:15:14.441209Z","end":"2026-03-03T21:15:14.56749Z","steps":["trace[425369454] 'process raft request' (duration: 78.155114ms)","trace[425369454] 'compare' (duration: 47.606265ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:15:14.567666Z","caller":"traceutil/trace.go:171","msg":"trace[2118555390] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"110.904223ms","start":"2026-03-03T21:15:14.45672Z","end":"2026-03-03T21:15:14.567624Z","steps":["trace[2118555390] 'process raft request' (duration: 110.492634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:14.567739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.637835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-03-03T21:15:14.568014Z","caller":"traceutil/trace.go:171","msg":"trace[1152833106] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:500; }","duration":"125.720977ms","start":"2026-03-03T21:15:14.442048Z","end":"2026-03-03T21:15:14.567769Z","steps":["trace[1152833106] 'agreement among raft nodes before linearized reading' (duration: 125.38931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:14.944811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.221343ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-03T21:15:14.945574Z","caller":"traceutil/trace.go:171","msg":"trace[1991443587] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:515; }","duration":"378.158755ms","start":"2026-03-03T21:15:14.567395Z","end":"2026-03-03T21:15:14.945554Z","steps":["trace[1991443587] 'read index received' (duration: 59.221µs)","trace[1991443587] 'applied index is now lower than readState.Index' (duration: 378.098244ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:15:14.946097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.842515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-6xkpl\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-03-03T21:15:14.946592Z","caller":"traceutil/trace.go:171","msg":"trace[1730445774] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-6xkpl; range_end:; response_count:1; response_revision:501; }","duration":"478.422238ms","start":"2026-03-03T21:15:14.468144Z","end":"2026-03-03T21:15:14.946566Z","steps":["trace[1730445774] 'agreement among raft nodes before linearized reading' (duration: 477.769703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:14.946671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:14.468131Z","time spent":"478.524001ms","remote":"127.0.0.1:46080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":3659,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-6xkpl\" "} {"level":"info","ts":"2026-03-03T21:15:14.946072Z","caller":"traceutil/trace.go:171","msg":"trace[1174885521] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"446.650672ms","start":"2026-03-03T21:15:14.499282Z","end":"2026-03-03T21:15:14.945932Z","steps":["trace[1174885521] 'process raft request' (duration: 445.843034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:14.947092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:14.499249Z","time spent":"447.583352ms","remote":"127.0.0.1:45990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:15:14.957522Z","caller":"traceutil/trace.go:171","msg":"trace[723334666] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"385.357588ms","start":"2026-03-03T21:15:14.572075Z","end":"2026-03-03T21:15:14.957433Z","steps":["trace[723334666] 'process raft request' (duration: 385.207345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:14.957642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:14.572047Z","time spent":"385.535871ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T21:15:14.959836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.904988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-03-03T21:15:14.95988Z","caller":"traceutil/trace.go:171","msg":"trace[270905276] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:504; }","duration":"222.01587ms","start":"2026-03-03T21:15:14.737851Z","end":"2026-03-03T21:15:14.959867Z","steps":["trace[270905276] 'agreement among raft nodes before linearized reading' (duration: 221.917058ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:14.959963Z","caller":"traceutil/trace.go:171","msg":"trace[520955610] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"383.496956ms","start":"2026-03-03T21:15:14.576453Z","end":"2026-03-03T21:15:14.95995Z","steps":["trace[520955610] 'process raft request' (duration: 383.168969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:14.96003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:14.576427Z","time spent":"383.561587ms","remote":"127.0.0.1:46288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4617,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:15:14.960149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.959067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:15:14.960173Z","caller":"traceutil/trace.go:171","msg":"trace[1873781155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:504; }","duration":"372.984478ms","start":"2026-03-03T21:15:14.587181Z","end":"2026-03-03T21:15:14.960165Z","steps":["trace[1873781155] 'agreement among raft nodes before linearized reading' (duration: 372.540818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:14.960142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.388834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-03-03T21:15:14.960195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:14.587147Z","time spent":"373.043889ms","remote":"127.0.0.1:45862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T21:15:14.96022Z","caller":"traceutil/trace.go:171","msg":"trace[474302333] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:504; }","duration":"224.537587ms","start":"2026-03-03T21:15:14.735666Z","end":"2026-03-03T21:15:14.960203Z","steps":["trace[474302333] 'agreement among raft nodes before linearized reading' (duration: 224.395834ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:14.960393Z","caller":"traceutil/trace.go:171","msg":"trace[1004786161] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"382.115865ms","start":"2026-03-03T21:15:14.577886Z","end":"2026-03-03T21:15:14.960002Z","steps":["trace[1004786161] 'process raft request' (duration: 381.795488ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:14.960481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:14.577861Z","time spent":"382.547824ms","remote":"127.0.0.1:46314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4339,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:15:16.293019Z","caller":"traceutil/trace.go:171","msg":"trace[332185402] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"432.83378ms","start":"2026-03-03T21:15:15.860162Z","end":"2026-03-03T21:15:16.292996Z","steps":["trace[332185402] 'process raft request' (duration: 432.667806ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:16.293264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:15.860148Z","time spent":"432.964732ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:15:16.322363Z","caller":"traceutil/trace.go:171","msg":"trace[1764166064] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:529; }","duration":"229.263555ms","start":"2026-03-03T21:15:16.093089Z","end":"2026-03-03T21:15:16.322353Z","steps":["trace[1764166064] 'read index received' (duration: 199.721679ms)","trace[1764166064] 'applied index is now lower than readState.Index' (duration: 29.541256ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:15:16.322442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.346976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:15:16.322474Z","caller":"traceutil/trace.go:171","msg":"trace[1226975765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"229.409878ms","start":"2026-03-03T21:15:16.093057Z","end":"2026-03-03T21:15:16.322467Z","steps":["trace[1226975765] 'agreement among raft nodes before linearized reading' (duration: 229.337587ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:16.322658Z","caller":"traceutil/trace.go:171","msg":"trace[190621437] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"243.415534ms","start":"2026-03-03T21:15:16.079234Z","end":"2026-03-03T21:15:16.322649Z","steps":["trace[190621437] 'process raft request' (duration: 243.066937ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:17.766389Z","caller":"traceutil/trace.go:171","msg":"trace[1467646470] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"397.776038ms","start":"2026-03-03T21:15:17.368592Z","end":"2026-03-03T21:15:17.766368Z","steps":["trace[1467646470] 'process raft request' (duration: 397.635265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:17.766538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:17.368571Z","time spent":"397.89168ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:15:17.787943Z","caller":"traceutil/trace.go:171","msg":"trace[1828711251] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"418.73815ms","start":"2026-03-03T21:15:17.36908Z","end":"2026-03-03T21:15:17.787818Z","steps":["trace[1828711251] 'process raft request' (duration: 418.531846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:17.788214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:17.369062Z","time spent":"418.973625ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:15:19.911789Z","caller":"traceutil/trace.go:171","msg":"trace[402354882] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"119.930486ms","start":"2026-03-03T21:15:19.791844Z","end":"2026-03-03T21:15:19.911774Z","steps":["trace[402354882] 'read index received' (duration: 119.772933ms)","trace[402354882] 'applied index is now lower than readState.Index' (duration: 156.963µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:15:19.911953Z","caller":"traceutil/trace.go:171","msg":"trace[625306431] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"137.747309ms","start":"2026-03-03T21:15:19.774132Z","end":"2026-03-03T21:15:19.911879Z","steps":["trace[625306431] 'process raft request' (duration: 137.545564ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:19.911984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.68109ms","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-03-03T21:15:19.912023Z","caller":"traceutil/trace.go:171","msg":"trace[617650504] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"103.784643ms","start":"2026-03-03T21:15:19.808228Z","end":"2026-03-03T21:15:19.912013Z","steps":["trace[617650504] 'agreement among raft nodes before linearized reading' (duration: 103.6729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:19.911982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.116381ms","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-03-03T21:15:19.912148Z","caller":"traceutil/trace.go:171","msg":"trace[896387505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"120.303386ms","start":"2026-03-03T21:15:19.791813Z","end":"2026-03-03T21:15:19.912116Z","steps":["trace[896387505] 'agreement among raft nodes before linearized reading' (duration: 120.043109ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:24.424737Z","caller":"traceutil/trace.go:171","msg":"trace[129857976] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"428.358447ms","start":"2026-03-03T21:15:23.996351Z","end":"2026-03-03T21:15:24.42471Z","steps":["trace[129857976] 'read index received' (duration: 428.200504ms)","trace[129857976] 'applied index is now lower than readState.Index' (duration: 156.313µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:15:24.424808Z","caller":"traceutil/trace.go:171","msg":"trace[906354851] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"439.54372ms","start":"2026-03-03T21:15:23.985229Z","end":"2026-03-03T21:15:24.424772Z","steps":["trace[906354851] 'process raft request' (duration: 439.325445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:24.425016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:23.985209Z","time spent":"439.689433ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:15:24.425076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.300138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:15:24.425118Z","caller":"traceutil/trace.go:171","msg":"trace[646613985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"331.39989ms","start":"2026-03-03T21:15:24.093706Z","end":"2026-03-03T21:15:24.425106Z","steps":["trace[646613985] 'agreement among raft nodes before linearized reading' (duration: 331.299208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:24.425153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:24.093696Z","time spent":"331.448171ms","remote":"127.0.0.1:45862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T21:15:24.425116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.729656ms","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-03-03T21:15:24.4254Z","caller":"traceutil/trace.go:171","msg":"trace[842203655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"429.077275ms","start":"2026-03-03T21:15:23.996283Z","end":"2026-03-03T21:15:24.42536Z","steps":["trace[842203655] 'agreement among raft nodes before linearized reading' (duration: 428.550102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:24.425486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:23.99626Z","time spent":"429.208558ms","remote":"127.0.0.1:46120","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-03-03T21:15:24.425671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.242328ms","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-03-03T21:15:24.42571Z","caller":"traceutil/trace.go:171","msg":"trace[1931393438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"363.31163ms","start":"2026-03-03T21:15:24.062386Z","end":"2026-03-03T21:15:24.425697Z","steps":["trace[1931393438] 'agreement among raft nodes before linearized reading' (duration: 362.585204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:24.42574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:24.062368Z","time spent":"363.365401ms","remote":"127.0.0.1:46120","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-03-03T21:15:25.573011Z","caller":"traceutil/trace.go:171","msg":"trace[208911345] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"441.553456ms","start":"2026-03-03T21:15:25.131432Z","end":"2026-03-03T21:15:25.572986Z","steps":["trace[208911345] 'process raft request' (duration: 441.32737ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:25.573197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:25.131411Z","time spent":"441.705928ms","remote":"127.0.0.1:46080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:15:26.93082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198072164442267,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T21:15:26.934944Z","caller":"traceutil/trace.go:171","msg":"trace[614974866] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"504.618368ms","start":"2026-03-03T21:15:26.430296Z","end":"2026-03-03T21:15:26.934914Z","steps":["trace[614974866] 'read index received' (duration: 504.448874ms)","trace[614974866] 'applied index is now lower than readState.Index' (duration: 168.164µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:15:26.935284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.968207ms","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-03-03T21:15:26.935364Z","caller":"traceutil/trace.go:171","msg":"trace[2008373800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"505.109651ms","start":"2026-03-03T21:15:26.430235Z","end":"2026-03-03T21:15:26.935345Z","steps":["trace[2008373800] 'agreement among raft nodes before linearized reading' (duration: 504.783393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:26.935386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.980757ms","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-03-03T21:15:26.935423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.971877ms","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-03-03T21:15:26.935477Z","caller":"traceutil/trace.go:171","msg":"trace[290089032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:538; }","duration":"497.12379ms","start":"2026-03-03T21:15:26.438338Z","end":"2026-03-03T21:15:26.935462Z","steps":["trace[290089032] 'agreement among raft nodes before linearized reading' (duration: 496.827794ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:26.935488Z","caller":"traceutil/trace.go:171","msg":"trace[1989708264] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:538; }","duration":"497.085109ms","start":"2026-03-03T21:15:26.438386Z","end":"2026-03-03T21:15:26.935471Z","steps":["trace[1989708264] 'agreement among raft nodes before linearized reading' (duration: 496.868525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:26.935465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.977334ms","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-03-03T21:15:26.935074Z","caller":"traceutil/trace.go:171","msg":"trace[1823209012] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"551.724472ms","start":"2026-03-03T21:15:26.383282Z","end":"2026-03-03T21:15:26.935007Z","steps":["trace[1823209012] 'process raft request' (duration: 551.411204ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:26.935607Z","caller":"traceutil/trace.go:171","msg":"trace[692833660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"496.174808ms","start":"2026-03-03T21:15:26.439411Z","end":"2026-03-03T21:15:26.935586Z","steps":["trace[692833660] 'agreement among raft nodes before linearized reading' (duration: 495.79199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:26.935543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:26.438368Z","time spent":"497.164101ms","remote":"127.0.0.1:46120","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-03-03T21:15:26.935729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:26.439406Z","time spent":"496.310101ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T21:15:26.935778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:26.383251Z","time spent":"552.446318ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:15:26.935417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:26.430216Z","time spent":"505.189902ms","remote":"127.0.0.1:46120","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-03-03T21:15:26.935545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:26.438323Z","time spent":"497.209102ms","remote":"127.0.0.1:46120","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-03-03T21:15:27.605574Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198072164442270,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T21:15:27.861304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.455234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:15:27.861538Z","caller":"traceutil/trace.go:171","msg":"trace[1963154913] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"921.087999ms","start":"2026-03-03T21:15:26.940432Z","end":"2026-03-03T21:15:27.86152Z","steps":["trace[1963154913] 'process raft request' (duration: 920.980847ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:27.861526Z","caller":"traceutil/trace.go:171","msg":"trace[443558338] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"921.201732ms","start":"2026-03-03T21:15:26.940298Z","end":"2026-03-03T21:15:27.861499Z","steps":["trace[443558338] 'process raft request' (duration: 681.436221ms)","trace[443558338] 'compare' (duration: 239.257999ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:15:27.861614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:26.940409Z","time spent":"921.171111ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:15:27.861711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:26.94027Z","time spent":"921.345315ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:15:27.861818Z","caller":"traceutil/trace.go:171","msg":"trace[1110041218] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"921.338186ms","start":"2026-03-03T21:15:26.94047Z","end":"2026-03-03T21:15:27.861808Z","steps":["trace[1110041218] 'process raft request' (duration: 921.017688ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:27.861792Z","caller":"traceutil/trace.go:171","msg":"trace[590861623] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:557; }","duration":"768.382322ms","start":"2026-03-03T21:15:27.093379Z","end":"2026-03-03T21:15:27.861761Z","steps":["trace[590861623] 'read index received' (duration: 528.369976ms)","trace[590861623] 'applied index is now lower than readState.Index' (duration: 240.011016ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:15:27.861868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:26.940438Z","time spent":"921.404876ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:15:27.861907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.532685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:15:27.861908Z","caller":"traceutil/trace.go:171","msg":"trace[1462475183] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"920.999667ms","start":"2026-03-03T21:15:26.940855Z","end":"2026-03-03T21:15:27.861855Z","steps":["trace[1462475183] 'process raft request' (duration: 920.802382ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:27.861954Z","caller":"traceutil/trace.go:171","msg":"trace[111885088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"768.612217ms","start":"2026-03-03T21:15:27.093325Z","end":"2026-03-03T21:15:27.861938Z","steps":["trace[111885088] 'agreement among raft nodes before linearized reading' (duration: 768.547786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:27.861982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:27.093299Z","time spent":"768.676259ms","remote":"127.0.0.1:45862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T21:15:27.862048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:26.940843Z","time spent":"921.118099ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:15:30.377998Z","caller":"traceutil/trace.go:171","msg":"trace[1932171833] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"106.202366ms","start":"2026-03-03T21:15:30.27177Z","end":"2026-03-03T21:15:30.377972Z","steps":["trace[1932171833] 'process raft request' (duration: 105.368847ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:39.189649Z","caller":"traceutil/trace.go:171","msg":"trace[839203205] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"116.273164ms","start":"2026-03-03T21:15:39.073361Z","end":"2026-03-03T21:15:39.189634Z","steps":["trace[839203205] 'process raft request' (duration: 115.828924ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:39.31169Z","caller":"traceutil/trace.go:171","msg":"trace[1974031228] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"103.135338ms","start":"2026-03-03T21:15:39.208537Z","end":"2026-03-03T21:15:39.311673Z","steps":["trace[1974031228] 'process raft request' (duration: 103.024615ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:39.911661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198072164442513,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T21:15:40.079748Z","caller":"traceutil/trace.go:171","msg":"trace[545320321] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"760.454045ms","start":"2026-03-03T21:15:39.319266Z","end":"2026-03-03T21:15:40.07972Z","steps":["trace[545320321] 'process raft request' (duration: 760.271411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:40.079943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:39.319245Z","time spent":"760.598857ms","remote":"127.0.0.1:46066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4442,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:15:41.524548Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.444813059s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T21:15:41.525019Z","caller":"traceutil/trace.go:171","msg":"trace[401761155] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"2.114117846s","start":"2026-03-03T21:15:39.410869Z","end":"2026-03-03T21:15:41.524987Z","steps":["trace[401761155] 'read index received' (duration: 668.933579ms)","trace[401761155] 'applied index is now lower than readState.Index' (duration: 1.445183177s)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:15:41.525198Z","caller":"traceutil/trace.go:171","msg":"trace[930379225] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"2.181818194s","start":"2026-03-03T21:15:39.343344Z","end":"2026-03-03T21:15:41.525162Z","steps":["trace[930379225] 'process raft request' (duration: 2.181473426s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:41.525313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:39.343316Z","time spent":"2.181926885s","remote":"127.0.0.1:46312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14465,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:15:41.525303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.114401442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-03T21:15:41.525357Z","caller":"traceutil/trace.go:171","msg":"trace[703516252] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:664; }","duration":"2.114500595s","start":"2026-03-03T21:15:39.410842Z","end":"2026-03-03T21:15:41.525343Z","steps":["trace[703516252] 'agreement among raft nodes before linearized reading' (duration: 2.114314201s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:41.525398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:39.410833Z","time spent":"2.114555106s","remote":"127.0.0.1:45902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"warn","ts":"2026-03-03T21:15:41.58927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.671962975s","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-03-03T21:15:41.589297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.664626441s","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-03-03T21:15:41.589333Z","caller":"traceutil/trace.go:171","msg":"trace[911701538] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:665; }","duration":"1.672034047s","start":"2026-03-03T21:15:39.917283Z","end":"2026-03-03T21:15:41.589317Z","steps":["trace[911701538] 'agreement among raft nodes before linearized reading' (duration: 1.671817402s)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:41.589345Z","caller":"traceutil/trace.go:171","msg":"trace[824428761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:665; }","duration":"1.664688152s","start":"2026-03-03T21:15:39.924643Z","end":"2026-03-03T21:15:41.589332Z","steps":["trace[824428761] 'agreement among raft nodes before linearized reading' (duration: 1.664548069s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:41.589373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:39.917219Z","time spent":"1.67214558s","remote":"127.0.0.1:46120","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-03-03T21:15:41.589378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:39.92464Z","time spent":"1.664730593s","remote":"127.0.0.1:46120","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-03-03T21:15:41.589385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.655964454s","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-03-03T21:15:41.589479Z","caller":"traceutil/trace.go:171","msg":"trace[1312848758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:665; }","duration":"1.656096858s","start":"2026-03-03T21:15:39.933357Z","end":"2026-03-03T21:15:41.589454Z","steps":["trace[1312848758] 'agreement among raft nodes before linearized reading' (duration: 1.655804801s)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:41.589444Z","caller":"traceutil/trace.go:171","msg":"trace[382461183] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.502155394s","start":"2026-03-03T21:15:40.087235Z","end":"2026-03-03T21:15:41.58939Z","steps":["trace[382461183] 'process raft request' (duration: 1.501672952s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:41.589515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.667366422s","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-03-03T21:15:41.589558Z","caller":"traceutil/trace.go:171","msg":"trace[125665435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:665; }","duration":"1.667431343s","start":"2026-03-03T21:15:39.922117Z","end":"2026-03-03T21:15:41.589548Z","steps":["trace[125665435] 'agreement among raft nodes before linearized reading' (duration: 1.667338831s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:41.589566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497194871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T21:15:41.589549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.663221869s","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-03-03T21:15:41.589647Z","caller":"traceutil/trace.go:171","msg":"trace[1579472662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:665; }","duration":"1.663358871s","start":"2026-03-03T21:15:39.926267Z","end":"2026-03-03T21:15:41.589626Z","steps":["trace[1579472662] 'agreement among raft nodes before linearized reading' (duration: 1.663033244s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:41.589686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:40.087212Z","time spent":"1.502350808s","remote":"127.0.0.1:46066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4794,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:15:41.589699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:39.926261Z","time spent":"1.663428593s","remote":"127.0.0.1:46120","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-03-03T21:15:41.589589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:39.922102Z","time spent":"1.667481015s","remote":"127.0.0.1:46120","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-03-03T21:15:41.589522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:39.933351Z","time spent":"1.656161839s","remote":"127.0.0.1:46120","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-03-03T21:15:41.589593Z","caller":"traceutil/trace.go:171","msg":"trace[937813585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"1.497256843s","start":"2026-03-03T21:15:40.092329Z","end":"2026-03-03T21:15:41.589586Z","steps":["trace[937813585] 'agreement among raft nodes before linearized reading' (duration: 1.497218732s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:41.589865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:40.092309Z","time spent":"1.497545899s","remote":"127.0.0.1:45862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T21:15:43.443064Z","caller":"traceutil/trace.go:171","msg":"trace[925515779] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"115.714481ms","start":"2026-03-03T21:15:43.327327Z","end":"2026-03-03T21:15:43.443041Z","steps":["trace[925515779] 'process raft request' (duration: 109.242386ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.465963Z","caller":"traceutil/trace.go:171","msg":"trace[1098536946] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"138.362573ms","start":"2026-03-03T21:15:43.327574Z","end":"2026-03-03T21:15:43.465937Z","steps":["trace[1098536946] 'process raft request' (duration: 138.165338ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.466456Z","caller":"traceutil/trace.go:171","msg":"trace[745911531] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"137.031163ms","start":"2026-03-03T21:15:43.329398Z","end":"2026-03-03T21:15:43.466429Z","steps":["trace[745911531] 'process raft request' (duration: 136.484131ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.466453Z","caller":"traceutil/trace.go:171","msg":"trace[1299547809] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"131.15239ms","start":"2026-03-03T21:15:43.335272Z","end":"2026-03-03T21:15:43.466424Z","steps":["trace[1299547809] 'process raft request' (duration: 130.989886ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.597209Z","caller":"traceutil/trace.go:171","msg":"trace[779314583] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"124.197033ms","start":"2026-03-03T21:15:43.472989Z","end":"2026-03-03T21:15:43.597186Z","steps":["trace[779314583] 'read index received' (duration: 88.705622ms)","trace[779314583] 'applied index is now lower than readState.Index' (duration: 35.490401ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:15:43.597318Z","caller":"traceutil/trace.go:171","msg":"trace[1355187503] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"124.421848ms","start":"2026-03-03T21:15:43.472843Z","end":"2026-03-03T21:15:43.597265Z","steps":["trace[1355187503] 'process raft request' (duration: 88.655572ms)","trace[1355187503] 'compare' (duration: 35.565952ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:15:43.597453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.431728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-qld7b\" ","response":"range_response_count:1 size:5086"} {"level":"info","ts":"2026-03-03T21:15:43.597537Z","caller":"traceutil/trace.go:171","msg":"trace[526552162] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-qld7b; range_end:; response_count:1; response_revision:699; }","duration":"124.570992ms","start":"2026-03-03T21:15:43.47294Z","end":"2026-03-03T21:15:43.597511Z","steps":["trace[526552162] 'agreement among raft nodes before linearized reading' (duration: 124.332886ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.610682Z","caller":"traceutil/trace.go:171","msg":"trace[1150665094] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"137.573465ms","start":"2026-03-03T21:15:43.473092Z","end":"2026-03-03T21:15:43.610666Z","steps":["trace[1150665094] 'process raft request' (duration: 137.401031ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.610992Z","caller":"traceutil/trace.go:171","msg":"trace[143543598] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"136.408508ms","start":"2026-03-03T21:15:43.474569Z","end":"2026-03-03T21:15:43.610977Z","steps":["trace[143543598] 'process raft request' (duration: 136.03732ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.611156Z","caller":"traceutil/trace.go:171","msg":"trace[360374642] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"136.280436ms","start":"2026-03-03T21:15:43.474761Z","end":"2026-03-03T21:15:43.611042Z","steps":["trace[360374642] 'process raft request' (duration: 136.060561ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.730942Z","caller":"traceutil/trace.go:171","msg":"trace[1767447811] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"111.688081ms","start":"2026-03-03T21:15:43.619227Z","end":"2026-03-03T21:15:43.730915Z","steps":["trace[1767447811] 'process raft request' (duration: 111.503207ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.816868Z","caller":"traceutil/trace.go:171","msg":"trace[483246819] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"144.395941ms","start":"2026-03-03T21:15:43.672437Z","end":"2026-03-03T21:15:43.816833Z","steps":["trace[483246819] 'process raft request' (duration: 143.934821ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.817221Z","caller":"traceutil/trace.go:171","msg":"trace[482497688] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"141.964976ms","start":"2026-03-03T21:15:43.675192Z","end":"2026-03-03T21:15:43.817157Z","steps":["trace[482497688] 'process raft request' (duration: 141.548237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:43.817394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.67623ms","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-03-03T21:15:43.817491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.553543ms","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-03-03T21:15:43.817937Z","caller":"traceutil/trace.go:171","msg":"trace[1320685069] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:707; }","duration":"154.235212ms","start":"2026-03-03T21:15:43.663675Z","end":"2026-03-03T21:15:43.81791Z","steps":["trace[1320685069] 'agreement among raft nodes before linearized reading' (duration: 153.554547ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.818077Z","caller":"traceutil/trace.go:171","msg":"trace[1873995865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:707; }","duration":"156.127916ms","start":"2026-03-03T21:15:43.661903Z","end":"2026-03-03T21:15:43.818031Z","steps":["trace[1873995865] 'agreement among raft nodes before linearized reading' (duration: 155.536663ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.817542Z","caller":"traceutil/trace.go:171","msg":"trace[388376086] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"144.373919ms","start":"2026-03-03T21:15:43.673151Z","end":"2026-03-03T21:15:43.817525Z","steps":["trace[388376086] 'process raft request' (duration: 143.49509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:43.81756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.04713ms","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-03-03T21:15:43.818534Z","caller":"traceutil/trace.go:171","msg":"trace[612371859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:707; }","duration":"156.244467ms","start":"2026-03-03T21:15:43.662267Z","end":"2026-03-03T21:15:43.818511Z","steps":["trace[612371859] 'agreement among raft nodes before linearized reading' (duration: 155.02572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:43.817642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.0321ms","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-03-03T21:15:43.818709Z","caller":"traceutil/trace.go:171","msg":"trace[13980054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:707; }","duration":"156.138374ms","start":"2026-03-03T21:15:43.662554Z","end":"2026-03-03T21:15:43.818693Z","steps":["trace[13980054] 'agreement among raft nodes before linearized reading' (duration: 154.940518ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.878136Z","caller":"traceutil/trace.go:171","msg":"trace[1891637402] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"136.303169ms","start":"2026-03-03T21:15:43.741756Z","end":"2026-03-03T21:15:43.878059Z","steps":["trace[1891637402] 'process raft request' (duration: 135.846228ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.878264Z","caller":"traceutil/trace.go:171","msg":"trace[1245652274] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"136.332889ms","start":"2026-03-03T21:15:43.741914Z","end":"2026-03-03T21:15:43.878247Z","steps":["trace[1245652274] 'process raft request' (duration: 136.198716ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.87832Z","caller":"traceutil/trace.go:171","msg":"trace[1883053274] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"135.018779ms","start":"2026-03-03T21:15:43.743292Z","end":"2026-03-03T21:15:43.87831Z","steps":["trace[1883053274] 'process raft request' (duration: 134.908527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:43.879846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.392355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-xbdlc\" ","response":"range_response_count:1 size:4872"} {"level":"info","ts":"2026-03-03T21:15:43.880031Z","caller":"traceutil/trace.go:171","msg":"trace[589755519] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-xbdlc; range_end:; response_count:1; response_revision:710; }","duration":"138.682221ms","start":"2026-03-03T21:15:43.741327Z","end":"2026-03-03T21:15:43.880009Z","steps":["trace[589755519] 'agreement among raft nodes before linearized reading' (duration: 137.081296ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.943241Z","caller":"traceutil/trace.go:171","msg":"trace[1666568237] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"119.422866ms","start":"2026-03-03T21:15:43.823796Z","end":"2026-03-03T21:15:43.943219Z","steps":["trace[1666568237] 'process raft request' (duration: 119.365545ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.943301Z","caller":"traceutil/trace.go:171","msg":"trace[100311941] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"119.768054ms","start":"2026-03-03T21:15:43.823464Z","end":"2026-03-03T21:15:43.943232Z","steps":["trace[100311941] 'process raft request' (duration: 119.541479ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.943419Z","caller":"traceutil/trace.go:171","msg":"trace[203716859] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"119.092299ms","start":"2026-03-03T21:15:43.824316Z","end":"2026-03-03T21:15:43.943408Z","steps":["trace[203716859] 'process raft request' (duration: 118.875135ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:43.943276Z","caller":"traceutil/trace.go:171","msg":"trace[1940155735] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"119.686423ms","start":"2026-03-03T21:15:43.823546Z","end":"2026-03-03T21:15:43.943233Z","steps":["trace[1940155735] 'process raft request' (duration: 119.58235ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:48.158734Z","caller":"traceutil/trace.go:171","msg":"trace[646850180] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:752; }","duration":"267.694062ms","start":"2026-03-03T21:15:47.891019Z","end":"2026-03-03T21:15:48.158713Z","steps":["trace[646850180] 'read index received' (duration: 267.475417ms)","trace[646850180] 'applied index is now lower than readState.Index' (duration: 216.935µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:15:48.158881Z","caller":"traceutil/trace.go:171","msg":"trace[1266217221] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"546.706798ms","start":"2026-03-03T21:15:47.612138Z","end":"2026-03-03T21:15:48.158845Z","steps":["trace[1266217221] 'process raft request' (duration: 546.423582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:48.159045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.976011ms","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-03-03T21:15:48.159087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:47.612114Z","time spent":"546.852251ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:15:48.159103Z","caller":"traceutil/trace.go:171","msg":"trace[313820262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:729; }","duration":"199.087493ms","start":"2026-03-03T21:15:47.959995Z","end":"2026-03-03T21:15:48.159083Z","steps":["trace[313820262] 'agreement among raft nodes before linearized reading' (duration: 198.912559ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:48.159184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.321116ms","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-03-03T21:15:48.159264Z","caller":"traceutil/trace.go:171","msg":"trace[76749605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:729; }","duration":"198.466759ms","start":"2026-03-03T21:15:47.96077Z","end":"2026-03-03T21:15:48.159237Z","steps":["trace[76749605] 'agreement among raft nodes before linearized reading' (duration: 198.263224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:48.159349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.210496ms","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-03-03T21:15:48.159461Z","caller":"traceutil/trace.go:171","msg":"trace[264484036] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:729; }","duration":"199.347108ms","start":"2026-03-03T21:15:47.960066Z","end":"2026-03-03T21:15:48.159413Z","steps":["trace[264484036] 'agreement among raft nodes before linearized reading' (duration: 198.994871ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:48.159765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.622552ms","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-03-03T21:15:48.159834Z","caller":"traceutil/trace.go:171","msg":"trace[280468808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:729; }","duration":"198.718155ms","start":"2026-03-03T21:15:47.961105Z","end":"2026-03-03T21:15:48.159824Z","steps":["trace[280468808] 'agreement among raft nodes before linearized reading' (duration: 197.909797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:48.159904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.876578ms","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-03-03T21:15:48.159957Z","caller":"traceutil/trace.go:171","msg":"trace[1715540498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:729; }","duration":"268.96511ms","start":"2026-03-03T21:15:47.890975Z","end":"2026-03-03T21:15:48.15994Z","steps":["trace[1715540498] 'agreement among raft nodes before linearized reading' (duration: 267.871936ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:48.824828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.691022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:15:48.82516Z","caller":"traceutil/trace.go:171","msg":"trace[977919209] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"661.329678ms","start":"2026-03-03T21:15:48.163814Z","end":"2026-03-03T21:15:48.825144Z","steps":["trace[977919209] 'process raft request' (duration: 455.261107ms)","trace[977919209] 'compare' (duration: 205.60808ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:15:48.825215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:48.163784Z","time spent":"661.40569ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:15:48.825351Z","caller":"traceutil/trace.go:171","msg":"trace[1009894262] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"661.065362ms","start":"2026-03-03T21:15:48.164251Z","end":"2026-03-03T21:15:48.825317Z","steps":["trace[1009894262] 'process raft request' (duration: 660.650613ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:48.82552Z","caller":"traceutil/trace.go:171","msg":"trace[1689964746] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"661.147055ms","start":"2026-03-03T21:15:48.164364Z","end":"2026-03-03T21:15:48.825511Z","steps":["trace[1689964746] 'process raft request' (duration: 660.568342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:48.825581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:48.164225Z","time spent":"661.199666ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:15:48.825646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:48.164356Z","time spent":"661.189895ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:15:48.826126Z","caller":"traceutil/trace.go:171","msg":"trace[1771632766] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"661.700446ms","start":"2026-03-03T21:15:48.164415Z","end":"2026-03-03T21:15:48.826115Z","steps":["trace[1771632766] 'process raft request' (duration: 661.042371ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:48.826137Z","caller":"traceutil/trace.go:171","msg":"trace[1227737520] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"660.407518ms","start":"2026-03-03T21:15:48.165706Z","end":"2026-03-03T21:15:48.826114Z","steps":["trace[1227737520] 'process raft request' (duration: 660.305046ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:48.826175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:48.164408Z","time spent":"661.743828ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:15:48.826271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:48.165694Z","time spent":"660.50616ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:15:51.592851Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198072164442644,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T21:15:52.093747Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198072164442644,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T21:15:52.245257Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.412794956s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T21:15:52.245557Z","caller":"traceutil/trace.go:171","msg":"trace[709799367] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"1.413175525s","start":"2026-03-03T21:15:50.832363Z","end":"2026-03-03T21:15:52.245538Z","steps":["trace[709799367] 'process raft request' (duration: 1.413052103s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:52.245671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:50.832346Z","time spent":"1.413266818s","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:15:52.271324Z","caller":"traceutil/trace.go:171","msg":"trace[517528255] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:760; }","duration":"1.178920839s","start":"2026-03-03T21:15:51.092385Z","end":"2026-03-03T21:15:52.271306Z","steps":["trace[517528255] 'read index received' (duration: 1.153052525s)","trace[517528255] 'applied index is now lower than readState.Index' (duration: 25.867444ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:15:52.271568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.179168784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:15:52.271598Z","caller":"traceutil/trace.go:171","msg":"trace[203694811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"1.179250516s","start":"2026-03-03T21:15:51.092337Z","end":"2026-03-03T21:15:52.271587Z","steps":["trace[203694811] 'agreement among raft nodes before linearized reading' (duration: 1.179052272s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:52.271624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:51.092253Z","time spent":"1.179365018s","remote":"127.0.0.1:45862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T21:15:52.27175Z","caller":"traceutil/trace.go:171","msg":"trace[1433891255] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"1.436350489s","start":"2026-03-03T21:15:50.835355Z","end":"2026-03-03T21:15:52.271705Z","steps":["trace[1433891255] 'process raft request' (duration: 1.435876568s)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:15:52.271925Z","caller":"traceutil/trace.go:171","msg":"trace[1640387574] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"1.433718799s","start":"2026-03-03T21:15:50.838193Z","end":"2026-03-03T21:15:52.271912Z","steps":["trace[1640387574] 'process raft request' (duration: 1.433085955s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:52.271956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:50.835326Z","time spent":"1.436517892s","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:15:52.272008Z","caller":"traceutil/trace.go:171","msg":"trace[1066258179] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"1.436700616s","start":"2026-03-03T21:15:50.835295Z","end":"2026-03-03T21:15:52.271995Z","steps":["trace[1066258179] 'process raft request' (duration: 1.435829137s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:52.27202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:50.838172Z","time spent":"1.43378449s","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:15:52.272034Z","caller":"traceutil/trace.go:171","msg":"trace[1315751475] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"1.436349608s","start":"2026-03-03T21:15:50.835662Z","end":"2026-03-03T21:15:52.272012Z","steps":["trace[1315751475] 'process raft request' (duration: 1.435592512s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:52.272065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:50.835264Z","time spent":"1.436768318s","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:15:52.272128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:50.83565Z","time spent":"1.43643257s","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:15:53.186055Z","caller":"traceutil/trace.go:171","msg":"trace[390195021] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"530.070803ms","start":"2026-03-03T21:15:52.655965Z","end":"2026-03-03T21:15:53.186036Z","steps":["trace[390195021] 'process raft request' (duration: 529.92003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:15:53.186195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:15:52.655946Z","time spent":"530.171346ms","remote":"127.0.0.1:46066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5170,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:15:53.196601Z","caller":"traceutil/trace.go:171","msg":"trace[2105391456] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"107.725281ms","start":"2026-03-03T21:15:53.088856Z","end":"2026-03-03T21:15:53.196581Z","steps":["trace[2105391456] 'process raft request' (duration: 107.599088ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:16:16.675675Z","caller":"traceutil/trace.go:171","msg":"trace[1224050964] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"133.820958ms","start":"2026-03-03T21:16:16.541821Z","end":"2026-03-03T21:16:16.675642Z","steps":["trace[1224050964] 'process raft request' (duration: 133.605783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:16.676178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.317919ms","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-03-03T21:16:16.676341Z","caller":"traceutil/trace.go:171","msg":"trace[868358993] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"126.551294ms","start":"2026-03-03T21:16:16.549766Z","end":"2026-03-03T21:16:16.676317Z","steps":["trace[868358993] 'agreement among raft nodes before linearized reading' (duration: 126.191655ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:16:16.675922Z","caller":"traceutil/trace.go:171","msg":"trace[364550550] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"126.014432ms","start":"2026-03-03T21:16:16.549823Z","end":"2026-03-03T21:16:16.675837Z","steps":["trace[364550550] 'read index received' (duration: 125.568602ms)","trace[364550550] 'applied index is now lower than readState.Index' (duration: 441.04µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:16:16.676739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.589914ms","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-03-03T21:16:16.676797Z","caller":"traceutil/trace.go:171","msg":"trace[1785432795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:809; }","duration":"126.680256ms","start":"2026-03-03T21:16:16.550101Z","end":"2026-03-03T21:16:16.676781Z","steps":["trace[1785432795] 'agreement among raft nodes before linearized reading' (duration: 126.482631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:16.676821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.508803ms","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-03-03T21:16:16.676865Z","caller":"traceutil/trace.go:171","msg":"trace[745036977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"126.553694ms","start":"2026-03-03T21:16:16.5503Z","end":"2026-03-03T21:16:16.676853Z","steps":["trace[745036977] 'agreement among raft nodes before linearized reading' (duration: 126.443121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:16.676876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.680842ms","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-03-03T21:16:16.676943Z","caller":"traceutil/trace.go:171","msg":"trace[694662815] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:809; }","duration":"100.841395ms","start":"2026-03-03T21:16:16.576092Z","end":"2026-03-03T21:16:16.676933Z","steps":["trace[694662815] 'agreement among raft nodes before linearized reading' (duration: 100.634711ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:16:16.978616Z","caller":"traceutil/trace.go:171","msg":"trace[236598480] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"297.767617ms","start":"2026-03-03T21:16:16.680819Z","end":"2026-03-03T21:16:16.978587Z","steps":["trace[236598480] 'process raft request' (duration: 292.815845ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:16:16.996259Z","caller":"traceutil/trace.go:171","msg":"trace[594584698] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"315.072714ms","start":"2026-03-03T21:16:16.681166Z","end":"2026-03-03T21:16:16.996238Z","steps":["trace[594584698] 'process raft request' (duration: 314.992172ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:16:16.996311Z","caller":"traceutil/trace.go:171","msg":"trace[856607682] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"315.184426ms","start":"2026-03-03T21:16:16.681085Z","end":"2026-03-03T21:16:16.99627Z","steps":["trace[856607682] 'process raft request' (duration: 314.972172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:16.9964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:16.681141Z","time spent":"315.182176ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:16:16.996554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:16.68106Z","time spent":"315.356651ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:16:16.996616Z","caller":"traceutil/trace.go:171","msg":"trace[660680637] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"315.087614ms","start":"2026-03-03T21:16:16.68145Z","end":"2026-03-03T21:16:16.996538Z","steps":["trace[660680637] 'process raft request' (duration: 314.742137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:16.997078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:16.681394Z","time spent":"315.474743ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:16:26.579129Z","caller":"traceutil/trace.go:171","msg":"trace[1937144233] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"109.556386ms","start":"2026-03-03T21:16:26.469551Z","end":"2026-03-03T21:16:26.579107Z","steps":["trace[1937144233] 'process raft request' (duration: 109.399872ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:16:26.686482Z","caller":"traceutil/trace.go:171","msg":"trace[1026194492] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:884; }","duration":"105.048042ms","start":"2026-03-03T21:16:26.581407Z","end":"2026-03-03T21:16:26.686455Z","steps":["trace[1026194492] 'read index received' (duration: 96.609169ms)","trace[1026194492] 'applied index is now lower than readState.Index' (duration: 8.437813ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:16:26.686598Z","caller":"traceutil/trace.go:171","msg":"trace[647465251] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"105.304088ms","start":"2026-03-03T21:16:26.581279Z","end":"2026-03-03T21:16:26.686583Z","steps":["trace[647465251] 'process raft request' (duration: 96.770683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:26.686786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.362389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:104024"} {"level":"info","ts":"2026-03-03T21:16:26.686851Z","caller":"traceutil/trace.go:171","msg":"trace[1569192667] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:852; }","duration":"106.124116ms","start":"2026-03-03T21:16:26.580706Z","end":"2026-03-03T21:16:26.68683Z","steps":["trace[1569192667] 'agreement among raft nodes before linearized reading' (duration: 105.885051ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:16:29.061115Z","caller":"traceutil/trace.go:171","msg":"trace[1960131994] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"314.680825ms","start":"2026-03-03T21:16:28.746407Z","end":"2026-03-03T21:16:29.061088Z","steps":["trace[1960131994] 'process raft request' (duration: 314.468359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:29.061286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:28.746384Z","time spent":"314.813068ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:16:29.450757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.021514ms","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-03-03T21:16:29.450862Z","caller":"traceutil/trace.go:171","msg":"trace[1848915825] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:894; }","duration":"218.141917ms","start":"2026-03-03T21:16:29.232703Z","end":"2026-03-03T21:16:29.450845Z","steps":["trace[1848915825] 'range keys from in-memory index tree' (duration: 218.005964ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:16:29.451073Z","caller":"traceutil/trace.go:171","msg":"trace[935807897] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"365.495557ms","start":"2026-03-03T21:16:29.085561Z","end":"2026-03-03T21:16:29.451056Z","steps":["trace[935807897] 'read index received' (duration: 349.116982ms)","trace[935807897] 'applied index is now lower than readState.Index' (duration: 16.377995ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:16:29.451162Z","caller":"traceutil/trace.go:171","msg":"trace[827616068] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"384.272086ms","start":"2026-03-03T21:16:29.066881Z","end":"2026-03-03T21:16:29.451154Z","steps":["trace[827616068] 'process raft request' (duration: 367.724748ms)","trace[827616068] 'compare' (duration: 16.112468ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:16:29.451242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:29.066869Z","time spent":"384.310247ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:16:29.451469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.905396ms","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-03-03T21:16:29.451493Z","caller":"traceutil/trace.go:171","msg":"trace[495353124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"365.950288ms","start":"2026-03-03T21:16:29.085535Z","end":"2026-03-03T21:16:29.451485Z","steps":["trace[495353124] 'agreement among raft nodes before linearized reading' (duration: 365.865696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:29.451511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:29.085521Z","time spent":"365.986588ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T21:16:29.451523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.407461ms","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-03-03T21:16:29.451558Z","caller":"traceutil/trace.go:171","msg":"trace[1785123874] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"364.459333ms","start":"2026-03-03T21:16:29.08709Z","end":"2026-03-03T21:16:29.451549Z","steps":["trace[1785123874] 'agreement among raft nodes before linearized reading' (duration: 364.362881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:29.451584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:29.087082Z","time spent":"364.496674ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-03T21:16:29.451827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.534665ms","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-03-03T21:16:29.451894Z","caller":"traceutil/trace.go:171","msg":"trace[1981403112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:896; }","duration":"364.636716ms","start":"2026-03-03T21:16:29.087243Z","end":"2026-03-03T21:16:29.451879Z","steps":["trace[1981403112] 'agreement among raft nodes before linearized reading' (duration: 364.452572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:29.451933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:29.087225Z","time spent":"364.700958ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T21:16:31.592956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198072164443060,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T21:16:31.929608Z","caller":"traceutil/trace.go:171","msg":"trace[1886605355] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"836.845372ms","start":"2026-03-03T21:16:31.092742Z","end":"2026-03-03T21:16:31.929588Z","steps":["trace[1886605355] 'read index received' (duration: 836.706849ms)","trace[1886605355] 'applied index is now lower than readState.Index' (duration: 137.813µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:16:31.929737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.976626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:16:31.92974Z","caller":"traceutil/trace.go:171","msg":"trace[1151550467] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"860.958505ms","start":"2026-03-03T21:16:31.068758Z","end":"2026-03-03T21:16:31.929716Z","steps":["trace[1151550467] 'process raft request' (duration: 860.710299ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:16:31.929764Z","caller":"traceutil/trace.go:171","msg":"trace[827005387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"837.062148ms","start":"2026-03-03T21:16:31.092694Z","end":"2026-03-03T21:16:31.929756Z","steps":["trace[827005387] 'agreement among raft nodes before linearized reading' (duration: 836.998627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:31.930055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:31.068729Z","time spent":"861.263601ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:16:31.9301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:31.092666Z","time spent":"837.425167ms","remote":"127.0.0.1:45862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T21:16:31.929841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.684456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T21:16:31.930272Z","caller":"traceutil/trace.go:171","msg":"trace[2099422663] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:901; }","duration":"752.157817ms","start":"2026-03-03T21:16:31.178089Z","end":"2026-03-03T21:16:31.930247Z","steps":["trace[2099422663] 'agreement among raft nodes before linearized reading' (duration: 751.645196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:31.930329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:31.178071Z","time spent":"752.243359ms","remote":"127.0.0.1:46188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-03T21:16:31.92989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.338095ms","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-03-03T21:16:31.930413Z","caller":"traceutil/trace.go:171","msg":"trace[93352948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:901; }","duration":"474.875427ms","start":"2026-03-03T21:16:31.455518Z","end":"2026-03-03T21:16:31.930393Z","steps":["trace[93352948] 'agreement among raft nodes before linearized reading' (duration: 474.282324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:31.930466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:31.455501Z","time spent":"474.949159ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T21:16:31.929906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.708378ms","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-03-03T21:16:31.930648Z","caller":"traceutil/trace.go:171","msg":"trace[1305264252] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:901; }","duration":"452.466565ms","start":"2026-03-03T21:16:31.478171Z","end":"2026-03-03T21:16:31.930637Z","steps":["trace[1305264252] 'agreement among raft nodes before linearized reading' (duration: 451.623096ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:31.930685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:31.478165Z","time spent":"452.509696ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-03T21:16:31.929917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.742589ms","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-03-03T21:16:31.930762Z","caller":"traceutil/trace.go:171","msg":"trace[1197905896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:901; }","duration":"452.640279ms","start":"2026-03-03T21:16:31.478112Z","end":"2026-03-03T21:16:31.930752Z","steps":["trace[1197905896] 'agreement among raft nodes before linearized reading' (duration: 451.655637ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:31.930789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:31.478089Z","time spent":"452.6928ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T21:16:31.929934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.249257ms","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-03-03T21:16:31.930854Z","caller":"traceutil/trace.go:171","msg":"trace[189215554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:901; }","duration":"459.209839ms","start":"2026-03-03T21:16:31.471636Z","end":"2026-03-03T21:16:31.930846Z","steps":["trace[189215554] 'agreement among raft nodes before linearized reading' (duration: 458.145335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:31.930882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:31.471626Z","time spent":"459.25004ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-03T21:16:34.744155Z","caller":"traceutil/trace.go:171","msg":"trace[1160124594] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"154.060933ms","start":"2026-03-03T21:16:34.590062Z","end":"2026-03-03T21:16:34.744123Z","steps":["trace[1160124594] 'read index received' (duration: 116.223578ms)","trace[1160124594] 'applied index is now lower than readState.Index' (duration: 37.835274ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:16:34.744276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.192925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:16:34.744326Z","caller":"traceutil/trace.go:171","msg":"trace[979318347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"154.295408ms","start":"2026-03-03T21:16:34.590021Z","end":"2026-03-03T21:16:34.744316Z","steps":["trace[979318347] 'agreement among raft nodes before linearized reading' (duration: 154.201776ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:16:40.310867Z","caller":"traceutil/trace.go:171","msg":"trace[338057487] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:962; }","duration":"214.916963ms","start":"2026-03-03T21:16:40.095931Z","end":"2026-03-03T21:16:40.310848Z","steps":["trace[338057487] 'read index received' (duration: 20.963859ms)","trace[338057487] 'applied index is now lower than readState.Index' (duration: 193.952504ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:16:40.310969Z","caller":"traceutil/trace.go:171","msg":"trace[453781125] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"216.517961ms","start":"2026-03-03T21:16:40.09442Z","end":"2026-03-03T21:16:40.310938Z","steps":["trace[453781125] 'process raft request' (duration: 179.240078ms)","trace[453781125] 'compare' (duration: 37.105299ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:16:40.311031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.090717ms","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-03-03T21:16:40.31105Z","caller":"traceutil/trace.go:171","msg":"trace[231850066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:926; }","duration":"215.153619ms","start":"2026-03-03T21:16:40.09589Z","end":"2026-03-03T21:16:40.311044Z","steps":["trace[231850066] 'agreement among raft nodes before linearized reading' (duration: 215.055537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:40.311226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.791996ms","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-03-03T21:16:40.311287Z","caller":"traceutil/trace.go:171","msg":"trace[666112169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:926; }","duration":"202.892608ms","start":"2026-03-03T21:16:40.108381Z","end":"2026-03-03T21:16:40.311273Z","steps":["trace[666112169] 'agreement among raft nodes before linearized reading' (duration: 202.659913ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:16:46.223273Z","caller":"traceutil/trace.go:171","msg":"trace[1400559337] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"106.523125ms","start":"2026-03-03T21:16:46.116706Z","end":"2026-03-03T21:16:46.223229Z","steps":["trace[1400559337] 'process raft request' (duration: 106.218719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:46.559317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.666884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:16:46.559476Z","caller":"traceutil/trace.go:171","msg":"trace[811776788] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"194.283431ms","start":"2026-03-03T21:16:46.365165Z","end":"2026-03-03T21:16:46.559448Z","steps":["trace[811776788] 'process raft request' (duration: 75.367302ms)","trace[811776788] 'compare' (duration: 118.49613ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:16:50.246968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:49.415756Z","time spent":"831.208055ms","remote":"127.0.0.1:45902","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-03T21:16:50.532676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.922305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:16:50.532854Z","caller":"traceutil/trace.go:171","msg":"trace[2133073109] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"932.271695ms","start":"2026-03-03T21:16:49.600552Z","end":"2026-03-03T21:16:50.532824Z","steps":["trace[2133073109] 'process raft request' (duration: 811.086245ms)","trace[2133073109] 'compare' (duration: 120.648018ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:16:50.532939Z","caller":"traceutil/trace.go:171","msg":"trace[1522247395] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"284.748029ms","start":"2026-03-03T21:16:50.248172Z","end":"2026-03-03T21:16:50.53292Z","steps":["trace[1522247395] 'process raft request' (duration: 284.622976ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:16:50.532932Z","caller":"traceutil/trace.go:171","msg":"trace[1353184314] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"557.985237ms","start":"2026-03-03T21:16:49.974924Z","end":"2026-03-03T21:16:50.532909Z","steps":["trace[1353184314] 'read index received' (duration: 272.307726ms)","trace[1353184314] 'applied index is now lower than readState.Index' (duration: 285.675871ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:16:50.532979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:49.600526Z","time spent":"932.381147ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:16:50.533181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.209492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T21:16:50.533289Z","caller":"traceutil/trace.go:171","msg":"trace[107885505] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:952; }","duration":"558.380036ms","start":"2026-03-03T21:16:49.974884Z","end":"2026-03-03T21:16:50.533264Z","steps":["trace[107885505] 'agreement among raft nodes before linearized reading' (duration: 558.11337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:50.533338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:49.974868Z","time spent":"558.459137ms","remote":"127.0.0.1:46162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-03-03T21:16:50.556488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.522675ms","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-03-03T21:16:50.55655Z","caller":"traceutil/trace.go:171","msg":"trace[1856489617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:953; }","duration":"106.612518ms","start":"2026-03-03T21:16:50.449921Z","end":"2026-03-03T21:16:50.556534Z","steps":["trace[1856489617] 'agreement among raft nodes before linearized reading' (duration: 106.437454ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:50.556684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.798777ms","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-03-03T21:16:50.556702Z","caller":"traceutil/trace.go:171","msg":"trace[1834390914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:953; }","duration":"118.867318ms","start":"2026-03-03T21:16:50.43783Z","end":"2026-03-03T21:16:50.556698Z","steps":["trace[1834390914] 'agreement among raft nodes before linearized reading' (duration: 118.821447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:50.55678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.607304ms","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-03-03T21:16:50.556797Z","caller":"traceutil/trace.go:171","msg":"trace[5243362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:953; }","duration":"154.660365ms","start":"2026-03-03T21:16:50.402133Z","end":"2026-03-03T21:16:50.556794Z","steps":["trace[5243362] 'agreement among raft nodes before linearized reading' (duration: 154.619144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:50.55688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.15805ms","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-03-03T21:16:50.556896Z","caller":"traceutil/trace.go:171","msg":"trace[682415530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:953; }","duration":"261.210272ms","start":"2026-03-03T21:16:50.295682Z","end":"2026-03-03T21:16:50.556892Z","steps":["trace[682415530] 'agreement among raft nodes before linearized reading' (duration: 261.166531ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:50.556967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.509458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T21:16:50.556982Z","caller":"traceutil/trace.go:171","msg":"trace[782204789] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:953; }","duration":"355.550849ms","start":"2026-03-03T21:16:50.201425Z","end":"2026-03-03T21:16:50.556976Z","steps":["trace[782204789] 'agreement among raft nodes before linearized reading' (duration: 355.522658ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:50.556996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:50.201404Z","time spent":"355.58611ms","remote":"127.0.0.1:40794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-03-03T21:16:50.727135Z","caller":"traceutil/trace.go:171","msg":"trace[1445096660] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:993; }","duration":"165.641538ms","start":"2026-03-03T21:16:50.561469Z","end":"2026-03-03T21:16:50.72711Z","steps":["trace[1445096660] 'read index received' (duration: 24.758696ms)","trace[1445096660] 'applied index is now lower than readState.Index' (duration: 140.881602ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:16:50.727889Z","caller":"traceutil/trace.go:171","msg":"trace[364620664] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"167.02312ms","start":"2026-03-03T21:16:50.560834Z","end":"2026-03-03T21:16:50.727857Z","steps":["trace[364620664] 'process raft request' (duration: 166.16107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:50.727956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.443116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-03T21:16:50.728107Z","caller":"traceutil/trace.go:171","msg":"trace[1956586306] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:957; }","duration":"166.636401ms","start":"2026-03-03T21:16:50.561443Z","end":"2026-03-03T21:16:50.72808Z","steps":["trace[1956586306] 'agreement among raft nodes before linearized reading' (duration: 166.327364ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:16:50.728165Z","caller":"traceutil/trace.go:171","msg":"trace[1046873538] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"167.431898ms","start":"2026-03-03T21:16:50.560718Z","end":"2026-03-03T21:16:50.72815Z","steps":["trace[1046873538] 'process raft request' (duration: 92.848052ms)","trace[1046873538] 'compare' (duration: 73.300267ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:16:50.72839Z","caller":"traceutil/trace.go:171","msg":"trace[703611281] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"166.809513ms","start":"2026-03-03T21:16:50.561571Z","end":"2026-03-03T21:16:50.72838Z","steps":["trace[703611281] 'process raft request' (duration: 165.488654ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:50.728392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.072176ms","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-03-03T21:16:50.728551Z","caller":"traceutil/trace.go:171","msg":"trace[1148633386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:957; }","duration":"138.206009ms","start":"2026-03-03T21:16:50.590306Z","end":"2026-03-03T21:16:50.728512Z","steps":["trace[1148633386] 'agreement among raft nodes before linearized reading' (duration: 137.950114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:50.970047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.496296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:16:50.970293Z","caller":"traceutil/trace.go:171","msg":"trace[977428480] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"232.444644ms","start":"2026-03-03T21:16:50.737824Z","end":"2026-03-03T21:16:50.970269Z","steps":["trace[977428480] 'process raft request' (duration: 114.60163ms)","trace[977428480] 'compare' (duration: 117.308182ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:16:50.970502Z","caller":"traceutil/trace.go:171","msg":"trace[424407879] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:998; }","duration":"191.362955ms","start":"2026-03-03T21:16:50.779109Z","end":"2026-03-03T21:16:50.970472Z","steps":["trace[424407879] 'read index received' (duration: 73.329486ms)","trace[424407879] 'applied index is now lower than readState.Index' (duration: 118.031949ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:16:50.97064Z","caller":"traceutil/trace.go:171","msg":"trace[1909870323] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"207.416213ms","start":"2026-03-03T21:16:50.763213Z","end":"2026-03-03T21:16:50.970629Z","steps":["trace[1909870323] 'process raft request' (duration: 206.986423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:50.971067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.923608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:16:50.971131Z","caller":"traceutil/trace.go:171","msg":"trace[1960771655] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:960; }","duration":"192.01339ms","start":"2026-03-03T21:16:50.7791Z","end":"2026-03-03T21:16:50.971113Z","steps":["trace[1960771655] 'agreement among raft nodes before linearized reading' (duration: 191.863377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:50.971543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.058725ms","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-03-03T21:16:50.971602Z","caller":"traceutil/trace.go:171","msg":"trace[2146532974] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:960; }","duration":"124.166778ms","start":"2026-03-03T21:16:50.847419Z","end":"2026-03-03T21:16:50.971585Z","steps":["trace[2146532974] 'agreement among raft nodes before linearized reading' (duration: 124.059145ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:50.971756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.921807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:16:50.971872Z","caller":"traceutil/trace.go:171","msg":"trace[284939572] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:960; }","duration":"188.07242ms","start":"2026-03-03T21:16:50.783779Z","end":"2026-03-03T21:16:50.971851Z","steps":["trace[284939572] 'agreement among raft nodes before linearized reading' (duration: 187.044847ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:51.710728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.616477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:16:51.710812Z","caller":"traceutil/trace.go:171","msg":"trace[1399368848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"616.75478ms","start":"2026-03-03T21:16:51.094038Z","end":"2026-03-03T21:16:51.710793Z","steps":["trace[1399368848] 'range keys from in-memory index tree' (duration: 616.528886ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:51.710852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:51.094014Z","time spent":"616.829773ms","remote":"127.0.0.1:45862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T21:16:52.778163Z","caller":"traceutil/trace.go:171","msg":"trace[2128207658] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"184.646822ms","start":"2026-03-03T21:16:52.593487Z","end":"2026-03-03T21:16:52.778134Z","steps":["trace[2128207658] 'process raft request' (duration: 184.5293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:53.210347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.937349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:16:53.210455Z","caller":"traceutil/trace.go:171","msg":"trace[1812774691] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"429.160551ms","start":"2026-03-03T21:16:52.781275Z","end":"2026-03-03T21:16:53.210435Z","steps":["trace[1812774691] 'process raft request' (duration: 240.058279ms)","trace[1812774691] 'compare' (duration: 188.744734ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:16:53.210513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:52.781257Z","time spent":"429.228803ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:16:53.760485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.783304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:16:53.760607Z","caller":"traceutil/trace.go:171","msg":"trace[520474934] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"979.178106ms","start":"2026-03-03T21:16:52.781416Z","end":"2026-03-03T21:16:53.760594Z","steps":["trace[520474934] 'process raft request' (duration: 602.213648ms)","trace[520474934] 'compare' (duration: 376.689102ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:16:53.760666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:52.781397Z","time spent":"979.251627ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:16:53.760686Z","caller":"traceutil/trace.go:171","msg":"trace[1460492411] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1001; }","duration":"784.450955ms","start":"2026-03-03T21:16:52.976216Z","end":"2026-03-03T21:16:53.760667Z","steps":["trace[1460492411] 'read index received' (duration: 45.130273ms)","trace[1460492411] 'applied index is now lower than readState.Index' (duration: 739.319102ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:16:53.760713Z","caller":"traceutil/trace.go:171","msg":"trace[1819335868] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"978.892559ms","start":"2026-03-03T21:16:52.781795Z","end":"2026-03-03T21:16:53.760688Z","steps":["trace[1819335868] 'process raft request' (duration: 978.771477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:53.760846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:52.781787Z","time spent":"978.972313ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:16:53.76086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.692092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:16:53.760902Z","caller":"traceutil/trace.go:171","msg":"trace[365516879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"669.774494ms","start":"2026-03-03T21:16:53.091115Z","end":"2026-03-03T21:16:53.76089Z","steps":["trace[365516879] 'agreement among raft nodes before linearized reading' (duration: 669.708692ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:53.760857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.64759ms","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-03-03T21:16:53.760972Z","caller":"traceutil/trace.go:171","msg":"trace[792993297] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:964; }","duration":"784.766133ms","start":"2026-03-03T21:16:52.976168Z","end":"2026-03-03T21:16:53.760934Z","steps":["trace[792993297] 'agreement among raft nodes before linearized reading' (duration: 784.593069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:16:53.760998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:53.091093Z","time spent":"669.841645ms","remote":"127.0.0.1:45876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T21:16:53.761008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:16:52.976148Z","time spent":"784.850554ms","remote":"127.0.0.1:46120","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-03-03T21:16:55.769656Z","caller":"traceutil/trace.go:171","msg":"trace[624891098] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"283.420531ms","start":"2026-03-03T21:16:55.486215Z","end":"2026-03-03T21:16:55.769635Z","steps":["trace[624891098] 'process raft request' (duration: 283.223676ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:16:59.570786Z","caller":"traceutil/trace.go:171","msg":"trace[891699248] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"103.313752ms","start":"2026-03-03T21:16:59.467429Z","end":"2026-03-03T21:16:59.570743Z","steps":["trace[891699248] 'process raft request' (duration: 88.024612ms)","trace[891699248] 'compare' (duration: 14.997003ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:16:59.570815Z","caller":"traceutil/trace.go:171","msg":"trace[1886730555] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"100.974719ms","start":"2026-03-03T21:16:59.469811Z","end":"2026-03-03T21:16:59.570785Z","steps":["trace[1886730555] 'process raft request' (duration: 100.867426ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:02.017297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.67331ms","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-03-03T21:17:02.017406Z","caller":"traceutil/trace.go:171","msg":"trace[1318353900] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1043; }","duration":"211.853083ms","start":"2026-03-03T21:17:01.80553Z","end":"2026-03-03T21:17:02.017383Z","steps":["trace[1318353900] 'range keys from in-memory index tree' (duration: 211.417534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:02.017726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.816289ms","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-03-03T21:17:02.017784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.763268ms","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-03-03T21:17:02.017838Z","caller":"traceutil/trace.go:171","msg":"trace[601834588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1043; }","duration":"211.000043ms","start":"2026-03-03T21:17:01.806818Z","end":"2026-03-03T21:17:02.017818Z","steps":["trace[601834588] 'range keys from in-memory index tree' (duration: 210.467222ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:02.017909Z","caller":"traceutil/trace.go:171","msg":"trace[190732707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1043; }","duration":"210.860811ms","start":"2026-03-03T21:17:01.806973Z","end":"2026-03-03T21:17:02.017834Z","steps":["trace[190732707] 'range keys from in-memory index tree' (duration: 210.535903ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:02.594662Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198072164443364,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T21:17:02.76036Z","caller":"traceutil/trace.go:171","msg":"trace[1862115824] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"739.21884ms","start":"2026-03-03T21:17:02.02112Z","end":"2026-03-03T21:17:02.760339Z","steps":["trace[1862115824] 'process raft request' (duration: 739.108998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:02.760466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:02.0211Z","time spent":"739.310583ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:17:02.778245Z","caller":"traceutil/trace.go:171","msg":"trace[1991677982] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"757.032408ms","start":"2026-03-03T21:17:02.02119Z","end":"2026-03-03T21:17:02.778223Z","steps":["trace[1991677982] 'process raft request' (duration: 756.874354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:02.778363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:02.02116Z","time spent":"757.14219ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:17:02.778406Z","caller":"traceutil/trace.go:171","msg":"trace[1676839716] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1085; }","duration":"683.825805ms","start":"2026-03-03T21:17:02.094555Z","end":"2026-03-03T21:17:02.778381Z","steps":["trace[1676839716] 'read index received' (duration: 665.69439ms)","trace[1676839716] 'applied index is now lower than readState.Index' (duration: 18.130065ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:02.778533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.961768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:17:02.778571Z","caller":"traceutil/trace.go:171","msg":"trace[478695629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1046; }","duration":"684.009209ms","start":"2026-03-03T21:17:02.094549Z","end":"2026-03-03T21:17:02.778558Z","steps":["trace[478695629] 'agreement among raft nodes before linearized reading' (duration: 683.917137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:02.778603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:02.0945Z","time spent":"684.095851ms","remote":"127.0.0.1:45862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T21:17:02.778864Z","caller":"traceutil/trace.go:171","msg":"trace[1750769278] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"757.572141ms","start":"2026-03-03T21:17:02.021279Z","end":"2026-03-03T21:17:02.778851Z","steps":["trace[1750769278] 'process raft request' (duration: 756.903715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:02.778944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:02.021266Z","time spent":"757.631012ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:17:03.034707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.783085ms","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-03-03T21:17:03.034806Z","caller":"traceutil/trace.go:171","msg":"trace[438047288] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1046; }","duration":"188.938719ms","start":"2026-03-03T21:17:02.845845Z","end":"2026-03-03T21:17:03.034784Z","steps":["trace[438047288] 'range keys from in-memory index tree' (duration: 188.575161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:03.035213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.000839ms","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-03-03T21:17:03.035305Z","caller":"traceutil/trace.go:171","msg":"trace[782124098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1046; }","duration":"213.751806ms","start":"2026-03-03T21:17:02.821534Z","end":"2026-03-03T21:17:03.035286Z","steps":["trace[782124098] 'range keys from in-memory index tree' (duration: 212.789845ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:18:57.723561Z","caller":"traceutil/trace.go:171","msg":"trace[1828832395] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"175.358271ms","start":"2026-03-03T21:18:57.548168Z","end":"2026-03-03T21:18:57.723526Z","steps":["trace[1828832395] 'process raft request' (duration: 85.581074ms)","trace[1828832395] 'compare' (duration: 89.625654ms)"],"step_count":2}