{"level":"warn","ts":"2026-02-08T16:37:47.990815Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-08T16:37:47.990888Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-08T16:37:47.990943Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-08T16:37:47.99095Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-02-08T16:37:47.990967Z","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-02-08T16:37:47.992182Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-02-08T16:37:47.993253Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-08T16:37:48.044412Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"50.947758ms"} {"level":"info","ts":"2026-02-08T16:37:48.095872Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-02-08T16:37:48.095937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T16:37:48.095998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-02-08T16:37:48.096007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T16:37:48.09602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-02-08T16:37:48.096087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-02-08T16:37:48.119829Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T16:37:48.132016Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T16:37:48.137377Z","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-02-08T16:37:48.160125Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T16:37:48.160829Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T16:37:48.160966Z","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-02-08T16:37:48.160991Z","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-02-08T16:37:48.161016Z","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-02-08T16:37:48.164162Z","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-02-08T16:37:48.164277Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-02-08T16:37:48.164349Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-02-08T16:37:48.172023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-02-08T16:37:48.172152Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T16:37:48.172176Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T16:37:48.172238Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-02-08T16:37:48.699448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T16:37:48.699488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T16:37:48.699507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-02-08T16:37:48.699517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-02-08T16:37:48.699521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-02-08T16:37:48.699531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-02-08T16:37:48.699538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-02-08T16:37:48.704229Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T16:37:48.704264Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T16:37:48.704569Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T16:37:48.704713Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T16:37:48.704824Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T16:37:48.704836Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T16:37:48.706023Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-02-08T16:37:48.712789Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-08T16:37:48.712982Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T16:37:48.713029Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T16:37:48.71304Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T16:37:52.501901Z","caller":"traceutil/trace.go:171","msg":"trace[1433266243] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"187.999959ms","start":"2026-02-08T16:37:52.313873Z","end":"2026-02-08T16:37:52.501873Z","steps":["trace[1433266243] 'process raft request' (duration: 110.437623ms)","trace[1433266243] 'compare' (duration: 77.437383ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:54.699122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.45692ms","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-02-08T16:37:54.699182Z","caller":"traceutil/trace.go:171","msg":"trace[1394847016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:254; }","duration":"127.574382ms","start":"2026-02-08T16:37:54.571597Z","end":"2026-02-08T16:37:54.699171Z","steps":["trace[1394847016] 'range keys from in-memory index tree' (duration: 127.381878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:54.699378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.238344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:37:54.699455Z","caller":"traceutil/trace.go:171","msg":"trace[2040604233] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:0; response_revision:254; }","duration":"145.329495ms","start":"2026-02-08T16:37:54.554094Z","end":"2026-02-08T16:37:54.699423Z","steps":["trace[2040604233] 'range keys from in-memory index tree' (duration: 145.193262ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:54.699649Z","caller":"traceutil/trace.go:171","msg":"trace[2082815700] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"107.734763ms","start":"2026-02-08T16:37:54.591905Z","end":"2026-02-08T16:37:54.699639Z","steps":["trace[2082815700] 'process raft request' (duration: 106.536427ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:54.734213Z","caller":"traceutil/trace.go:171","msg":"trace[1988773877] linearizableReadLoop","detail":"{readStateIndex:266; appliedIndex:261; }","duration":"137.301818ms","start":"2026-02-08T16:37:54.596898Z","end":"2026-02-08T16:37:54.7342Z","steps":["trace[1988773877] 'read index received' (duration: 101.506406ms)","trace[1988773877] 'applied index is now lower than readState.Index' (duration: 35.794872ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:37:54.734427Z","caller":"traceutil/trace.go:171","msg":"trace[269920871] transaction","detail":"{read_only:false; number_of_response:0; response_revision:255; }","duration":"142.410821ms","start":"2026-02-08T16:37:54.592011Z","end":"2026-02-08T16:37:54.734422Z","steps":["trace[269920871] 'process raft request' (duration: 142.050733ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:54.734614Z","caller":"traceutil/trace.go:171","msg":"trace[1439198614] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"142.563284ms","start":"2026-02-08T16:37:54.592046Z","end":"2026-02-08T16:37:54.734609Z","steps":["trace[1439198614] 'process raft request' (duration: 142.056773ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:54.734667Z","caller":"traceutil/trace.go:171","msg":"trace[1165472139] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"142.532333ms","start":"2026-02-08T16:37:54.592131Z","end":"2026-02-08T16:37:54.734663Z","steps":["trace[1165472139] 'process raft request' (duration: 142.013782ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:54.734716Z","caller":"traceutil/trace.go:171","msg":"trace[584733983] transaction","detail":"{read_only:false; number_of_response:0; response_revision:257; }","duration":"142.546453ms","start":"2026-02-08T16:37:54.592166Z","end":"2026-02-08T16:37:54.734712Z","steps":["trace[584733983] 'process raft request' (duration: 141.992891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:54.73483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.917871ms","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-02-08T16:37:54.734846Z","caller":"traceutil/trace.go:171","msg":"trace[1150358132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:257; }","duration":"137.962392ms","start":"2026-02-08T16:37:54.596879Z","end":"2026-02-08T16:37:54.734841Z","steps":["trace[1150358132] 'agreement among raft nodes before linearized reading' (duration: 137.87871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:09.300775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.861772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T16:38:09.30083Z","caller":"traceutil/trace.go:171","msg":"trace[120941808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:417; }","duration":"425.942993ms","start":"2026-02-08T16:38:08.874876Z","end":"2026-02-08T16:38:09.300819Z","steps":["trace[120941808] 'range keys from in-memory index tree' (duration: 425.789178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:09.300854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:08.87487Z","time spent":"425.979635ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T16:38:09.301033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.908039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:09.301046Z","caller":"traceutil/trace.go:171","msg":"trace[1902065705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:417; }","duration":"767.9485ms","start":"2026-02-08T16:38:08.533094Z","end":"2026-02-08T16:38:09.301042Z","steps":["trace[1902065705] 'range keys from in-memory index tree' (duration: 767.845787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:09.301057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:08.533078Z","time spent":"767.976581ms","remote":"127.0.0.1:55796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T16:38:09.301125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.307816ms","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-02-08T16:38:09.301136Z","caller":"traceutil/trace.go:171","msg":"trace[1615280064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:417; }","duration":"480.342027ms","start":"2026-02-08T16:38:08.820791Z","end":"2026-02-08T16:38:09.301133Z","steps":["trace[1615280064] 'range keys from in-memory index tree' (duration: 480.261186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:09.301148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:08.820778Z","time spent":"480.367268ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T16:38:09.301203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.266641ms","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-02-08T16:38:09.301219Z","caller":"traceutil/trace.go:171","msg":"trace[452877559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:417; }","duration":"464.299592ms","start":"2026-02-08T16:38:08.836912Z","end":"2026-02-08T16:38:09.301212Z","steps":["trace[452877559] 'range keys from in-memory index tree' (duration: 464.236321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:09.301233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:08.836905Z","time spent":"464.322592ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-08T16:38:15.551714Z","caller":"traceutil/trace.go:171","msg":"trace[410705963] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:446; }","duration":"224.559508ms","start":"2026-02-08T16:38:15.327134Z","end":"2026-02-08T16:38:15.551694Z","steps":["trace[410705963] 'read index received' (duration: 224.367644ms)","trace[410705963] 'applied index is now lower than readState.Index' (duration: 190.884µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:38:15.552421Z","caller":"traceutil/trace.go:171","msg":"trace[1717826634] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"585.766468ms","start":"2026-02-08T16:38:14.96664Z","end":"2026-02-08T16:38:15.552407Z","steps":["trace[1717826634] 'process raft request' (duration: 584.92352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:15.553503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:14.966617Z","time spent":"586.29345ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:38:15.553796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.815549ms","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-02-08T16:38:15.554918Z","caller":"traceutil/trace.go:171","msg":"trace[1282287449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:432; }","duration":"204.957225ms","start":"2026-02-08T16:38:15.349939Z","end":"2026-02-08T16:38:15.554896Z","steps":["trace[1282287449] 'agreement among raft nodes before linearized reading' (duration: 202.763066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:15.553873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.733906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-08T16:38:15.554336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.336231ms","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-02-08T16:38:15.56147Z","caller":"traceutil/trace.go:171","msg":"trace[781004907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:432; }","duration":"211.465169ms","start":"2026-02-08T16:38:15.349989Z","end":"2026-02-08T16:38:15.561454Z","steps":["trace[781004907] 'agreement among raft nodes before linearized reading' (duration: 204.317281ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:15.559115Z","caller":"traceutil/trace.go:171","msg":"trace[1762940517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:432; }","duration":"231.993222ms","start":"2026-02-08T16:38:15.327102Z","end":"2026-02-08T16:38:15.559095Z","steps":["trace[1762940517] 'agreement among raft nodes before linearized reading' (duration: 226.727155ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:18.620804Z","caller":"traceutil/trace.go:171","msg":"trace[1283399781] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"290.567627ms","start":"2026-02-08T16:38:18.33022Z","end":"2026-02-08T16:38:18.620787Z","steps":["trace[1283399781] 'process raft request' (duration: 290.22182ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:21.304908Z","caller":"traceutil/trace.go:171","msg":"trace[1576005434] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"676.765241ms","start":"2026-02-08T16:38:20.628127Z","end":"2026-02-08T16:38:21.304893Z","steps":["trace[1576005434] 'process raft request' (duration: 676.676129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:21.305013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:20.628114Z","time spent":"676.848622ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:38:21.706265Z","caller":"traceutil/trace.go:171","msg":"trace[1050172598] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"856.616051ms","start":"2026-02-08T16:38:20.849633Z","end":"2026-02-08T16:38:21.706249Z","steps":["trace[1050172598] 'process raft request' (duration: 789.427114ms)","trace[1050172598] 'compare' (duration: 66.878561ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:21.706356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:20.849615Z","time spent":"856.694383ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:38:22.362205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763279511503906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T16:38:22.539968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.986763ms","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-02-08T16:38:22.540045Z","caller":"traceutil/trace.go:171","msg":"trace[1991233400] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:481; }","duration":"355.071766ms","start":"2026-02-08T16:38:22.184963Z","end":"2026-02-08T16:38:22.540034Z","steps":["trace[1991233400] 'range keys from in-memory index tree' (duration: 354.976143ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:22.540149Z","caller":"traceutil/trace.go:171","msg":"trace[1714431890] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"829.057858ms","start":"2026-02-08T16:38:21.71107Z","end":"2026-02-08T16:38:22.540128Z","steps":["trace[1714431890] 'process raft request' (duration: 747.78397ms)","trace[1714431890] 'compare' (duration: 80.837418ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:22.540415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:21.71106Z","time spent":"829.294653ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:38:22.549485Z","caller":"traceutil/trace.go:171","msg":"trace[563961935] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:497; }","duration":"687.664672ms","start":"2026-02-08T16:38:21.861808Z","end":"2026-02-08T16:38:22.549473Z","steps":["trace[563961935] 'read index received' (duration: 597.009966ms)","trace[563961935] 'applied index is now lower than readState.Index' (duration: 90.654226ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:22.549566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.749034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T16:38:22.549599Z","caller":"traceutil/trace.go:171","msg":"trace[832286952] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:484; }","duration":"687.824645ms","start":"2026-02-08T16:38:21.861768Z","end":"2026-02-08T16:38:22.549593Z","steps":["trace[832286952] 'agreement among raft nodes before linearized reading' (duration: 687.752734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:22.54962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:21.861751Z","time spent":"687.862897ms","remote":"127.0.0.1:55832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"info","ts":"2026-02-08T16:38:22.549915Z","caller":"traceutil/trace.go:171","msg":"trace[168148299] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"838.750142ms","start":"2026-02-08T16:38:21.711157Z","end":"2026-02-08T16:38:22.549907Z","steps":["trace[168148299] 'process raft request' (duration: 838.222951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:22.549972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:21.711153Z","time spent":"838.780483ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:38:22.550047Z","caller":"traceutil/trace.go:171","msg":"trace[1765158670] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"838.859565ms","start":"2026-02-08T16:38:21.711182Z","end":"2026-02-08T16:38:22.550042Z","steps":["trace[1765158670] 'process raft request' (duration: 838.269202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:22.550077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:21.71118Z","time spent":"838.880525ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:38:24.036954Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763279511503919,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T16:38:24.199572Z","caller":"traceutil/trace.go:171","msg":"trace[15340902] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:502; }","duration":"662.976926ms","start":"2026-02-08T16:38:23.536573Z","end":"2026-02-08T16:38:24.19955Z","steps":["trace[15340902] 'read index received' (duration: 662.792592ms)","trace[15340902] 'applied index is now lower than readState.Index' (duration: 183.424µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:24.199719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.132669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:24.19975Z","caller":"traceutil/trace.go:171","msg":"trace[530867507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:486; }","duration":"663.219151ms","start":"2026-02-08T16:38:23.536522Z","end":"2026-02-08T16:38:24.199741Z","steps":["trace[530867507] 'agreement among raft nodes before linearized reading' (duration: 663.116979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:24.199827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:23.536496Z","time spent":"663.323432ms","remote":"127.0.0.1:55796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T16:38:24.200042Z","caller":"traceutil/trace.go:171","msg":"trace[935950251] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"887.593113ms","start":"2026-02-08T16:38:23.312438Z","end":"2026-02-08T16:38:24.200031Z","steps":["trace[935950251] 'process raft request' (duration: 886.98476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:24.200142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:23.312427Z","time spent":"887.639224ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:38:24.691799Z","caller":"traceutil/trace.go:171","msg":"trace[1776214376] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"122.325736ms","start":"2026-02-08T16:38:24.569456Z","end":"2026-02-08T16:38:24.691782Z","steps":["trace[1776214376] 'process raft request' (duration: 52.454741ms)","trace[1776214376] 'compare' (duration: 69.656521ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:38:28.706017Z","caller":"traceutil/trace.go:171","msg":"trace[1531852312] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"251.877511ms","start":"2026-02-08T16:38:28.454122Z","end":"2026-02-08T16:38:28.705999Z","steps":["trace[1531852312] 'read index received' (duration: 251.746388ms)","trace[1531852312] 'applied index is now lower than readState.Index' (duration: 130.203µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:38:28.706124Z","caller":"traceutil/trace.go:171","msg":"trace[260848052] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"492.868343ms","start":"2026-02-08T16:38:28.213247Z","end":"2026-02-08T16:38:28.706115Z","steps":["trace[260848052] 'process raft request' (duration: 492.640378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:28.706209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:28.21323Z","time spent":"492.916044ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:38:28.706352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.630424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:28.706415Z","caller":"traceutil/trace.go:171","msg":"trace[598291401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"170.721466ms","start":"2026-02-08T16:38:28.535677Z","end":"2026-02-08T16:38:28.706399Z","steps":["trace[598291401] 'agreement among raft nodes before linearized reading' (duration: 170.622674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:28.706641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.513905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jz7p8\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-08T16:38:28.706678Z","caller":"traceutil/trace.go:171","msg":"trace[71926740] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jz7p8; range_end:; response_count:1; response_revision:498; }","duration":"252.572327ms","start":"2026-02-08T16:38:28.454095Z","end":"2026-02-08T16:38:28.706667Z","steps":["trace[71926740] 'agreement among raft nodes before linearized reading' (duration: 252.513605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:28.706829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.739743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T16:38:28.706864Z","caller":"traceutil/trace.go:171","msg":"trace[1195615428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:498; }","duration":"126.802025ms","start":"2026-02-08T16:38:28.580052Z","end":"2026-02-08T16:38:28.706854Z","steps":["trace[1195615428] 'agreement among raft nodes before linearized reading' (duration: 126.744883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:30.03498Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763279511503952,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T16:38:30.038305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.20663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:38:30.038415Z","caller":"traceutil/trace.go:171","msg":"trace[880138070] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.322052117s","start":"2026-02-08T16:38:28.716344Z","end":"2026-02-08T16:38:30.038397Z","steps":["trace[880138070] 'process raft request' (duration: 621.53687ms)","trace[880138070] 'compare' (duration: 699.990115ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:30.038481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:28.716327Z","time spent":"1.322120548s","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:38:30.071155Z","caller":"traceutil/trace.go:171","msg":"trace[756675734] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:517; }","duration":"536.347585ms","start":"2026-02-08T16:38:29.534791Z","end":"2026-02-08T16:38:30.071138Z","steps":["trace[756675734] 'read index received' (duration: 536.163451ms)","trace[756675734] 'applied index is now lower than readState.Index' (duration: 183.434µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:30.071293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.451218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:30.071317Z","caller":"traceutil/trace.go:171","msg":"trace[1100694810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:502; }","duration":"536.55807ms","start":"2026-02-08T16:38:29.534752Z","end":"2026-02-08T16:38:30.07131Z","steps":["trace[1100694810] 'agreement among raft nodes before linearized reading' (duration: 536.451208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:30.071341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:29.534722Z","time spent":"536.611682ms","remote":"127.0.0.1:55796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T16:38:30.071678Z","caller":"traceutil/trace.go:171","msg":"trace[677447177] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.35520785s","start":"2026-02-08T16:38:28.716461Z","end":"2026-02-08T16:38:30.071669Z","steps":["trace[677447177] 'process raft request' (duration: 1.354544225s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:30.07174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:28.716454Z","time spent":"1.355243821s","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:38:30.071872Z","caller":"traceutil/trace.go:171","msg":"trace[1693801298] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"1.347962159s","start":"2026-02-08T16:38:28.723885Z","end":"2026-02-08T16:38:30.071847Z","steps":["trace[1693801298] 'process raft request' (duration: 1.347215262s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:30.071968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:28.723865Z","time spent":"1.348056251s","remote":"127.0.0.1:55978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:38:30.072217Z","caller":"traceutil/trace.go:171","msg":"trace[725032740] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.35568204s","start":"2026-02-08T16:38:28.716518Z","end":"2026-02-08T16:38:30.0722Z","steps":["trace[725032740] 'process raft request' (duration: 1.354559916s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:30.072322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:28.716512Z","time spent":"1.355765722s","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:38:31.74423Z","caller":"traceutil/trace.go:171","msg":"trace[463275287] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"266.470984ms","start":"2026-02-08T16:38:31.477737Z","end":"2026-02-08T16:38:31.744208Z","steps":["trace[463275287] 'process raft request' (duration: 266.27175ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:31.752324Z","caller":"traceutil/trace.go:171","msg":"trace[845246236] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"217.133664ms","start":"2026-02-08T16:38:31.535181Z","end":"2026-02-08T16:38:31.752315Z","steps":["trace[845246236] 'read index received' (duration: 209.628318ms)","trace[845246236] 'applied index is now lower than readState.Index' (duration: 7.504706ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:31.752393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.204385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:31.752413Z","caller":"traceutil/trace.go:171","msg":"trace[1278534516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"217.260706ms","start":"2026-02-08T16:38:31.535146Z","end":"2026-02-08T16:38:31.752407Z","steps":["trace[1278534516] 'agreement among raft nodes before linearized reading' (duration: 217.203825ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:31.752406Z","caller":"traceutil/trace.go:171","msg":"trace[981635770] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"274.462631ms","start":"2026-02-08T16:38:31.477917Z","end":"2026-02-08T16:38:31.752379Z","steps":["trace[981635770] 'process raft request' (duration: 274.334638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:32.524833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.818828ms","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-02-08T16:38:32.524938Z","caller":"traceutil/trace.go:171","msg":"trace[28937555] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:514; }","duration":"339.928181ms","start":"2026-02-08T16:38:32.184991Z","end":"2026-02-08T16:38:32.524919Z","steps":["trace[28937555] 'range keys from in-memory index tree' (duration: 339.786088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:32.525945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.535645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:38:32.526017Z","caller":"traceutil/trace.go:171","msg":"trace[1415248645] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"412.767971ms","start":"2026-02-08T16:38:32.113239Z","end":"2026-02-08T16:38:32.526007Z","steps":["trace[1415248645] 'process raft request' (duration: 301.977371ms)","trace[1415248645] 'compare' (duration: 110.007943ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:32.526367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:32.113222Z","time spent":"413.12104ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:38:32.528752Z","caller":"traceutil/trace.go:171","msg":"trace[1846911717] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"413.673162ms","start":"2026-02-08T16:38:32.115067Z","end":"2026-02-08T16:38:32.52874Z","steps":["trace[1846911717] 'process raft request' (duration: 413.449017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:32.528837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:32.115061Z","time spent":"413.722784ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:38:36.059385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.613711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:38:36.059551Z","caller":"traceutil/trace.go:171","msg":"trace[500528545] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"149.44907ms","start":"2026-02-08T16:38:35.910088Z","end":"2026-02-08T16:38:36.059537Z","steps":["trace[500528545] 'process raft request' (duration: 149.384188ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:36.059886Z","caller":"traceutil/trace.go:171","msg":"trace[1659375656] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"323.035674ms","start":"2026-02-08T16:38:35.736834Z","end":"2026-02-08T16:38:36.05987Z","steps":["trace[1659375656] 'process raft request' (duration: 33.87975ms)","trace[1659375656] 'compare' (duration: 288.330105ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:36.059948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:35.736817Z","time spent":"323.100895ms","remote":"127.0.0.1:55882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T16:38:36.060219Z","caller":"traceutil/trace.go:171","msg":"trace[1990414274] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"310.583497ms","start":"2026-02-08T16:38:35.749623Z","end":"2026-02-08T16:38:36.060206Z","steps":["trace[1990414274] 'read index received' (duration: 21.054866ms)","trace[1990414274] 'applied index is now lower than readState.Index' (duration: 289.526751ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:36.061151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.517688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-08T16:38:36.06119Z","caller":"traceutil/trace.go:171","msg":"trace[773781303] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:576; }","duration":"311.560569ms","start":"2026-02-08T16:38:35.749618Z","end":"2026-02-08T16:38:36.061178Z","steps":["trace[773781303] 'agreement among raft nodes before linearized reading' (duration: 311.420446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:36.061223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:35.749557Z","time spent":"311.658591ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-08T16:38:36.061433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.569341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-08T16:38:36.061466Z","caller":"traceutil/trace.go:171","msg":"trace[302187230] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:576; }","duration":"111.621232ms","start":"2026-02-08T16:38:35.949835Z","end":"2026-02-08T16:38:36.061456Z","steps":["trace[302187230] 'agreement among raft nodes before linearized reading' (duration: 111.5284ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:40.89574Z","caller":"traceutil/trace.go:171","msg":"trace[927122073] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"362.923288ms","start":"2026-02-08T16:38:40.532799Z","end":"2026-02-08T16:38:40.895723Z","steps":["trace[927122073] 'read index received' (duration: 362.743044ms)","trace[927122073] 'applied index is now lower than readState.Index' (duration: 179.624µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:38:40.896Z","caller":"traceutil/trace.go:171","msg":"trace[2118610602] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"693.989329ms","start":"2026-02-08T16:38:40.202003Z","end":"2026-02-08T16:38:40.895992Z","steps":["trace[2118610602] 'process raft request' (duration: 693.625731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:40.896075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:40.201992Z","time spent":"694.02964ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:38:40.896199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.398168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:40.896219Z","caller":"traceutil/trace.go:171","msg":"trace[1208422989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"363.443989ms","start":"2026-02-08T16:38:40.532769Z","end":"2026-02-08T16:38:40.896213Z","steps":["trace[1208422989] 'agreement among raft nodes before linearized reading' (duration: 363.409389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:40.896251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:40.532739Z","time spent":"363.49343ms","remote":"127.0.0.1:55796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T16:38:40.896398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.119118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T16:38:40.896415Z","caller":"traceutil/trace.go:171","msg":"trace[286824142] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"259.158739ms","start":"2026-02-08T16:38:40.637251Z","end":"2026-02-08T16:38:40.89641Z","steps":["trace[286824142] 'agreement among raft nodes before linearized reading' (duration: 259.089917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:40.896678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.061122ms","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-02-08T16:38:40.896748Z","caller":"traceutil/trace.go:171","msg":"trace[1674906429] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"117.160915ms","start":"2026-02-08T16:38:40.77958Z","end":"2026-02-08T16:38:40.896741Z","steps":["trace[1674906429] 'agreement among raft nodes before linearized reading' (duration: 117.054703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:40.896871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.201611ms","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-02-08T16:38:40.896889Z","caller":"traceutil/trace.go:171","msg":"trace[1197917765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"250.230101ms","start":"2026-02-08T16:38:40.646653Z","end":"2026-02-08T16:38:40.896883Z","steps":["trace[1197917765] 'agreement among raft nodes before linearized reading' (duration: 250.18142ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:41.198206Z","caller":"traceutil/trace.go:171","msg":"trace[180565985] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"265.087451ms","start":"2026-02-08T16:38:40.933099Z","end":"2026-02-08T16:38:41.198186Z","steps":["trace[180565985] 'process raft request' (duration: 186.48559ms)","trace[180565985] 'compare' (duration: 77.6323ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:41.199288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.053242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:41.199318Z","caller":"traceutil/trace.go:171","msg":"trace[843467330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"266.107433ms","start":"2026-02-08T16:38:40.933202Z","end":"2026-02-08T16:38:41.19931Z","steps":["trace[843467330] 'agreement among raft nodes before linearized reading' (duration: 266.048332ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:41.200111Z","caller":"traceutil/trace.go:171","msg":"trace[1209401045] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"263.996027ms","start":"2026-02-08T16:38:40.933345Z","end":"2026-02-08T16:38:41.197341Z","steps":["trace[1209401045] 'read index received' (duration: 186.184084ms)","trace[1209401045] 'applied index is now lower than readState.Index' (duration: 77.810943ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:41.202045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.607679ms","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-02-08T16:38:41.202074Z","caller":"traceutil/trace.go:171","msg":"trace[145666290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:617; }","duration":"268.646919ms","start":"2026-02-08T16:38:40.933419Z","end":"2026-02-08T16:38:41.202066Z","steps":["trace[145666290] 'agreement among raft nodes before linearized reading' (duration: 268.548467ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:41.202171Z","caller":"traceutil/trace.go:171","msg":"trace[1781765992] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"268.778442ms","start":"2026-02-08T16:38:40.933385Z","end":"2026-02-08T16:38:41.202163Z","steps":["trace[1781765992] 'process raft request' (duration: 268.478526ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:41.202306Z","caller":"traceutil/trace.go:171","msg":"trace[588981097] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"268.814053ms","start":"2026-02-08T16:38:40.933468Z","end":"2026-02-08T16:38:41.202282Z","steps":["trace[588981097] 'process raft request' (duration: 268.475816ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:41.202451Z","caller":"traceutil/trace.go:171","msg":"trace[1860005815] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"268.998206ms","start":"2026-02-08T16:38:40.933445Z","end":"2026-02-08T16:38:41.202443Z","steps":["trace[1860005815] 'process raft request' (duration: 268.467895ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:44.036883Z","caller":"traceutil/trace.go:171","msg":"trace[1965369931] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"104.134586ms","start":"2026-02-08T16:38:43.932732Z","end":"2026-02-08T16:38:44.036867Z","steps":["trace[1965369931] 'process raft request' (duration: 102.332837ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:47.378195Z","caller":"traceutil/trace.go:171","msg":"trace[1886523131] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"151.765291ms","start":"2026-02-08T16:38:47.226411Z","end":"2026-02-08T16:38:47.378177Z","steps":["trace[1886523131] 'process raft request' (duration: 151.661198ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:47.378249Z","caller":"traceutil/trace.go:171","msg":"trace[1924138018] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:703; }","duration":"144.596923ms","start":"2026-02-08T16:38:47.233626Z","end":"2026-02-08T16:38:47.378223Z","steps":["trace[1924138018] 'read index received' (duration: 144.588123ms)","trace[1924138018] 'applied index is now lower than readState.Index' (duration: 7.25µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:47.378523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.869709ms","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-02-08T16:38:47.37858Z","caller":"traceutil/trace.go:171","msg":"trace[256350147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:681; }","duration":"144.95938ms","start":"2026-02-08T16:38:47.233611Z","end":"2026-02-08T16:38:47.37857Z","steps":["trace[256350147] 'agreement among raft nodes before linearized reading' (duration: 144.716075ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:47.551752Z","caller":"traceutil/trace.go:171","msg":"trace[470065310] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"173.43198ms","start":"2026-02-08T16:38:47.378307Z","end":"2026-02-08T16:38:47.551739Z","steps":["trace[470065310] 'read index received' (duration: 151.774491ms)","trace[470065310] 'applied index is now lower than readState.Index' (duration: 21.656759ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:47.551905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.291482ms","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-02-08T16:38:47.551925Z","caller":"traceutil/trace.go:171","msg":"trace[1444705089] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:681; }","duration":"301.351463ms","start":"2026-02-08T16:38:47.250568Z","end":"2026-02-08T16:38:47.551919Z","steps":["trace[1444705089] 'agreement among raft nodes before linearized reading' (duration: 301.267491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:47.551945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:47.250553Z","time spent":"301.386705ms","remote":"127.0.0.1:56066","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-02-08T16:38:47.552123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.96151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-08T16:38:47.552141Z","caller":"traceutil/trace.go:171","msg":"trace[546420837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:681; }","duration":"317.992751ms","start":"2026-02-08T16:38:47.234143Z","end":"2026-02-08T16:38:47.552136Z","steps":["trace[546420837] 'agreement among raft nodes before linearized reading' (duration: 317.928579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:47.552157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:47.234138Z","time spent":"318.015172ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-08T16:38:47.552532Z","caller":"traceutil/trace.go:171","msg":"trace[374863083] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"170.069646ms","start":"2026-02-08T16:38:47.382454Z","end":"2026-02-08T16:38:47.552524Z","steps":["trace[374863083] 'process raft request' (duration: 169.250118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:51.654468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.940964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:51.654523Z","caller":"traceutil/trace.go:171","msg":"trace[2046478098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"119.026225ms","start":"2026-02-08T16:38:51.535484Z","end":"2026-02-08T16:38:51.65451Z","steps":["trace[2046478098] 'range keys from in-memory index tree' (duration: 118.864552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:51.654529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.945245ms","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-02-08T16:38:51.654594Z","caller":"traceutil/trace.go:171","msg":"trace[1627541596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:691; }","duration":"264.035877ms","start":"2026-02-08T16:38:51.390533Z","end":"2026-02-08T16:38:51.654569Z","steps":["trace[1627541596] 'range keys from in-memory index tree' (duration: 263.71394ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:51.936201Z","caller":"traceutil/trace.go:171","msg":"trace[1180583245] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"275.788417ms","start":"2026-02-08T16:38:51.660394Z","end":"2026-02-08T16:38:51.936183Z","steps":["trace[1180583245] 'process raft request' (duration: 275.588743ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:51.936273Z","caller":"traceutil/trace.go:171","msg":"trace[1012035198] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"273.893146ms","start":"2026-02-08T16:38:51.662362Z","end":"2026-02-08T16:38:51.936255Z","steps":["trace[1012035198] 'process raft request' (duration: 273.64384ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:51.936342Z","caller":"traceutil/trace.go:171","msg":"trace[1313304302] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"276.583485ms","start":"2026-02-08T16:38:51.659752Z","end":"2026-02-08T16:38:51.936335Z","steps":["trace[1313304302] 'process raft request' (duration: 276.126305ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:52.465807Z","caller":"traceutil/trace.go:171","msg":"trace[1750418544] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"262.930133ms","start":"2026-02-08T16:38:52.202852Z","end":"2026-02-08T16:38:52.465782Z","steps":["trace[1750418544] 'process raft request' (duration: 262.756889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:07.579011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.504441ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-08T16:39:10.166208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.066702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:503"} {"level":"info","ts":"2026-02-08T16:39:10.166284Z","caller":"traceutil/trace.go:171","msg":"trace[1443033255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:749; }","duration":"154.177774ms","start":"2026-02-08T16:39:10.012092Z","end":"2026-02-08T16:39:10.16627Z","steps":["trace[1443033255] 'range keys from in-memory index tree' (duration: 153.958509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:10.166311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.530903ms","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-02-08T16:39:10.166365Z","caller":"traceutil/trace.go:171","msg":"trace[82568927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:749; }","duration":"150.627716ms","start":"2026-02-08T16:39:10.015723Z","end":"2026-02-08T16:39:10.166351Z","steps":["trace[82568927] 'range keys from in-memory index tree' (duration: 150.399931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:10.166492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.476081ms","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-02-08T16:39:10.166513Z","caller":"traceutil/trace.go:171","msg":"trace[1391602151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:749; }","duration":"149.508581ms","start":"2026-02-08T16:39:10.016999Z","end":"2026-02-08T16:39:10.166507Z","steps":["trace[1391602151] 'range keys from in-memory index tree' (duration: 149.376168ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:39:10.380308Z","caller":"traceutil/trace.go:171","msg":"trace[1767546692] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"208.769704ms","start":"2026-02-08T16:39:10.171522Z","end":"2026-02-08T16:39:10.380292Z","steps":["trace[1767546692] 'process raft request' (duration: 208.659591ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:39:10.423195Z","caller":"traceutil/trace.go:171","msg":"trace[1018551193] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"250.61376ms","start":"2026-02-08T16:39:10.172558Z","end":"2026-02-08T16:39:10.423171Z","steps":["trace[1018551193] 'process raft request' (duration: 250.581099ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:39:10.423477Z","caller":"traceutil/trace.go:171","msg":"trace[83302324] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"251.798426ms","start":"2026-02-08T16:39:10.171672Z","end":"2026-02-08T16:39:10.423471Z","steps":["trace[83302324] 'process raft request' (duration: 251.344817ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:39:16.072154Z","caller":"traceutil/trace.go:171","msg":"trace[2050576416] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"129.309624ms","start":"2026-02-08T16:39:15.942826Z","end":"2026-02-08T16:39:16.072135Z","steps":["trace[2050576416] 'process raft request' (duration: 129.208902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:17.03656Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763279511504528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T16:39:17.333162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.431282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:39:17.333258Z","caller":"traceutil/trace.go:171","msg":"trace[94386722] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"845.658177ms","start":"2026-02-08T16:39:16.487582Z","end":"2026-02-08T16:39:17.33324Z","steps":["trace[94386722] 'process raft request' (duration: 643.065561ms)","trace[94386722] 'compare' (duration: 200.589222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:39:17.333314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:16.487546Z","time spent":"845.741559ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:39:17.811738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.18683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:39:17.81197Z","caller":"traceutil/trace.go:171","msg":"trace[1754985585] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"828.733682ms","start":"2026-02-08T16:39:16.983208Z","end":"2026-02-08T16:39:17.811942Z","steps":["trace[1754985585] 'process raft request' (duration: 625.246765ms)","trace[1754985585] 'compare' (duration: 203.072498ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:39:17.812117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:16.983189Z","time spent":"828.839265ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:39:17.812372Z","caller":"traceutil/trace.go:171","msg":"trace[1971957929] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:796; }","duration":"1.276345535s","start":"2026-02-08T16:39:16.536015Z","end":"2026-02-08T16:39:17.812361Z","steps":["trace[1971957929] 'read index received' (duration: 594.644609ms)","trace[1971957929] 'applied index is now lower than readState.Index' (duration: 681.697386ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:39:17.963341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.455994ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-08T16:39:17.96335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427309208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:39:17.963446Z","caller":"traceutil/trace.go:171","msg":"trace[267211282] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:798; }","duration":"151.033514ms","start":"2026-02-08T16:39:17.812395Z","end":"2026-02-08T16:39:17.963428Z","steps":["trace[267211282] 'read index received' (duration: 50.861µs)","trace[267211282] 'applied index is now lower than readState.Index' (duration: 150.981203ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:39:17.963478Z","caller":"traceutil/trace.go:171","msg":"trace[1128152110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:770; }","duration":"1.427474132s","start":"2026-02-08T16:39:16.535986Z","end":"2026-02-08T16:39:17.96346Z","steps":["trace[1128152110] 'agreement among raft nodes before linearized reading' (duration: 1.276446338s)","trace[1128152110] 'range keys from in-memory index tree' (duration: 150.867601ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:39:17.963513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:16.535973Z","time spent":"1.427532623s","remote":"127.0.0.1:55796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T16:39:17.963535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.282979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T16:39:17.963563Z","caller":"traceutil/trace.go:171","msg":"trace[735520471] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:770; }","duration":"831.35596ms","start":"2026-02-08T16:39:17.132197Z","end":"2026-02-08T16:39:17.963553Z","steps":["trace[735520471] 'agreement among raft nodes before linearized reading' (duration: 831.280429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:17.963599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:17.132178Z","time spent":"831.411552ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-02-08T16:39:17.963859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.735347ms","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-02-08T16:39:17.963881Z","caller":"traceutil/trace.go:171","msg":"trace[1327685938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:770; }","duration":"616.790128ms","start":"2026-02-08T16:39:17.347084Z","end":"2026-02-08T16:39:17.963875Z","steps":["trace[1327685938] 'agreement among raft nodes before linearized reading' (duration: 616.674966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:17.9639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:17.347067Z","time spent":"616.82918ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-08T16:39:30.592226Z","caller":"traceutil/trace.go:171","msg":"trace[1195936991] linearizableReadLoop","detail":"{readStateIndex:888; appliedIndex:887; }","duration":"375.791382ms","start":"2026-02-08T16:39:30.216423Z","end":"2026-02-08T16:39:30.592215Z","steps":["trace[1195936991] 'read index received' (duration: 375.640109ms)","trace[1195936991] 'applied index is now lower than readState.Index' (duration: 150.683µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:39:30.592372Z","caller":"traceutil/trace.go:171","msg":"trace[1122327470] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"532.121733ms","start":"2026-02-08T16:39:30.060244Z","end":"2026-02-08T16:39:30.592366Z","steps":["trace[1122327470] 'process raft request' (duration: 531.871898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:30.592431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:30.060215Z","time spent":"532.175415ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:39:30.592549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.125199ms","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-02-08T16:39:30.592569Z","caller":"traceutil/trace.go:171","msg":"trace[310165805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:855; }","duration":"376.16598ms","start":"2026-02-08T16:39:30.216398Z","end":"2026-02-08T16:39:30.592564Z","steps":["trace[310165805] 'agreement among raft nodes before linearized reading' (duration: 376.109288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:30.592582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:30.216386Z","time spent":"376.1936ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T16:39:31.317761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.435568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:39:31.317882Z","caller":"traceutil/trace.go:171","msg":"trace[1200943462] linearizableReadLoop","detail":"{readStateIndex:890; appliedIndex:888; }","duration":"716.094847ms","start":"2026-02-08T16:39:30.601778Z","end":"2026-02-08T16:39:31.317872Z","steps":["trace[1200943462] 'read index received' (duration: 146.493135ms)","trace[1200943462] 'applied index is now lower than readState.Index' (duration: 569.601202ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:39:31.317931Z","caller":"traceutil/trace.go:171","msg":"trace[2046166948] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"720.343791ms","start":"2026-02-08T16:39:30.597582Z","end":"2026-02-08T16:39:31.317926Z","steps":["trace[2046166948] 'process raft request' (duration: 720.240769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:31.317958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:30.597576Z","time spent":"720.366581ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:39:31.317965Z","caller":"traceutil/trace.go:171","msg":"trace[1857097387] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"721.624769ms","start":"2026-02-08T16:39:30.59632Z","end":"2026-02-08T16:39:31.317944Z","steps":["trace[1857097387] 'process raft request' (duration: 151.956346ms)","trace[1857097387] 'compare' (duration: 569.350506ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:39:31.318062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:30.596311Z","time spent":"721.699672ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:39:31.318099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.318782ms","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-02-08T16:39:31.318118Z","caller":"traceutil/trace.go:171","msg":"trace[1484906429] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:857; }","duration":"716.350923ms","start":"2026-02-08T16:39:30.60176Z","end":"2026-02-08T16:39:31.318111Z","steps":["trace[1484906429] 'agreement among raft nodes before linearized reading' (duration: 716.286442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:31.318132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:30.601752Z","time spent":"716.376493ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T16:39:31.318184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.026017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:39:31.318198Z","caller":"traceutil/trace.go:171","msg":"trace[171315751] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:857; }","duration":"554.063669ms","start":"2026-02-08T16:39:30.764128Z","end":"2026-02-08T16:39:31.318192Z","steps":["trace[171315751] 'agreement among raft nodes before linearized reading' (duration: 554.041818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:31.318212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:30.764116Z","time spent":"554.092099ms","remote":"127.0.0.1:55996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-08T16:39:31.318361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.232503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:39:31.31839Z","caller":"traceutil/trace.go:171","msg":"trace[946819114] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:857; }","duration":"233.262975ms","start":"2026-02-08T16:39:31.085118Z","end":"2026-02-08T16:39:31.318381Z","steps":["trace[946819114] 'agreement among raft nodes before linearized reading' (duration: 233.217824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:31.720149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.067852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:39:31.720207Z","caller":"traceutil/trace.go:171","msg":"trace[2145006272] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:890; }","duration":"306.402476ms","start":"2026-02-08T16:39:31.413796Z","end":"2026-02-08T16:39:31.720198Z","steps":["trace[2145006272] 'read index received' (duration: 31.18877ms)","trace[2145006272] 'applied index is now lower than readState.Index' (duration: 275.212586ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:39:31.720283Z","caller":"traceutil/trace.go:171","msg":"trace[1605839472] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"398.136788ms","start":"2026-02-08T16:39:31.322117Z","end":"2026-02-08T16:39:31.720254Z","steps":["trace[1605839472] 'process raft request' (duration: 122.917432ms)","trace[1605839472] 'compare' (duration: 274.96773ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:39:31.720338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.132307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:39:31.720354Z","caller":"traceutil/trace.go:171","msg":"trace[1753412908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:858; }","duration":"188.168807ms","start":"2026-02-08T16:39:31.53218Z","end":"2026-02-08T16:39:31.720348Z","steps":["trace[1753412908] 'agreement among raft nodes before linearized reading' (duration: 188.150506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:31.72041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:31.322104Z","time spent":"398.23619ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:39:31.720317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.524839ms","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-02-08T16:39:31.720543Z","caller":"traceutil/trace.go:171","msg":"trace[1887478682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:858; }","duration":"306.772394ms","start":"2026-02-08T16:39:31.413766Z","end":"2026-02-08T16:39:31.720538Z","steps":["trace[1887478682] 'agreement among raft nodes before linearized reading' (duration: 306.478048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:31.72056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:31.413754Z","time spent":"306.801484ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-08T16:39:32.206827Z","caller":"traceutil/trace.go:171","msg":"trace[43564110] linearizableReadLoop","detail":"{readStateIndex:892; appliedIndex:891; }","duration":"341.592085ms","start":"2026-02-08T16:39:31.865222Z","end":"2026-02-08T16:39:32.206815Z","steps":["trace[43564110] 'read index received' (duration: 339.97744ms)","trace[43564110] 'applied index is now lower than readState.Index' (duration: 1.613845ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:39:32.207063Z","caller":"traceutil/trace.go:171","msg":"trace[390900968] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"483.188819ms","start":"2026-02-08T16:39:31.723868Z","end":"2026-02-08T16:39:32.207057Z","steps":["trace[390900968] 'process raft request' (duration: 481.306448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:32.207126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:31.72386Z","time spent":"483.223071ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:39:32.20769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.471915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T16:39:32.207713Z","caller":"traceutil/trace.go:171","msg":"trace[1797870502] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:859; }","duration":"342.532246ms","start":"2026-02-08T16:39:31.865175Z","end":"2026-02-08T16:39:32.207707Z","steps":["trace[1797870502] 'agreement among raft nodes before linearized reading' (duration: 341.690467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:32.207728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:31.865155Z","time spent":"342.570257ms","remote":"127.0.0.1:55832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"info","ts":"2026-02-08T16:39:32.762251Z","caller":"traceutil/trace.go:171","msg":"trace[810666178] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"161.494276ms","start":"2026-02-08T16:39:32.600742Z","end":"2026-02-08T16:39:32.762237Z","steps":["trace[810666178] 'process raft request' (duration: 161.164398ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:39:45.001136Z","caller":"traceutil/trace.go:171","msg":"trace[754999463] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"116.680681ms","start":"2026-02-08T16:39:44.884438Z","end":"2026-02-08T16:39:45.001118Z","steps":["trace[754999463] 'process raft request' (duration: 116.581218ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:39:45.776282Z","caller":"traceutil/trace.go:171","msg":"trace[492368275] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"354.603139ms","start":"2026-02-08T16:39:45.421651Z","end":"2026-02-08T16:39:45.776254Z","steps":["trace[492368275] 'process raft request' (duration: 354.482947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:45.776379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:45.421642Z","time spent":"354.68873ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:39:45.84061Z","caller":"traceutil/trace.go:171","msg":"trace[507586695] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"308.16411ms","start":"2026-02-08T16:39:45.532435Z","end":"2026-02-08T16:39:45.840599Z","steps":["trace[507586695] 'read index received' (duration: 244.168661ms)","trace[507586695] 'applied index is now lower than readState.Index' (duration: 63.994879ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:39:45.840723Z","caller":"traceutil/trace.go:171","msg":"trace[903902861] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"419.009564ms","start":"2026-02-08T16:39:45.421708Z","end":"2026-02-08T16:39:45.840718Z","steps":["trace[903902861] 'process raft request' (duration: 418.843052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:45.840768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:45.421692Z","time spent":"419.043484ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:39:45.840849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.413764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:39:45.840864Z","caller":"traceutil/trace.go:171","msg":"trace[1749946450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"308.448015ms","start":"2026-02-08T16:39:45.532411Z","end":"2026-02-08T16:39:45.840859Z","steps":["trace[1749946450] 'agreement among raft nodes before linearized reading' (duration: 308.424165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:45.840876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:45.532397Z","time spent":"308.476245ms","remote":"127.0.0.1:55796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T16:39:47.26855Z","caller":"traceutil/trace.go:171","msg":"trace[1172722356] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"262.537275ms","start":"2026-02-08T16:39:47.005998Z","end":"2026-02-08T16:39:47.268535Z","steps":["trace[1172722356] 'process raft request' (duration: 262.454764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:47.561648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.070182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T16:39:47.561696Z","caller":"traceutil/trace.go:171","msg":"trace[2098723957] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:900; }","duration":"269.150893ms","start":"2026-02-08T16:39:47.292534Z","end":"2026-02-08T16:39:47.561685Z","steps":["trace[2098723957] 'count revisions from in-memory index tree' (duration: 269.032231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:47.593742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.821463ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-08T16:39:47.593812Z","caller":"traceutil/trace.go:171","msg":"trace[47052583] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"103.452674ms","start":"2026-02-08T16:39:47.490347Z","end":"2026-02-08T16:39:47.593799Z","steps":["trace[47052583] 'read index received' (duration: 33.56µs)","trace[47052583] 'applied index is now lower than readState.Index' (duration: 103.418074ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:39:47.593895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.536326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:39:47.593928Z","caller":"traceutil/trace.go:171","msg":"trace[1432861701] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:900; }","duration":"103.603417ms","start":"2026-02-08T16:39:47.490316Z","end":"2026-02-08T16:39:47.593919Z","steps":["trace[1432861701] 'agreement among raft nodes before linearized reading' (duration: 103.535406ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:39:48.081191Z","caller":"traceutil/trace.go:171","msg":"trace[1509707185] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"237.24996ms","start":"2026-02-08T16:39:47.843926Z","end":"2026-02-08T16:39:48.081175Z","steps":["trace[1509707185] 'read index received' (duration: 237.101027ms)","trace[1509707185] 'applied index is now lower than readState.Index' (duration: 148.413µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:39:48.081421Z","caller":"traceutil/trace.go:171","msg":"trace[1213002123] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"297.040295ms","start":"2026-02-08T16:39:47.784375Z","end":"2026-02-08T16:39:48.081415Z","steps":["trace[1213002123] 'process raft request' (duration: 296.69459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:48.081598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.665895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T16:39:48.081615Z","caller":"traceutil/trace.go:171","msg":"trace[496584925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:901; }","duration":"237.730886ms","start":"2026-02-08T16:39:47.843879Z","end":"2026-02-08T16:39:48.08161Z","steps":["trace[496584925] 'agreement among raft nodes before linearized reading' (duration: 237.653215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:48.081701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.835438ms","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-02-08T16:39:48.081714Z","caller":"traceutil/trace.go:171","msg":"trace[911319039] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:901; }","duration":"233.861568ms","start":"2026-02-08T16:39:47.847848Z","end":"2026-02-08T16:39:48.08171Z","steps":["trace[911319039] 'agreement among raft nodes before linearized reading' (duration: 233.820468ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:39:56.584263Z","caller":"traceutil/trace.go:171","msg":"trace[51787208] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"409.490813ms","start":"2026-02-08T16:39:56.174757Z","end":"2026-02-08T16:39:56.584248Z","steps":["trace[51787208] 'read index received' (duration: 409.363202ms)","trace[51787208] 'applied index is now lower than readState.Index' (duration: 127.101µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:39:56.584331Z","caller":"traceutil/trace.go:171","msg":"trace[515163179] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"443.193053ms","start":"2026-02-08T16:39:56.14113Z","end":"2026-02-08T16:39:56.584323Z","steps":["trace[515163179] 'process raft request' (duration: 443.029311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:56.584408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:56.141117Z","time spent":"443.239734ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:39:56.584525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.768607ms","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-02-08T16:39:56.584541Z","caller":"traceutil/trace.go:171","msg":"trace[1519166932] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:959; }","duration":"409.806549ms","start":"2026-02-08T16:39:56.174729Z","end":"2026-02-08T16:39:56.584536Z","steps":["trace[1519166932] 'agreement among raft nodes before linearized reading' (duration: 409.743878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:56.584555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:56.174714Z","time spent":"409.837399ms","remote":"127.0.0.1:56066","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-02-08T16:39:56.584961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.384685ms","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-02-08T16:39:56.585043Z","caller":"traceutil/trace.go:171","msg":"trace[558673532] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:959; }","duration":"184.473427ms","start":"2026-02-08T16:39:56.400552Z","end":"2026-02-08T16:39:56.585026Z","steps":["trace[558673532] 'agreement among raft nodes before linearized reading' (duration: 184.284004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:56.586233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.954751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T16:39:56.586258Z","caller":"traceutil/trace.go:171","msg":"trace[1074030535] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:959; }","duration":"350.008331ms","start":"2026-02-08T16:39:56.236243Z","end":"2026-02-08T16:39:56.586251Z","steps":["trace[1074030535] 'agreement among raft nodes before linearized reading' (duration: 348.347816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:39:56.586273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:39:56.236228Z","time spent":"350.041362ms","remote":"127.0.0.1:56232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-08T16:39:57.407802Z","caller":"traceutil/trace.go:171","msg":"trace[791177884] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"246.386005ms","start":"2026-02-08T16:39:57.161368Z","end":"2026-02-08T16:39:57.407754Z","steps":["trace[791177884] 'process raft request' (duration: 246.266943ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:40:02.847551Z","caller":"traceutil/trace.go:171","msg":"trace[1096485949] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"182.408285ms","start":"2026-02-08T16:40:02.665126Z","end":"2026-02-08T16:40:02.847535Z","steps":["trace[1096485949] 'process raft request' (duration: 116.477428ms)","trace[1096485949] 'compare' (duration: 65.617513ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:40:02.847899Z","caller":"traceutil/trace.go:171","msg":"trace[1009755753] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"182.72115ms","start":"2026-02-08T16:40:02.665173Z","end":"2026-02-08T16:40:02.847894Z","steps":["trace[1009755753] 'process raft request' (duration: 182.313374ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:40:02.847998Z","caller":"traceutil/trace.go:171","msg":"trace[104226938] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"178.763802ms","start":"2026-02-08T16:40:02.669229Z","end":"2026-02-08T16:40:02.847993Z","steps":["trace[104226938] 'process raft request' (duration: 178.280975ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:40:03.569734Z","caller":"traceutil/trace.go:171","msg":"trace[524120996] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"177.239019ms","start":"2026-02-08T16:40:03.392479Z","end":"2026-02-08T16:40:03.569718Z","steps":["trace[524120996] 'process raft request' (duration: 177.009116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:40:04.304934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.202101ms","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-02-08T16:40:04.305031Z","caller":"traceutil/trace.go:171","msg":"trace[9813744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1012; }","duration":"130.331642ms","start":"2026-02-08T16:40:04.174662Z","end":"2026-02-08T16:40:04.304993Z","steps":["trace[9813744] 'range keys from in-memory index tree' (duration: 130.013618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:40:04.305504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.42746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-08T16:40:04.306052Z","caller":"traceutil/trace.go:171","msg":"trace[274548532] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1012; }","duration":"314.002587ms","start":"2026-02-08T16:40:03.992033Z","end":"2026-02-08T16:40:04.306036Z","steps":["trace[274548532] 'range keys from in-memory index tree' (duration: 313.328558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:40:04.306098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:40:03.992015Z","time spent":"314.068458ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-08T16:40:04.716415Z","caller":"traceutil/trace.go:171","msg":"trace[1607871550] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"405.270611ms","start":"2026-02-08T16:40:04.311128Z","end":"2026-02-08T16:40:04.716398Z","steps":["trace[1607871550] 'process raft request' (duration: 405.155489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:40:04.716647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:40:04.31111Z","time spent":"405.428473ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:40:04.718114Z","caller":"traceutil/trace.go:171","msg":"trace[1517625167] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"181.693915ms","start":"2026-02-08T16:40:04.536402Z","end":"2026-02-08T16:40:04.718096Z","steps":["trace[1517625167] 'read index received' (duration: 180.716741ms)","trace[1517625167] 'applied index is now lower than readState.Index' (duration: 976.024µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:40:04.718197Z","caller":"traceutil/trace.go:171","msg":"trace[1069971050] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"404.744244ms","start":"2026-02-08T16:40:04.313445Z","end":"2026-02-08T16:40:04.718189Z","steps":["trace[1069971050] 'process raft request' (duration: 404.525031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:40:04.718281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:40:04.313425Z","time spent":"404.795555ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:40:04.718453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.04759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:40:04.718523Z","caller":"traceutil/trace.go:171","msg":"trace[358466996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"182.150562ms","start":"2026-02-08T16:40:04.536363Z","end":"2026-02-08T16:40:04.718513Z","steps":["trace[358466996] 'agreement among raft nodes before linearized reading' (duration: 182.068311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:40:05.311707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.362651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-08T16:40:05.311788Z","caller":"traceutil/trace.go:171","msg":"trace[2113391938] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1015; }","duration":"442.453302ms","start":"2026-02-08T16:40:04.869302Z","end":"2026-02-08T16:40:05.311755Z","steps":["trace[2113391938] 'range keys from in-memory index tree' (duration: 442.25767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:40:05.311819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:40:04.869295Z","time spent":"442.517743ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-08T16:40:05.311876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.601297ms","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:510"} {"level":"info","ts":"2026-02-08T16:40:05.311931Z","caller":"traceutil/trace.go:171","msg":"trace[485131634] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1015; }","duration":"426.661428ms","start":"2026-02-08T16:40:04.885253Z","end":"2026-02-08T16:40:05.311914Z","steps":["trace[485131634] 'range keys from in-memory index tree' (duration: 426.465015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:40:05.311959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:40:04.885246Z","time spent":"426.707168ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-08T16:40:05.312105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.801029ms","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-02-08T16:40:05.312127Z","caller":"traceutil/trace.go:171","msg":"trace[696171270] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1015; }","duration":"459.859411ms","start":"2026-02-08T16:40:04.852261Z","end":"2026-02-08T16:40:05.31212Z","steps":["trace[696171270] 'range keys from in-memory index tree' (duration: 459.701818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:40:05.312144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:40:04.852241Z","time spent":"459.898591ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T16:40:05.312273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.938572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T16:40:05.312297Z","caller":"traceutil/trace.go:171","msg":"trace[823989784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1015; }","duration":"460.000313ms","start":"2026-02-08T16:40:04.852289Z","end":"2026-02-08T16:40:05.312289Z","steps":["trace[823989784] 'range keys from in-memory index tree' (duration: 459.80808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:40:05.312317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:40:04.85227Z","time spent":"460.042693ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-08T16:42:13.774951Z","caller":"traceutil/trace.go:171","msg":"trace[1512867108] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"111.956761ms","start":"2026-02-08T16:42:13.662976Z","end":"2026-02-08T16:42:13.774933Z","steps":["trace[1512867108] 'process raft request' (duration: 48.691929ms)","trace[1512867108] 'compare' (duration: 63.051707ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:42:42.00731Z","caller":"traceutil/trace.go:171","msg":"trace[955881179] transaction","detail":"{read_only:false; response_revision:1655; number_of_response:1; }","duration":"119.740463ms","start":"2026-02-08T16:42:41.887549Z","end":"2026-02-08T16:42:42.007289Z","steps":["trace[955881179] 'process raft request' (duration: 109.777696ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:42:42.007875Z","caller":"traceutil/trace.go:171","msg":"trace[375943327] linearizableReadLoop","detail":"{readStateIndex:1727; appliedIndex:1726; }","duration":"111.341936ms","start":"2026-02-08T16:42:41.896522Z","end":"2026-02-08T16:42:42.007864Z","steps":["trace[375943327] 'read index received' (duration: 100.753176ms)","trace[375943327] 'applied index is now lower than readState.Index' (duration: 10.58735ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:42:42.008115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.576802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-08T16:42:42.00819Z","caller":"traceutil/trace.go:171","msg":"trace[1486517427] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1656; }","duration":"111.694024ms","start":"2026-02-08T16:42:41.896486Z","end":"2026-02-08T16:42:42.00818Z","steps":["trace[1486517427] 'agreement among raft nodes before linearized reading' (duration: 111.452389ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:43:21.257211Z","caller":"traceutil/trace.go:171","msg":"trace[1048918572] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1925; }","duration":"481.515562ms","start":"2026-02-08T16:43:20.775675Z","end":"2026-02-08T16:43:21.257191Z","steps":["trace[1048918572] 'read index received' (duration: 481.366368ms)","trace[1048918572] 'applied index is now lower than readState.Index' (duration: 148.514µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:43:21.257625Z","caller":"traceutil/trace.go:171","msg":"trace[1410873418] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"540.755905ms","start":"2026-02-08T16:43:20.71686Z","end":"2026-02-08T16:43:21.257616Z","steps":["trace[1410873418] 'process raft request' (duration: 540.232164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:43:21.258295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.594626ms","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:510"} {"level":"info","ts":"2026-02-08T16:43:21.258379Z","caller":"traceutil/trace.go:171","msg":"trace[1256370097] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1846; }","duration":"482.715259ms","start":"2026-02-08T16:43:20.775643Z","end":"2026-02-08T16:43:21.258358Z","steps":["trace[1256370097] 'agreement among raft nodes before linearized reading' (duration: 482.481773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:43:21.258465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:43:20.775619Z","time spent":"482.834591ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-08T16:43:21.258621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:43:20.716848Z","time spent":"541.469921ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:43:21.258899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.158936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-08T16:43:21.258943Z","caller":"traceutil/trace.go:171","msg":"trace[1164237291] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1846; }","duration":"452.228018ms","start":"2026-02-08T16:43:20.806704Z","end":"2026-02-08T16:43:21.258932Z","steps":["trace[1164237291] 'agreement among raft nodes before linearized reading' (duration: 452.119125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:43:21.258982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:43:20.806693Z","time spent":"452.280329ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-08T16:43:21.25925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.759491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T16:43:21.259289Z","caller":"traceutil/trace.go:171","msg":"trace[78285280] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1846; }","duration":"273.832022ms","start":"2026-02-08T16:43:20.985447Z","end":"2026-02-08T16:43:21.259279Z","steps":["trace[78285280] 'agreement among raft nodes before linearized reading' (duration: 273.766681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:43:21.259561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.448022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-08T16:43:21.259637Z","caller":"traceutil/trace.go:171","msg":"trace[1776286047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1846; }","duration":"362.523033ms","start":"2026-02-08T16:43:20.897066Z","end":"2026-02-08T16:43:21.259589Z","steps":["trace[1776286047] 'agreement among raft nodes before linearized reading' (duration: 362.3938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:43:21.259673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:43:20.897045Z","time spent":"362.616995ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}