{"level":"warn","ts":"2026-02-09T03:08:35.343565Z","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-09T03:08:35.343656Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-09T03:08:35.344608Z","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-09T03:08:35.344633Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-02-09T03:08:35.344663Z","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-09T03:08:35.345737Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-02-09T03:08:35.346436Z","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.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-09T03:08:35.363808Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"16.740682ms"} {"level":"info","ts":"2026-02-09T03:08:35.388356Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-02-09T03:08:35.388482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-02-09T03:08:35.388511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-02-09T03:08:35.388528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-09T03:08:35.388543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-02-09T03:08:35.388593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-02-09T03:08:35.40754Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-09T03:08:35.424477Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-09T03:08:35.432418Z","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-09T03:08:35.443308Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-09T03:08:35.445458Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-09T03:08:35.445552Z","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-09T03:08:35.445581Z","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-09T03:08:35.445587Z","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-09T03:08:35.44608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-02-09T03:08:35.446154Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-02-09T03:08:35.464468Z","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-09T03:08:35.464709Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-09T03:08:35.468148Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-09T03:08:35.468334Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-02-09T03:08:35.469192Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-02-09T03:08:36.192242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-02-09T03:08:36.192277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-09T03:08:36.192355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-02-09T03:08:36.192368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-02-09T03:08:36.192374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-02-09T03:08:36.192391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-02-09T03:08:36.192398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-02-09T03:08:36.200571Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-02-09T03:08:36.202162Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T03:08:36.202415Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T03:08:36.202978Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T03:08:36.203128Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-09T03:08:36.203161Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-09T03:08:36.204652Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-09T03:08:36.205205Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-02-09T03:08:36.254057Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T03:08:36.254121Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T03:08:36.254139Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-09T03:08:38.89957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.127544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-09T03:08:38.899804Z","caller":"traceutil/trace.go:171","msg":"trace[1296090026] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:112; }","duration":"142.387501ms","start":"2026-02-09T03:08:38.757356Z","end":"2026-02-09T03:08:38.899744Z","steps":["trace[1296090026] 'range keys from in-memory index tree' (duration: 142.065323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:38.90043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.3967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-09T03:08:38.902007Z","caller":"traceutil/trace.go:171","msg":"trace[226120374] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:112; }","duration":"143.989491ms","start":"2026-02-09T03:08:38.758007Z","end":"2026-02-09T03:08:38.901997Z","steps":["trace[226120374] 'range keys from in-memory index tree' (duration: 142.36276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:39.238309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.16728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-09T03:08:39.238992Z","caller":"traceutil/trace.go:171","msg":"trace[1212158963] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"252.084746ms","start":"2026-02-09T03:08:38.986875Z","end":"2026-02-09T03:08:39.23896Z","steps":["trace[1212158963] 'process raft request' (duration: 34.758296ms)","trace[1212158963] 'compare' (duration: 216.052367ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:08:39.331083Z","caller":"traceutil/trace.go:171","msg":"trace[776459364] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"161.631466ms","start":"2026-02-09T03:08:39.169425Z","end":"2026-02-09T03:08:39.331057Z","steps":["trace[776459364] 'process raft request' (duration: 161.304557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:39.597655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.054931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-09T03:08:39.597784Z","caller":"traceutil/trace.go:171","msg":"trace[733710468] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"262.352916ms","start":"2026-02-09T03:08:39.335411Z","end":"2026-02-09T03:08:39.597764Z","steps":["trace[733710468] 'process raft request' (duration: 103.134021ms)","trace[733710468] 'compare' (duration: 158.914237ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:08:39.958683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.989481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-09T03:08:39.962149Z","caller":"traceutil/trace.go:171","msg":"trace[723972801] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"308.048037ms","start":"2026-02-09T03:08:39.654081Z","end":"2026-02-09T03:08:39.962129Z","steps":["trace[723972801] 'process raft request' (duration: 181.557608ms)","trace[723972801] 'compare' (duration: 122.875268ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:08:39.962254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:39.654068Z","time spent":"308.13444ms","remote":"127.0.0.1:45708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T03:08:40.167464Z","caller":"traceutil/trace.go:171","msg":"trace[205748225] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"148.860004ms","start":"2026-02-09T03:08:40.018582Z","end":"2026-02-09T03:08:40.167442Z","steps":["trace[205748225] 'process raft request' (duration: 144.117564ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:08:40.417431Z","caller":"traceutil/trace.go:171","msg":"trace[200568442] linearizableReadLoop","detail":"{readStateIndex:132; appliedIndex:131; }","duration":"203.714455ms","start":"2026-02-09T03:08:40.21369Z","end":"2026-02-09T03:08:40.417404Z","steps":["trace[200568442] 'read index received' (duration: 146.087083ms)","trace[200568442] 'applied index is now lower than readState.Index' (duration: 57.626272ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:08:40.417663Z","caller":"traceutil/trace.go:171","msg":"trace[2138679239] transaction","detail":"{read_only:false; response_revision:126; number_of_response:1; }","duration":"204.772133ms","start":"2026-02-09T03:08:40.212859Z","end":"2026-02-09T03:08:40.417631Z","steps":["trace[2138679239] 'process raft request' (duration: 146.905004ms)","trace[2138679239] 'compare' (duration: 57.452039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:08:40.418322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.893981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-09T03:08:40.418443Z","caller":"traceutil/trace.go:171","msg":"trace[67817530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:126; }","duration":"204.767043ms","start":"2026-02-09T03:08:40.213661Z","end":"2026-02-09T03:08:40.418428Z","steps":["trace[67817530] 'agreement among raft nodes before linearized reading' (duration: 203.833159ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:08:40.8178Z","caller":"traceutil/trace.go:171","msg":"trace[1796909187] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"157.961243ms","start":"2026-02-09T03:08:40.659813Z","end":"2026-02-09T03:08:40.817774Z","steps":["trace[1796909187] 'process raft request' (duration: 102.766242ms)","trace[1796909187] 'compare' (duration: 55.071678ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:08:41.086009Z","caller":"traceutil/trace.go:171","msg":"trace[599592232] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"263.117554ms","start":"2026-02-09T03:08:40.822866Z","end":"2026-02-09T03:08:41.085983Z","steps":["trace[599592232] 'process raft request' (duration: 262.94097ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:08:41.296414Z","caller":"traceutil/trace.go:171","msg":"trace[1330058303] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"303.563964ms","start":"2026-02-09T03:08:40.992833Z","end":"2026-02-09T03:08:41.296397Z","steps":["trace[1330058303] 'process raft request' (duration: 279.323443ms)","trace[1330058303] 'compare' (duration: 23.802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:08:41.29663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:40.992804Z","time spent":"303.77214ms","remote":"127.0.0.1:45554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3943,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T03:08:41.296793Z","caller":"traceutil/trace.go:171","msg":"trace[1492161031] linearizableReadLoop","detail":"{readStateIndex:141; appliedIndex:139; }","duration":"237.5652ms","start":"2026-02-09T03:08:41.058505Z","end":"2026-02-09T03:08:41.296071Z","steps":["trace[1492161031] 'read index received' (duration: 27.311298ms)","trace[1492161031] 'applied index is now lower than readState.Index' (duration: 210.252942ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:08:41.296872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.360031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:08:41.296902Z","caller":"traceutil/trace.go:171","msg":"trace[982853876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:135; }","duration":"238.428132ms","start":"2026-02-09T03:08:41.058463Z","end":"2026-02-09T03:08:41.296891Z","steps":["trace[982853876] 'agreement among raft nodes before linearized reading' (duration: 237.659213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:41.297079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.013755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-aggregator\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:08:41.297106Z","caller":"traceutil/trace.go:171","msg":"trace[1257433251] range","detail":"{range_begin:/registry/clusterroles/system:kube-aggregator; range_end:; response_count:0; response_revision:135; }","duration":"208.066486ms","start":"2026-02-09T03:08:41.089031Z","end":"2026-02-09T03:08:41.297098Z","steps":["trace[1257433251] 'agreement among raft nodes before linearized reading' (duration: 208.022645ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:08:41.842708Z","caller":"traceutil/trace.go:171","msg":"trace[1263725062] transaction","detail":"{read_only:false; response_revision:154; number_of_response:1; }","duration":"179.500446ms","start":"2026-02-09T03:08:41.663188Z","end":"2026-02-09T03:08:41.842689Z","steps":["trace[1263725062] 'process raft request' (duration: 128.605293ms)","trace[1263725062] 'compare' (duration: 50.634077ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:08:42.210302Z","caller":"traceutil/trace.go:171","msg":"trace[166337526] transaction","detail":"{read_only:false; response_revision:161; number_of_response:1; }","duration":"146.024742ms","start":"2026-02-09T03:08:42.063755Z","end":"2026-02-09T03:08:42.20978Z","steps":["trace[166337526] 'process raft request' (duration: 123.635978ms)","trace[166337526] 'compare' (duration: 21.997515ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:08:42.579314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.454715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:08:42.579512Z","caller":"traceutil/trace.go:171","msg":"trace[1744041252] linearizableReadLoop","detail":"{readStateIndex:171; appliedIndex:170; }","duration":"221.190957ms","start":"2026-02-09T03:08:42.358292Z","end":"2026-02-09T03:08:42.579483Z","steps":["trace[1744041252] 'read index received' (duration: 41.447065ms)","trace[1744041252] 'applied index is now lower than readState.Index' (duration: 179.742152ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:08:42.579676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.374662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:08:42.579713Z","caller":"traceutil/trace.go:171","msg":"trace[1035825255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:165; }","duration":"221.415143ms","start":"2026-02-09T03:08:42.358287Z","end":"2026-02-09T03:08:42.579702Z","steps":["trace[1035825255] 'agreement among raft nodes before linearized reading' (duration: 221.255829ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:08:42.579966Z","caller":"traceutil/trace.go:171","msg":"trace[1284749526] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"276.345228ms","start":"2026-02-09T03:08:42.303601Z","end":"2026-02-09T03:08:42.579946Z","steps":["trace[1284749526] 'process raft request' (duration: 96.192966ms)","trace[1284749526] 'compare' (duration: 178.156362ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:08:42.799585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.29747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:08:42.799883Z","caller":"traceutil/trace.go:171","msg":"trace[1167745583] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"185.114058ms","start":"2026-02-09T03:08:42.614749Z","end":"2026-02-09T03:08:42.799863Z","steps":["trace[1167745583] 'process raft request' (duration: 82.159862ms)","trace[1167745583] 'compare' (duration: 102.168656ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:08:42.800967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.031593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:08:42.801081Z","caller":"traceutil/trace.go:171","msg":"trace[250034851] range","detail":"{range_begin:/registry/clusterroles/system:controller:persistent-volume-binder; range_end:; response_count:0; response_revision:168; }","duration":"148.168416ms","start":"2026-02-09T03:08:42.652896Z","end":"2026-02-09T03:08:42.801065Z","steps":["trace[250034851] 'agreement among raft nodes before linearized reading' (duration: 148.028092ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:08:42.801359Z","caller":"traceutil/trace.go:171","msg":"trace[777041373] linearizableReadLoop","detail":"{readStateIndex:174; appliedIndex:173; }","duration":"146.768911ms","start":"2026-02-09T03:08:42.652924Z","end":"2026-02-09T03:08:42.799693Z","steps":["trace[777041373] 'read index received' (duration: 43.968089ms)","trace[777041373] 'applied index is now lower than readState.Index' (duration: 102.799492ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:08:42.979165Z","caller":"traceutil/trace.go:171","msg":"trace[174859969] transaction","detail":"{read_only:false; response_revision:169; number_of_response:1; }","duration":"175.033094ms","start":"2026-02-09T03:08:42.804111Z","end":"2026-02-09T03:08:42.979144Z","steps":["trace[174859969] 'process raft request' (duration: 125.261339ms)","trace[174859969] 'compare' (duration: 49.660622ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:08:43.145029Z","caller":"traceutil/trace.go:171","msg":"trace[1516105091] transaction","detail":"{read_only:false; response_revision:170; number_of_response:1; }","duration":"161.568784ms","start":"2026-02-09T03:08:42.983435Z","end":"2026-02-09T03:08:43.145004Z","steps":["trace[1516105091] 'process raft request' (duration: 115.00913ms)","trace[1516105091] 'compare' (duration: 46.372719ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:08:43.593424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.486189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:08:43.593513Z","caller":"traceutil/trace.go:171","msg":"trace[2007098448] linearizableReadLoop","detail":"{readStateIndex:180; appliedIndex:179; }","duration":"147.707675ms","start":"2026-02-09T03:08:43.445791Z","end":"2026-02-09T03:08:43.593498Z","steps":["trace[2007098448] 'read index received' (duration: 21.027231ms)","trace[2007098448] 'applied index is now lower than readState.Index' (duration: 126.679514ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:08:43.593582Z","caller":"traceutil/trace.go:171","msg":"trace[1447064053] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"349.616106ms","start":"2026-02-09T03:08:43.243956Z","end":"2026-02-09T03:08:43.593572Z","steps":["trace[1447064053] 'process raft request' (duration: 222.917661ms)","trace[1447064053] 'compare' (duration: 126.363326ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:08:43.593627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:43.243938Z","time spent":"349.669106ms","remote":"127.0.0.1:45708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":662,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-09T03:08:43.593849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.864211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:08:43.593928Z","caller":"traceutil/trace.go:171","msg":"trace[1581444405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:174; }","duration":"134.980633ms","start":"2026-02-09T03:08:43.458926Z","end":"2026-02-09T03:08:43.593906Z","steps":["trace[1581444405] 'agreement among raft nodes before linearized reading' (duration: 134.806509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:43.594087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.286909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T03:08:43.594128Z","caller":"traceutil/trace.go:171","msg":"trace[2063913600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:174; }","duration":"148.365531ms","start":"2026-02-09T03:08:43.445749Z","end":"2026-02-09T03:08:43.594115Z","steps":["trace[2063913600] 'agreement among raft nodes before linearized reading' (duration: 148.284719ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:08:43.781214Z","caller":"traceutil/trace.go:171","msg":"trace[1808962390] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"181.22473ms","start":"2026-02-09T03:08:43.599965Z","end":"2026-02-09T03:08:43.78119Z","steps":["trace[1808962390] 'process raft request' (duration: 112.99508ms)","trace[1808962390] 'compare' (duration: 67.791199ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:08:43.781739Z","caller":"traceutil/trace.go:171","msg":"trace[2067879630] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"181.400533ms","start":"2026-02-09T03:08:43.600226Z","end":"2026-02-09T03:08:43.781627Z","steps":["trace[2067879630] 'process raft request' (duration: 180.925072ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:08:43.982517Z","caller":"traceutil/trace.go:171","msg":"trace[1294748605] transaction","detail":"{read_only:false; response_revision:178; number_of_response:1; }","duration":"175.024453ms","start":"2026-02-09T03:08:43.807467Z","end":"2026-02-09T03:08:43.982491Z","steps":["trace[1294748605] 'process raft request' (duration: 116.149108ms)","trace[1294748605] 'compare' (duration: 58.738231ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:08:44.386266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.301258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:08:44.386426Z","caller":"traceutil/trace.go:171","msg":"trace[1723411444] transaction","detail":"{read_only:false; response_revision:190; number_of_response:1; }","duration":"217.861434ms","start":"2026-02-09T03:08:44.16855Z","end":"2026-02-09T03:08:44.386412Z","steps":["trace[1723411444] 'process raft request' (duration: 98.371732ms)","trace[1723411444] 'compare' (duration: 119.203315ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:08:49.741218Z","caller":"traceutil/trace.go:171","msg":"trace[601124666] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"385.47626ms","start":"2026-02-09T03:08:49.355719Z","end":"2026-02-09T03:08:49.741196Z","steps":["trace[601124666] 'process raft request' (duration: 385.266555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:49.741351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:49.3557Z","time spent":"385.594693ms","remote":"127.0.0.1:45568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-09T03:08:50.245496Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697148886630171,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:08:50.74581Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697148886630171,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:08:51.246571Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697148886630171,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:08:51.747092Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697148886630171,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:08:52.247992Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697148886630171,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:08:52.610443Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.863116065s","expected-duration":"1s"} {"level":"info","ts":"2026-02-09T03:08:52.610851Z","caller":"traceutil/trace.go:171","msg":"trace[13986893] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:354; }","duration":"2.865806102s","start":"2026-02-09T03:08:49.745022Z","end":"2026-02-09T03:08:52.610828Z","steps":["trace[13986893] 'read index received' (duration: 2.865581347s)","trace[13986893] 'applied index is now lower than readState.Index' (duration: 223.785µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:08:52.610934Z","caller":"traceutil/trace.go:171","msg":"trace[1704432050] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"3.253495948s","start":"2026-02-09T03:08:49.357428Z","end":"2026-02-09T03:08:52.610924Z","steps":["trace[1704432050] 'process raft request' (duration: 3.253226841s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:52.611035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:49.357411Z","time spent":"3.253552309s","remote":"127.0.0.1:45554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4245,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:08:52.611192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.866203222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-09T03:08:52.611235Z","caller":"traceutil/trace.go:171","msg":"trace[1293633279] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:343; }","duration":"2.866272024s","start":"2026-02-09T03:08:49.74495Z","end":"2026-02-09T03:08:52.611222Z","steps":["trace[1293633279] 'agreement among raft nodes before linearized reading' (duration: 2.866205983s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:52.61127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:49.744935Z","time spent":"2.866328795s","remote":"127.0.0.1:45568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-02-09T03:08:52.611474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.81061202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T03:08:52.611525Z","caller":"traceutil/trace.go:171","msg":"trace[1178133890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:343; }","duration":"2.810691263s","start":"2026-02-09T03:08:49.800825Z","end":"2026-02-09T03:08:52.611516Z","steps":["trace[1178133890] 'agreement among raft nodes before linearized reading' (duration: 2.810610251s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:52.611555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:49.800809Z","time spent":"2.810737393s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T03:08:52.61175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.126894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:08:52.611788Z","caller":"traceutil/trace.go:171","msg":"trace[2104546582] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:343; }","duration":"355.197656ms","start":"2026-02-09T03:08:52.256579Z","end":"2026-02-09T03:08:52.611777Z","steps":["trace[2104546582] 'agreement among raft nodes before linearized reading' (duration: 355.131104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:52.611814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:52.256561Z","time spent":"355.247857ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-09T03:08:52.611936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.646596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:08:52.611969Z","caller":"traceutil/trace.go:171","msg":"trace[141826947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:343; }","duration":"558.678907ms","start":"2026-02-09T03:08:52.053281Z","end":"2026-02-09T03:08:52.61196Z","steps":["trace[141826947] 'agreement among raft nodes before linearized reading' (duration: 558.635446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:52.611997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:52.053242Z","time spent":"558.749328ms","remote":"127.0.0.1:45374","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-09T03:08:52.612286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.779748007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-09T03:08:52.612327Z","caller":"traceutil/trace.go:171","msg":"trace[260330746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:343; }","duration":"1.77982494s","start":"2026-02-09T03:08:50.83249Z","end":"2026-02-09T03:08:52.612315Z","steps":["trace[260330746] 'agreement among raft nodes before linearized reading' (duration: 1.779724327s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:52.612368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:50.832471Z","time spent":"1.779888932s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T03:08:52.612558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.827644835s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:08:52.612691Z","caller":"traceutil/trace.go:171","msg":"trace[1266426014] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:343; }","duration":"1.827779928s","start":"2026-02-09T03:08:50.784896Z","end":"2026-02-09T03:08:52.612676Z","steps":["trace[1266426014] 'agreement among raft nodes before linearized reading' (duration: 1.827629245s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:52.612858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.991678671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T03:08:52.612898Z","caller":"traceutil/trace.go:171","msg":"trace[1730624588] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:343; }","duration":"1.991775904s","start":"2026-02-09T03:08:50.621113Z","end":"2026-02-09T03:08:52.612889Z","steps":["trace[1730624588] 'agreement among raft nodes before linearized reading' (duration: 1.991692012s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:52.612927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:50.621092Z","time spent":"1.991828085s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T03:08:53.12301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697148886630176,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:08:53.62365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697148886630176,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:08:54.124073Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697148886630176,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:08:54.624979Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697148886630176,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:08:54.682014Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.860049799s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-09T03:08:54.785259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.282525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:08:54.785448Z","caller":"traceutil/trace.go:171","msg":"trace[1250074620] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"2.164463016s","start":"2026-02-09T03:08:52.620961Z","end":"2026-02-09T03:08:54.785424Z","steps":["trace[1250074620] 'process raft request' (duration: 2.061270873s)","trace[1250074620] 'compare' (duration: 99.340046ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:08:54.785655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:52.62095Z","time spent":"2.164674671s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T03:08:54.813161Z","caller":"traceutil/trace.go:171","msg":"trace[181372845] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"2.190048921s","start":"2026-02-09T03:08:52.62309Z","end":"2026-02-09T03:08:54.813139Z","steps":["trace[181372845] 'process raft request' (duration: 2.189884627s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:54.813325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:52.623082Z","time spent":"2.190169805s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:08:54.813612Z","caller":"traceutil/trace.go:171","msg":"trace[84662356] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"2.188279426s","start":"2026-02-09T03:08:52.62532Z","end":"2026-02-09T03:08:54.8136Z","steps":["trace[84662356] 'process raft request' (duration: 2.187777534s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:54.813764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:52.625314Z","time spent":"2.188333938s","remote":"127.0.0.1:45628","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-09T03:08:54.813923Z","caller":"traceutil/trace.go:171","msg":"trace[708278194] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:355; }","duration":"2.191268342s","start":"2026-02-09T03:08:52.622642Z","end":"2026-02-09T03:08:54.81391Z","steps":["trace[708278194] 'read index received' (duration: 2.059600401s)","trace[708278194] 'applied index is now lower than readState.Index' (duration: 131.666621ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:08:54.814016Z","caller":"traceutil/trace.go:171","msg":"trace[1304149515] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"2.188174125s","start":"2026-02-09T03:08:52.625832Z","end":"2026-02-09T03:08:54.814006Z","steps":["trace[1304149515] 'process raft request' (duration: 2.187721922s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:54.814094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:52.625825Z","time spent":"2.188217346s","remote":"127.0.0.1:45628","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-09T03:08:54.814374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.191707664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6897"} {"level":"info","ts":"2026-02-09T03:08:54.814495Z","caller":"traceutil/trace.go:171","msg":"trace[1712122747] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:347; }","duration":"2.191850277s","start":"2026-02-09T03:08:52.622624Z","end":"2026-02-09T03:08:54.814474Z","steps":["trace[1712122747] 'agreement among raft nodes before linearized reading' (duration: 2.191676873s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:54.814536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:52.622617Z","time spent":"2.191908889s","remote":"127.0.0.1:45554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6920,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-09T03:08:54.819654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.194398792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-09T03:08:54.819697Z","caller":"traceutil/trace.go:171","msg":"trace[1150434258] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:348; }","duration":"2.194457373s","start":"2026-02-09T03:08:52.625229Z","end":"2026-02-09T03:08:54.819687Z","steps":["trace[1150434258] 'agreement among raft nodes before linearized reading' (duration: 2.19436922s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:54.819722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:52.625224Z","time spent":"2.194492403s","remote":"127.0.0.1:45568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-02-09T03:08:54.819848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384637595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:08:54.819865Z","caller":"traceutil/trace.go:171","msg":"trace[642849290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:348; }","duration":"1.384690355s","start":"2026-02-09T03:08:53.435169Z","end":"2026-02-09T03:08:54.819859Z","steps":["trace[642849290] 'agreement among raft nodes before linearized reading' (duration: 1.384659854s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:54.819881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:53.43515Z","time spent":"1.384727466s","remote":"127.0.0.1:45374","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-09T03:08:54.819973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.933019231s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:08:54.819989Z","caller":"traceutil/trace.go:171","msg":"trace[1842996576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:348; }","duration":"1.933060741s","start":"2026-02-09T03:08:52.886924Z","end":"2026-02-09T03:08:54.819984Z","steps":["trace[1842996576] 'agreement among raft nodes before linearized reading' (duration: 1.93303268s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:54.820004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:08:52.886909Z","time spent":"1.933091672s","remote":"127.0.0.1:45390","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-09T03:08:56.997834Z","caller":"traceutil/trace.go:171","msg":"trace[453676111] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"177.875855ms","start":"2026-02-09T03:08:56.819948Z","end":"2026-02-09T03:08:56.997824Z","steps":["trace[453676111] 'read index received' (duration: 177.776972ms)","trace[453676111] 'applied index is now lower than readState.Index' (duration: 98.313µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:08:56.997975Z","caller":"traceutil/trace.go:171","msg":"trace[635500912] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"202.850164ms","start":"2026-02-09T03:08:56.795118Z","end":"2026-02-09T03:08:56.997969Z","steps":["trace[635500912] 'process raft request' (duration: 202.640509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:56.998091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.132541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T03:08:56.998107Z","caller":"traceutil/trace.go:171","msg":"trace[344218334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:411; }","duration":"178.180662ms","start":"2026-02-09T03:08:56.819921Z","end":"2026-02-09T03:08:56.998102Z","steps":["trace[344218334] 'agreement among raft nodes before linearized reading' (duration: 178.140871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:56.998187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.311468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-09T03:08:56.998199Z","caller":"traceutil/trace.go:171","msg":"trace[230596996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:411; }","duration":"159.346378ms","start":"2026-02-09T03:08:56.838849Z","end":"2026-02-09T03:08:56.998195Z","steps":["trace[230596996] 'agreement among raft nodes before linearized reading' (duration: 159.323398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:56.998368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.462868ms","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-09T03:08:56.998381Z","caller":"traceutil/trace.go:171","msg":"trace[1548987265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:411; }","duration":"149.49803ms","start":"2026-02-09T03:08:56.848879Z","end":"2026-02-09T03:08:56.998377Z","steps":["trace[1548987265] 'agreement among raft nodes before linearized reading' (duration: 149.474629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:08:56.998437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.542373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T03:08:56.998448Z","caller":"traceutil/trace.go:171","msg":"trace[1706772547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:411; }","duration":"159.559233ms","start":"2026-02-09T03:08:56.838886Z","end":"2026-02-09T03:08:56.998445Z","steps":["trace[1706772547] 'agreement among raft nodes before linearized reading' (duration: 159.541073ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:09:17.905496Z","caller":"traceutil/trace.go:171","msg":"trace[1671792644] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"174.221813ms","start":"2026-02-09T03:09:17.731258Z","end":"2026-02-09T03:09:17.905479Z","steps":["trace[1671792644] 'process raft request' (duration: 174.127231ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:09:21.291079Z","caller":"traceutil/trace.go:171","msg":"trace[138155627] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"175.6862ms","start":"2026-02-09T03:09:21.115376Z","end":"2026-02-09T03:09:21.291062Z","steps":["trace[138155627] 'read index received' (duration: 175.494385ms)","trace[138155627] 'applied index is now lower than readState.Index' (duration: 191.155µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:09:21.29141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.146059ms","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-09T03:09:21.291428Z","caller":"traceutil/trace.go:171","msg":"trace[353997601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"141.214621ms","start":"2026-02-09T03:09:21.150208Z","end":"2026-02-09T03:09:21.291423Z","steps":["trace[353997601] 'agreement among raft nodes before linearized reading' (duration: 141.031696ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:09:21.291468Z","caller":"traceutil/trace.go:171","msg":"trace[1921060168] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"177.558547ms","start":"2026-02-09T03:09:21.113904Z","end":"2026-02-09T03:09:21.291463Z","steps":["trace[1921060168] 'process raft request' (duration: 177.025834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:21.291582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.072375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-09T03:09:21.291595Z","caller":"traceutil/trace.go:171","msg":"trace[155006129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"131.111936ms","start":"2026-02-09T03:09:21.160478Z","end":"2026-02-09T03:09:21.29159Z","steps":["trace[155006129] 'agreement among raft nodes before linearized reading' (duration: 131.073765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:21.291669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.295075ms","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-09T03:09:21.291774Z","caller":"traceutil/trace.go:171","msg":"trace[1045467999] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"176.316866ms","start":"2026-02-09T03:09:21.11536Z","end":"2026-02-09T03:09:21.291677Z","steps":["trace[1045467999] 'agreement among raft nodes before linearized reading' (duration: 176.279805ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:09:21.668106Z","caller":"traceutil/trace.go:171","msg":"trace[2052241840] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"372.415341ms","start":"2026-02-09T03:09:21.294345Z","end":"2026-02-09T03:09:21.66676Z","steps":["trace[2052241840] 'process raft request' (duration: 368.075612ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:09:21.668158Z","caller":"traceutil/trace.go:171","msg":"trace[1332747633] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"371.13934ms","start":"2026-02-09T03:09:21.296989Z","end":"2026-02-09T03:09:21.668128Z","steps":["trace[1332747633] 'process raft request' (duration: 371.041847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:21.668235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:21.294327Z","time spent":"373.850718ms","remote":"127.0.0.1:45628","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-09T03:09:21.668311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:21.296981Z","time spent":"371.248172ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:09:21.668573Z","caller":"traceutil/trace.go:171","msg":"trace[1568203493] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"372.74068ms","start":"2026-02-09T03:09:21.295819Z","end":"2026-02-09T03:09:21.66856Z","steps":["trace[1568203493] 'process raft request' (duration: 372.080142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:21.668632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:21.295784Z","time spent":"372.816441ms","remote":"127.0.0.1:45628","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-09T03:09:24.510334Z","caller":"traceutil/trace.go:171","msg":"trace[725327005] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"527.118191ms","start":"2026-02-09T03:09:23.983193Z","end":"2026-02-09T03:09:24.510311Z","steps":["trace[725327005] 'process raft request' (duration: 526.968518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:24.510499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:23.983172Z","time spent":"527.244915ms","remote":"127.0.0.1:45554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:09:25.37691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.442681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:09:25.376982Z","caller":"traceutil/trace.go:171","msg":"trace[2116865264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"374.552963ms","start":"2026-02-09T03:09:25.002412Z","end":"2026-02-09T03:09:25.376965Z","steps":["trace[2116865264] 'range keys from in-memory index tree' (duration: 374.377209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:25.37702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:25.002395Z","time spent":"374.616715ms","remote":"127.0.0.1:45374","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-09T03:09:25.377053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.039543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-09T03:09:25.377143Z","caller":"traceutil/trace.go:171","msg":"trace[1174501053] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:548; }","duration":"616.176797ms","start":"2026-02-09T03:09:24.760945Z","end":"2026-02-09T03:09:25.377121Z","steps":["trace[1174501053] 'range keys from in-memory index tree' (duration: 615.867779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:25.377185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:24.760926Z","time spent":"616.24983ms","remote":"127.0.0.1:45414","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.240\" "} {"level":"info","ts":"2026-02-09T03:09:37.996602Z","caller":"traceutil/trace.go:171","msg":"trace[1222274169] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"187.355013ms","start":"2026-02-09T03:09:37.809231Z","end":"2026-02-09T03:09:37.996586Z","steps":["trace[1222274169] 'read index received' (duration: 186.226005ms)","trace[1222274169] 'applied index is now lower than readState.Index' (duration: 1.128458ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:09:37.996758Z","caller":"traceutil/trace.go:171","msg":"trace[288559808] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"206.665283ms","start":"2026-02-09T03:09:37.790086Z","end":"2026-02-09T03:09:37.996751Z","steps":["trace[288559808] 'process raft request' (duration: 205.403832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:37.996948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.709732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T03:09:37.996966Z","caller":"traceutil/trace.go:171","msg":"trace[1044051648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:681; }","duration":"187.796413ms","start":"2026-02-09T03:09:37.809164Z","end":"2026-02-09T03:09:37.996961Z","steps":["trace[1044051648] 'agreement among raft nodes before linearized reading' (duration: 187.707351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:37.997066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.832816ms","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-09T03:09:37.997092Z","caller":"traceutil/trace.go:171","msg":"trace[1689445559] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:681; }","duration":"173.882816ms","start":"2026-02-09T03:09:37.823205Z","end":"2026-02-09T03:09:37.997088Z","steps":["trace[1689445559] 'agreement among raft nodes before linearized reading' (duration: 173.831945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:40.131367Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697148886630873,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T03:09:40.320841Z","caller":"traceutil/trace.go:171","msg":"trace[516508523] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"690.236565ms","start":"2026-02-09T03:09:39.630583Z","end":"2026-02-09T03:09:40.320819Z","steps":["trace[516508523] 'read index received' (duration: 690.03535ms)","trace[516508523] 'applied index is now lower than readState.Index' (duration: 200.125µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:09:40.321129Z","caller":"traceutil/trace.go:171","msg":"trace[1638852182] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"724.642423ms","start":"2026-02-09T03:09:39.596476Z","end":"2026-02-09T03:09:40.321118Z","steps":["trace[1638852182] 'process raft request' (duration: 724.197612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:40.321202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:39.596444Z","time spent":"724.707725ms","remote":"127.0.0.1:45628","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-09T03:09:40.321388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.802509ms","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-09T03:09:40.32141Z","caller":"traceutil/trace.go:171","msg":"trace[1854076425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:718; }","duration":"690.849889ms","start":"2026-02-09T03:09:39.630554Z","end":"2026-02-09T03:09:40.321404Z","steps":["trace[1854076425] 'agreement among raft nodes before linearized reading' (duration: 690.749657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:40.32143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:39.630537Z","time spent":"690.888071ms","remote":"127.0.0.1:45628","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-09T03:09:40.32154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.07285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T03:09:40.321556Z","caller":"traceutil/trace.go:171","msg":"trace[352313045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:718; }","duration":"520.115351ms","start":"2026-02-09T03:09:39.801436Z","end":"2026-02-09T03:09:40.321551Z","steps":["trace[352313045] 'agreement among raft nodes before linearized reading' (duration: 520.07039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:40.321572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:39.801422Z","time spent":"520.146552ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T03:09:40.321729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.813607ms","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-09T03:09:40.321749Z","caller":"traceutil/trace.go:171","msg":"trace[257064822] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:718; }","duration":"297.834557ms","start":"2026-02-09T03:09:40.02391Z","end":"2026-02-09T03:09:40.321744Z","steps":["trace[257064822] 'agreement among raft nodes before linearized reading' (duration: 297.782216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:40.321848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.830261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T03:09:40.321863Z","caller":"traceutil/trace.go:171","msg":"trace[339238185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:718; }","duration":"312.858102ms","start":"2026-02-09T03:09:40.009Z","end":"2026-02-09T03:09:40.321859Z","steps":["trace[339238185] 'agreement among raft nodes before linearized reading' (duration: 312.815191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:40.321893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:40.008994Z","time spent":"312.895253ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T03:09:40.321969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.710464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:09:40.321989Z","caller":"traceutil/trace.go:171","msg":"trace[36083273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"313.743184ms","start":"2026-02-09T03:09:40.008241Z","end":"2026-02-09T03:09:40.321984Z","steps":["trace[36083273] 'agreement among raft nodes before linearized reading' (duration: 313.716184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:40.322004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:40.008234Z","time spent":"313.766116ms","remote":"127.0.0.1:45374","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-09T03:09:40.322113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.035721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-09T03:09:40.322129Z","caller":"traceutil/trace.go:171","msg":"trace[1495278458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:718; }","duration":"318.051652ms","start":"2026-02-09T03:09:40.004072Z","end":"2026-02-09T03:09:40.322124Z","steps":["trace[1495278458] 'agreement among raft nodes before linearized reading' (duration: 318.007551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:40.322143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:40.004014Z","time spent":"318.125153ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-09T03:09:40.55054Z","caller":"traceutil/trace.go:171","msg":"trace[109556801] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"214.861839ms","start":"2026-02-09T03:09:40.335657Z","end":"2026-02-09T03:09:40.550519Z","steps":["trace[109556801] 'process raft request' (duration: 214.833579ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:09:40.550769Z","caller":"traceutil/trace.go:171","msg":"trace[2075922516] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"216.493749ms","start":"2026-02-09T03:09:40.334269Z","end":"2026-02-09T03:09:40.550763Z","steps":["trace[2075922516] 'process raft request' (duration: 215.058354ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:09:40.550951Z","caller":"traceutil/trace.go:171","msg":"trace[1532663437] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"216.629683ms","start":"2026-02-09T03:09:40.334315Z","end":"2026-02-09T03:09:40.550945Z","steps":["trace[1532663437] 'process raft request' (duration: 216.064839ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:09:40.551022Z","caller":"traceutil/trace.go:171","msg":"trace[163924043] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"215.396573ms","start":"2026-02-09T03:09:40.33562Z","end":"2026-02-09T03:09:40.551017Z","steps":["trace[163924043] 'process raft request' (duration: 214.843419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:41.149725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.682556ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-09T03:09:41.226663Z","caller":"traceutil/trace.go:171","msg":"trace[1540403724] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"116.173747ms","start":"2026-02-09T03:09:41.110469Z","end":"2026-02-09T03:09:41.226643Z","steps":["trace[1540403724] 'process raft request' (duration: 116.056684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:47.53779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:46.984206Z","time spent":"553.577346ms","remote":"127.0.0.1:57060","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-09T03:09:47.537909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.73145ms","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-09T03:09:47.53797Z","caller":"traceutil/trace.go:171","msg":"trace[204788924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:737; }","duration":"964.829113ms","start":"2026-02-09T03:09:46.573122Z","end":"2026-02-09T03:09:47.537951Z","steps":["trace[204788924] 'range keys from in-memory index tree' (duration: 964.562656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:47.538005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:46.573094Z","time spent":"964.903744ms","remote":"127.0.0.1:45628","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-09T03:09:47.538154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.518884ms","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":"warn","ts":"2026-02-09T03:09:47.53817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.431953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T03:09:47.538192Z","caller":"traceutil/trace.go:171","msg":"trace[1986509968] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:737; }","duration":"960.467923ms","start":"2026-02-09T03:09:46.577718Z","end":"2026-02-09T03:09:47.538186Z","steps":["trace[1986509968] 'range keys from in-memory index tree' (duration: 960.372221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:47.538213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:46.577711Z","time spent":"960.498245ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T03:09:47.538324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.762281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-09T03:09:47.538342Z","caller":"traceutil/trace.go:171","msg":"trace[645461668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:737; }","duration":"954.806022ms","start":"2026-02-09T03:09:46.58353Z","end":"2026-02-09T03:09:47.538336Z","steps":["trace[645461668] 'range keys from in-memory index tree' (duration: 954.686839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:47.538357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:46.583517Z","time spent":"954.835913ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T03:09:47.538397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.252038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T03:09:47.538422Z","caller":"traceutil/trace.go:171","msg":"trace[444933447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:737; }","duration":"954.301539ms","start":"2026-02-09T03:09:46.584112Z","end":"2026-02-09T03:09:47.538414Z","steps":["trace[444933447] 'range keys from in-memory index tree' (duration: 954.157666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:09:47.538441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:09:46.584105Z","time spent":"954.332321ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-09T03:09:47.538189Z","caller":"traceutil/trace.go:171","msg":"trace[2146837925] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:737; }","duration":"144.606576ms","start":"2026-02-09T03:09:47.39357Z","end":"2026-02-09T03:09:47.538176Z","steps":["trace[2146837925] 'count revisions from in-memory index tree' (duration: 144.452962ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:09:47.823963Z","caller":"traceutil/trace.go:171","msg":"trace[1033633122] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"280.798633ms","start":"2026-02-09T03:09:47.543141Z","end":"2026-02-09T03:09:47.823939Z","steps":["trace[1033633122] 'process raft request' (duration: 280.640639ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:09:47.824268Z","caller":"traceutil/trace.go:171","msg":"trace[632766953] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"277.025029ms","start":"2026-02-09T03:09:47.547221Z","end":"2026-02-09T03:09:47.824246Z","steps":["trace[632766953] 'process raft request' (duration: 276.68537ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:09:47.824499Z","caller":"traceutil/trace.go:171","msg":"trace[2129188268] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"277.267696ms","start":"2026-02-09T03:09:47.547219Z","end":"2026-02-09T03:09:47.824486Z","steps":["trace[2129188268] 'process raft request' (duration: 276.961627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:05.263139Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697148886631096,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T03:10:05.534489Z","caller":"traceutil/trace.go:171","msg":"trace[993498045] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"772.096395ms","start":"2026-02-09T03:10:04.762377Z","end":"2026-02-09T03:10:05.534474Z","steps":["trace[993498045] 'read index received' (duration: 771.978172ms)","trace[993498045] 'applied index is now lower than readState.Index' (duration: 117.713µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:10:05.534704Z","caller":"traceutil/trace.go:171","msg":"trace[1588004001] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"978.270347ms","start":"2026-02-09T03:10:04.556426Z","end":"2026-02-09T03:10:05.534697Z","steps":["trace[1588004001] 'process raft request' (duration: 977.96892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:05.534778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:04.556418Z","time spent":"978.314709ms","remote":"127.0.0.1:45628","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-09T03:10:05.534935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.557437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-09T03:10:05.534952Z","caller":"traceutil/trace.go:171","msg":"trace[1042221158] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:793; }","duration":"772.65474ms","start":"2026-02-09T03:10:04.762292Z","end":"2026-02-09T03:10:05.534947Z","steps":["trace[1042221158] 'agreement among raft nodes before linearized reading' (duration: 772.581478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:05.534966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:04.762276Z","time spent":"772.687241ms","remote":"127.0.0.1:45414","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.240\" "} {"level":"warn","ts":"2026-02-09T03:10:05.535048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.050219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:10:05.53507Z","caller":"traceutil/trace.go:171","msg":"trace[1932673038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"538.10502ms","start":"2026-02-09T03:10:04.996961Z","end":"2026-02-09T03:10:05.535066Z","steps":["trace[1932673038] 'agreement among raft nodes before linearized reading' (duration: 538.076779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:05.535082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:04.996948Z","time spent":"538.132061ms","remote":"127.0.0.1:45374","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-09T03:10:06.081907Z","caller":"traceutil/trace.go:171","msg":"trace[1825884483] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"181.592399ms","start":"2026-02-09T03:10:05.900298Z","end":"2026-02-09T03:10:06.081891Z","steps":["trace[1825884483] 'read index received' (duration: 181.424255ms)","trace[1825884483] 'applied index is now lower than readState.Index' (duration: 167.444µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:10:06.08225Z","caller":"traceutil/trace.go:171","msg":"trace[1130020284] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"194.804519ms","start":"2026-02-09T03:10:05.887434Z","end":"2026-02-09T03:10:06.082239Z","steps":["trace[1130020284] 'process raft request' (duration: 194.330557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:06.083263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.946242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-09T03:10:06.08343Z","caller":"traceutil/trace.go:171","msg":"trace[631151558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"183.148387ms","start":"2026-02-09T03:10:05.900259Z","end":"2026-02-09T03:10:06.083407Z","steps":["trace[631151558] 'agreement among raft nodes before linearized reading' (duration: 181.984259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:06.08382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.988119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T03:10:06.084011Z","caller":"traceutil/trace.go:171","msg":"trace[1217101930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"174.170914ms","start":"2026-02-09T03:10:05.909806Z","end":"2026-02-09T03:10:06.083976Z","steps":["trace[1217101930] 'agreement among raft nodes before linearized reading' (duration: 173.936768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:08.068553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.015336ms","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-09T03:10:08.068656Z","caller":"traceutil/trace.go:171","msg":"trace[1120161871] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:799; }","duration":"246.131529ms","start":"2026-02-09T03:10:07.822511Z","end":"2026-02-09T03:10:08.068642Z","steps":["trace[1120161871] 'range keys from in-memory index tree' (duration: 246.000225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:08.068947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.087067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T03:10:08.069057Z","caller":"traceutil/trace.go:171","msg":"trace[1039627991] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:799; }","duration":"341.240981ms","start":"2026-02-09T03:10:07.727809Z","end":"2026-02-09T03:10:08.06905Z","steps":["trace[1039627991] 'range keys from in-memory index tree' (duration: 340.893293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:08.069112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:07.727753Z","time spent":"341.350543ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T03:10:08.069824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.927872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T03:10:08.069855Z","caller":"traceutil/trace.go:171","msg":"trace[540983401] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:799; }","duration":"103.991524ms","start":"2026-02-09T03:10:07.965854Z","end":"2026-02-09T03:10:08.069845Z","steps":["trace[540983401] 'count revisions from in-memory index tree' (duration: 103.85792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:08.357939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.66042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:10:08.358187Z","caller":"traceutil/trace.go:171","msg":"trace[1697100051] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"228.586231ms","start":"2026-02-09T03:10:08.129584Z","end":"2026-02-09T03:10:08.358171Z","steps":["trace[1697100051] 'process raft request' (duration: 44.198953ms)","trace[1697100051] 'compare' (duration: 183.544647ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:10:08.360985Z","caller":"traceutil/trace.go:171","msg":"trace[25625795] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"229.886784ms","start":"2026-02-09T03:10:08.131088Z","end":"2026-02-09T03:10:08.360975Z","steps":["trace[25625795] 'process raft request' (duration: 229.624918ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:10:08.361507Z","caller":"traceutil/trace.go:171","msg":"trace[30788734] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"230.373387ms","start":"2026-02-09T03:10:08.131124Z","end":"2026-02-09T03:10:08.361497Z","steps":["trace[30788734] 'process raft request' (duration: 229.644368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:20.860129Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697148886631310,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T03:10:21.061009Z","caller":"traceutil/trace.go:171","msg":"trace[2128913861] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"888.733695ms","start":"2026-02-09T03:10:20.172264Z","end":"2026-02-09T03:10:21.060998Z","steps":["trace[2128913861] 'process raft request' (duration: 888.662813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:21.061091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:20.172254Z","time spent":"888.794516ms","remote":"127.0.0.1:45628","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-09T03:10:22.470201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.073024ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-09T03:10:22.470334Z","caller":"traceutil/trace.go:171","msg":"trace[1942929501] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"2.111221574s","start":"2026-02-09T03:10:20.3591Z","end":"2026-02-09T03:10:22.470322Z","steps":["trace[1942929501] 'read index received' (duration: 702.167182ms)","trace[1942929501] 'applied index is now lower than readState.Index' (duration: 1.409053802s)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:10:22.470557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.11145494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T03:10:22.470579Z","caller":"traceutil/trace.go:171","msg":"trace[662102670] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:886; }","duration":"2.111506431s","start":"2026-02-09T03:10:20.359065Z","end":"2026-02-09T03:10:22.470572Z","steps":["trace[662102670] 'agreement among raft nodes before linearized reading' (duration: 2.11146364s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:22.470602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:20.359032Z","time spent":"2.111564962s","remote":"127.0.0.1:45550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-02-09T03:10:22.470853Z","caller":"traceutil/trace.go:171","msg":"trace[1740349614] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"1.410029426s","start":"2026-02-09T03:10:21.060817Z","end":"2026-02-09T03:10:22.470847Z","steps":["trace[1740349614] 'process raft request' (duration: 1.409449571s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:22.47091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:21.060801Z","time spent":"1.410070357s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:10:22.474749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.0308888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T03:10:22.474794Z","caller":"traceutil/trace.go:171","msg":"trace[1438614637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:887; }","duration":"2.030970132s","start":"2026-02-09T03:10:20.443813Z","end":"2026-02-09T03:10:22.474783Z","steps":["trace[1438614637] 'agreement among raft nodes before linearized reading' (duration: 2.027216168s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:22.474817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:20.443799Z","time spent":"2.031013123s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T03:10:22.474951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.477567411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:10:22.474967Z","caller":"traceutil/trace.go:171","msg":"trace[69972349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:887; }","duration":"1.477600092s","start":"2026-02-09T03:10:20.997361Z","end":"2026-02-09T03:10:22.474961Z","steps":["trace[69972349] 'agreement among raft nodes before linearized reading' (duration: 1.473658873s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:22.474964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.13583ms","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-09T03:10:22.475001Z","caller":"traceutil/trace.go:171","msg":"trace[1560418902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"827.199422ms","start":"2026-02-09T03:10:21.647792Z","end":"2026-02-09T03:10:22.474992Z","steps":["trace[1560418902] 'agreement among raft nodes before linearized reading' (duration: 823.220932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:22.475018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.031122116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-09T03:10:22.475024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:21.64778Z","time spent":"827.239252ms","remote":"127.0.0.1:45628","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":"info","ts":"2026-02-09T03:10:22.475033Z","caller":"traceutil/trace.go:171","msg":"trace[1371399029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:887; }","duration":"2.031148386s","start":"2026-02-09T03:10:20.443879Z","end":"2026-02-09T03:10:22.475027Z","steps":["trace[1371399029] 'agreement among raft nodes before linearized reading' (duration: 2.027144826s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:22.475048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:20.44385Z","time spent":"2.031195228s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T03:10:22.475133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.06011058s","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-09T03:10:22.47515Z","caller":"traceutil/trace.go:171","msg":"trace[1980837445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:887; }","duration":"2.06015117s","start":"2026-02-09T03:10:20.414992Z","end":"2026-02-09T03:10:22.475144Z","steps":["trace[1980837445] 'agreement among raft nodes before linearized reading' (duration: 2.055841492s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:22.475182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:20.414975Z","time spent":"2.060197292s","remote":"127.0.0.1:45628","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-09T03:10:22.474981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:20.997349Z","time spent":"1.477629752s","remote":"127.0.0.1:45374","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-09T03:10:34.972797Z","caller":"traceutil/trace.go:171","msg":"trace[108901547] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"289.796876ms","start":"2026-02-09T03:10:34.682977Z","end":"2026-02-09T03:10:34.972773Z","steps":["trace[108901547] 'process raft request' (duration: 289.703083ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:10:34.974182Z","caller":"traceutil/trace.go:171","msg":"trace[1637105884] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:959; }","duration":"290.051091ms","start":"2026-02-09T03:10:34.684114Z","end":"2026-02-09T03:10:34.974165Z","steps":["trace[1637105884] 'read index received' (duration: 289.004494ms)","trace[1637105884] 'applied index is now lower than readState.Index' (duration: 1.046037ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:10:34.974251Z","caller":"traceutil/trace.go:171","msg":"trace[1800549976] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"290.837001ms","start":"2026-02-09T03:10:34.683407Z","end":"2026-02-09T03:10:34.974244Z","steps":["trace[1800549976] 'process raft request' (duration: 290.638656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:34.974344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.215595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-09T03:10:34.974378Z","caller":"traceutil/trace.go:171","msg":"trace[1646821754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:923; }","duration":"290.302488ms","start":"2026-02-09T03:10:34.684066Z","end":"2026-02-09T03:10:34.974369Z","steps":["trace[1646821754] 'agreement among raft nodes before linearized reading' (duration: 290.199245ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:10:34.974472Z","caller":"traceutil/trace.go:171","msg":"trace[756105939] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"291.002215ms","start":"2026-02-09T03:10:34.683463Z","end":"2026-02-09T03:10:34.974466Z","steps":["trace[756105939] 'process raft request' (duration: 290.673447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:34.974479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.588529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-09T03:10:34.974503Z","caller":"traceutil/trace.go:171","msg":"trace[1796077492] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:923; }","duration":"210.644722ms","start":"2026-02-09T03:10:34.76385Z","end":"2026-02-09T03:10:34.974495Z","steps":["trace[1796077492] 'agreement among raft nodes before linearized reading' (duration: 210.552038ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:10:37.403402Z","caller":"traceutil/trace.go:171","msg":"trace[79520429] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"425.855508ms","start":"2026-02-09T03:10:36.977524Z","end":"2026-02-09T03:10:37.403379Z","steps":["trace[79520429] 'process raft request' (duration: 425.643872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:37.403544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:36.977515Z","time spent":"425.95245ms","remote":"127.0.0.1:45628","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-09T03:10:37.404006Z","caller":"traceutil/trace.go:171","msg":"trace[1156548783] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:966; }","duration":"425.623823ms","start":"2026-02-09T03:10:36.97837Z","end":"2026-02-09T03:10:37.403994Z","steps":["trace[1156548783] 'read index received' (duration: 425.619662ms)","trace[1156548783] 'applied index is now lower than readState.Index' (duration: 3.281µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:10:37.404318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.95016ms","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-09T03:10:37.404363Z","caller":"traceutil/trace.go:171","msg":"trace[411140144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"426.008942ms","start":"2026-02-09T03:10:36.978343Z","end":"2026-02-09T03:10:37.404352Z","steps":["trace[411140144] 'agreement among raft nodes before linearized reading' (duration: 425.708894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:37.404394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:36.978338Z","time spent":"426.048703ms","remote":"127.0.0.1:45628","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":"info","ts":"2026-02-09T03:10:37.413499Z","caller":"traceutil/trace.go:171","msg":"trace[1681181847] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"434.244242ms","start":"2026-02-09T03:10:36.979244Z","end":"2026-02-09T03:10:37.413488Z","steps":["trace[1681181847] 'process raft request' (duration: 434.16471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:37.413792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:36.979227Z","time spent":"434.489008ms","remote":"127.0.0.1:45628","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-09T03:10:37.414198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.507727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-09T03:10:37.415128Z","caller":"traceutil/trace.go:171","msg":"trace[1433437224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:928; }","duration":"433.448672ms","start":"2026-02-09T03:10:36.981669Z","end":"2026-02-09T03:10:37.415118Z","steps":["trace[1433437224] 'agreement among raft nodes before linearized reading' (duration: 432.466726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:37.415519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:36.981664Z","time spent":"433.78955ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T03:10:37.415935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.001449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:10:37.415981Z","caller":"traceutil/trace.go:171","msg":"trace[499112526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"420.06109ms","start":"2026-02-09T03:10:36.99591Z","end":"2026-02-09T03:10:37.415971Z","steps":["trace[499112526] 'agreement among raft nodes before linearized reading' (duration: 419.995579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:37.415999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:36.995905Z","time spent":"420.090212ms","remote":"127.0.0.1:45374","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-09T03:10:37.416181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.772805ms","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-09T03:10:37.416203Z","caller":"traceutil/trace.go:171","msg":"trace[1689854967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:929; }","duration":"258.824327ms","start":"2026-02-09T03:10:37.157372Z","end":"2026-02-09T03:10:37.416196Z","steps":["trace[1689854967] 'agreement among raft nodes before linearized reading' (duration: 258.743815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:45.149781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.970527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:10:45.150198Z","caller":"traceutil/trace.go:171","msg":"trace[1256096755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:976; }","duration":"150.48439ms","start":"2026-02-09T03:10:44.999693Z","end":"2026-02-09T03:10:45.150178Z","steps":["trace[1256096755] 'range keys from in-memory index tree' (duration: 149.844154ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:10:45.72359Z","caller":"traceutil/trace.go:171","msg":"trace[346691174] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"212.94295ms","start":"2026-02-09T03:10:45.510627Z","end":"2026-02-09T03:10:45.72357Z","steps":["trace[346691174] 'process raft request' (duration: 194.709015ms)","trace[346691174] 'compare' (duration: 18.008408ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:10:47.321553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.321568435s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:10:47.321603Z","caller":"traceutil/trace.go:171","msg":"trace[500514839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"1.321692218s","start":"2026-02-09T03:10:45.999899Z","end":"2026-02-09T03:10:47.321592Z","steps":["trace[500514839] 'range keys from in-memory index tree' (duration: 1.321511623s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:47.321625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:45.999887Z","time spent":"1.321733209s","remote":"127.0.0.1:45374","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-09T03:10:47.321526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.104626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T03:10:47.321857Z","caller":"traceutil/trace.go:171","msg":"trace[286597483] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:981; }","duration":"762.483925ms","start":"2026-02-09T03:10:46.559357Z","end":"2026-02-09T03:10:47.321841Z","steps":["trace[286597483] 'count revisions from in-memory index tree' (duration: 761.988083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:47.321892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:46.559338Z","time spent":"762.546236ms","remote":"127.0.0.1:33838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-09T03:10:50.43171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.832457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T03:10:50.431779Z","caller":"traceutil/trace.go:171","msg":"trace[1333266553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:997; }","duration":"867.950439ms","start":"2026-02-09T03:10:49.563812Z","end":"2026-02-09T03:10:50.431762Z","steps":["trace[1333266553] 'range keys from in-memory index tree' (duration: 867.589011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:50.431819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:49.563789Z","time spent":"868.022632ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T03:10:50.432122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.218075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-09T03:10:50.432155Z","caller":"traceutil/trace.go:171","msg":"trace[520753407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:997; }","duration":"699.250486ms","start":"2026-02-09T03:10:49.732896Z","end":"2026-02-09T03:10:50.432146Z","steps":["trace[520753407] 'range keys from in-memory index tree' (duration: 698.580439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:50.432181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:49.732883Z","time spent":"699.292137ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-09T03:10:50.432182Z","caller":"traceutil/trace.go:171","msg":"trace[1141741946] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"539.929001ms","start":"2026-02-09T03:10:49.892225Z","end":"2026-02-09T03:10:50.432154Z","steps":["trace[1141741946] 'process raft request' (duration: 539.262534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:50.432304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:49.892198Z","time spent":"540.038463ms","remote":"127.0.0.1:45550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:10:50.452698Z","caller":"traceutil/trace.go:171","msg":"trace[1167566607] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"392.037068ms","start":"2026-02-09T03:10:50.060627Z","end":"2026-02-09T03:10:50.452664Z","steps":["trace[1167566607] 'process raft request' (duration: 391.873454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:50.452798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:50.060603Z","time spent":"392.141991ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:10:53.793756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.950327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:10:53.793882Z","caller":"traceutil/trace.go:171","msg":"trace[1547742566] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"213.583185ms","start":"2026-02-09T03:10:53.580271Z","end":"2026-02-09T03:10:53.793853Z","steps":["trace[1547742566] 'process raft request' (duration: 92.448273ms)","trace[1547742566] 'compare' (duration: 120.497636ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:10:53.813065Z","caller":"traceutil/trace.go:171","msg":"trace[1582734426] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"227.319265ms","start":"2026-02-09T03:10:53.585729Z","end":"2026-02-09T03:10:53.813049Z","steps":["trace[1582734426] 'process raft request' (duration: 227.283324ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:10:53.813625Z","caller":"traceutil/trace.go:171","msg":"trace[1335010117] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"230.397893ms","start":"2026-02-09T03:10:53.583217Z","end":"2026-02-09T03:10:53.813614Z","steps":["trace[1335010117] 'process raft request' (duration: 229.647204ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:10:53.814286Z","caller":"traceutil/trace.go:171","msg":"trace[808714541] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"230.917518ms","start":"2026-02-09T03:10:53.58336Z","end":"2026-02-09T03:10:53.814278Z","steps":["trace[808714541] 'process raft request' (duration: 229.594883ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:10:53.81502Z","caller":"traceutil/trace.go:171","msg":"trace[1546022108] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"229.280775ms","start":"2026-02-09T03:10:53.58573Z","end":"2026-02-09T03:10:53.815011Z","steps":["trace[1546022108] 'process raft request' (duration: 227.255163ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:10:53.974945Z","caller":"traceutil/trace.go:171","msg":"trace[2011338130] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"148.12007ms","start":"2026-02-09T03:10:53.82681Z","end":"2026-02-09T03:10:53.97493Z","steps":["trace[2011338130] 'process raft request' (duration: 148.015118ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:10:56.028611Z","caller":"traceutil/trace.go:171","msg":"trace[1846336497] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"193.351131ms","start":"2026-02-09T03:10:55.835235Z","end":"2026-02-09T03:10:56.028586Z","steps":["trace[1846336497] 'process raft request' (duration: 193.074614ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:10:56.257604Z","caller":"traceutil/trace.go:171","msg":"trace[358922844] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"420.03496ms","start":"2026-02-09T03:10:55.837544Z","end":"2026-02-09T03:10:56.257579Z","steps":["trace[358922844] 'process raft request' (duration: 417.077354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:10:56.258876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:10:55.837532Z","time spent":"421.276652ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:11:00.227016Z","caller":"traceutil/trace.go:171","msg":"trace[248287763] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"175.859536ms","start":"2026-02-09T03:11:00.051132Z","end":"2026-02-09T03:11:00.226992Z","steps":["trace[248287763] 'read index received' (duration: 128.527972ms)","trace[248287763] 'applied index is now lower than readState.Index' (duration: 47.330144ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:11:00.227682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.527223ms","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-09T03:11:00.227831Z","caller":"traceutil/trace.go:171","msg":"trace[1302532709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1059; }","duration":"176.710177ms","start":"2026-02-09T03:11:00.051107Z","end":"2026-02-09T03:11:00.227817Z","steps":["trace[1302532709] 'agreement among raft nodes before linearized reading' (duration: 176.0449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:11:00.851251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.90818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:11:00.852114Z","caller":"traceutil/trace.go:171","msg":"trace[296739209] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"196.45918ms","start":"2026-02-09T03:11:00.655611Z","end":"2026-02-09T03:11:00.85207Z","steps":["trace[296739209] 'read index received' (duration: 66.638906ms)","trace[296739209] 'applied index is now lower than readState.Index' (duration: 129.818994ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:11:00.852146Z","caller":"traceutil/trace.go:171","msg":"trace[695566028] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"206.218098ms","start":"2026-02-09T03:11:00.645901Z","end":"2026-02-09T03:11:00.852119Z","steps":["trace[695566028] 'process raft request' (duration: 76.376244ms)","trace[695566028] 'compare' (duration: 128.736666ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:11:00.852233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.619814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-09T03:11:00.852259Z","caller":"traceutil/trace.go:171","msg":"trace[310571993] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1064; }","duration":"196.666554ms","start":"2026-02-09T03:11:00.655583Z","end":"2026-02-09T03:11:00.852249Z","steps":["trace[310571993] 'agreement among raft nodes before linearized reading' (duration: 196.553381ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:11:01.574147Z","caller":"traceutil/trace.go:171","msg":"trace[1460831246] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"181.53605ms","start":"2026-02-09T03:11:01.392588Z","end":"2026-02-09T03:11:01.574124Z","steps":["trace[1460831246] 'process raft request' (duration: 180.979865ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:11:02.430461Z","caller":"traceutil/trace.go:171","msg":"trace[1696853046] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"121.453451ms","start":"2026-02-09T03:11:02.308988Z","end":"2026-02-09T03:11:02.430441Z","steps":["trace[1696853046] 'process raft request' (duration: 120.762184ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:11:02.568124Z","caller":"traceutil/trace.go:171","msg":"trace[1293244882] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"132.126523ms","start":"2026-02-09T03:11:02.435939Z","end":"2026-02-09T03:11:02.568065Z","steps":["trace[1293244882] 'process raft request' (duration: 57.31809ms)","trace[1293244882] 'compare' (duration: 74.440844ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:11:03.159496Z","caller":"traceutil/trace.go:171","msg":"trace[1202250370] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"160.034064ms","start":"2026-02-09T03:11:02.999444Z","end":"2026-02-09T03:11:03.159478Z","steps":["trace[1202250370] 'read index received' (duration: 101.72723ms)","trace[1202250370] 'applied index is now lower than readState.Index' (duration: 58.305684ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:11:03.159586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.128896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:11:03.159606Z","caller":"traceutil/trace.go:171","msg":"trace[594118821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1074; }","duration":"160.183628ms","start":"2026-02-09T03:11:02.999417Z","end":"2026-02-09T03:11:03.1596Z","steps":["trace[594118821] 'agreement among raft nodes before linearized reading' (duration: 160.124917ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:11:03.159938Z","caller":"traceutil/trace.go:171","msg":"trace[567742052] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"222.823581ms","start":"2026-02-09T03:11:02.937103Z","end":"2026-02-09T03:11:03.159926Z","steps":["trace[567742052] 'process raft request' (duration: 164.031065ms)","trace[567742052] 'compare' (duration: 57.891403ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:11:50.115048Z","caller":"traceutil/trace.go:171","msg":"trace[454289663] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"215.044986ms","start":"2026-02-09T03:11:49.899973Z","end":"2026-02-09T03:11:50.115018Z","steps":["trace[454289663] 'process raft request' (duration: 116.319667ms)","trace[454289663] 'compare' (duration: 98.598526ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:11:50.991191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.57101ms","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-09T03:11:50.991282Z","caller":"traceutil/trace.go:171","msg":"trace[2065067909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1266; }","duration":"228.702803ms","start":"2026-02-09T03:11:50.76256Z","end":"2026-02-09T03:11:50.991263Z","steps":["trace[2065067909] 'range keys from in-memory index tree' (duration: 228.470967ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:11:52.017553Z","caller":"traceutil/trace.go:171","msg":"trace[1598225844] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"113.081104ms","start":"2026-02-09T03:11:51.904446Z","end":"2026-02-09T03:11:52.017528Z","steps":["trace[1598225844] 'process raft request' (duration: 112.93165ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:11:53.037557Z","caller":"traceutil/trace.go:171","msg":"trace[1226319273] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"190.502031ms","start":"2026-02-09T03:11:52.84704Z","end":"2026-02-09T03:11:53.037542Z","steps":["trace[1226319273] 'process raft request' (duration: 190.180063ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:11:53.177468Z","caller":"traceutil/trace.go:171","msg":"trace[57995584] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"122.126573ms","start":"2026-02-09T03:11:53.055304Z","end":"2026-02-09T03:11:53.17743Z","steps":["trace[57995584] 'read index received' (duration: 32.628608ms)","trace[57995584] 'applied index is now lower than readState.Index' (duration: 89.496295ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:11:53.17793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.560715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T03:11:53.17855Z","caller":"traceutil/trace.go:171","msg":"trace[2138472771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1277; }","duration":"123.272833ms","start":"2026-02-09T03:11:53.055258Z","end":"2026-02-09T03:11:53.178531Z","steps":["trace[2138472771] 'agreement among raft nodes before linearized reading' (duration: 122.41888ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:11:53.178829Z","caller":"traceutil/trace.go:171","msg":"trace[2053346568] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"134.171639ms","start":"2026-02-09T03:11:53.043445Z","end":"2026-02-09T03:11:53.177617Z","steps":["trace[2053346568] 'process raft request' (duration: 44.529621ms)","trace[2053346568] 'compare' (duration: 89.295749ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:11:54.409961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.647879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:11:54.410161Z","caller":"traceutil/trace.go:171","msg":"trace[1148532261] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"158.794856ms","start":"2026-02-09T03:11:54.251347Z","end":"2026-02-09T03:11:54.410142Z","steps":["trace[1148532261] 'read index received' (duration: 40.351µs)","trace[1148532261] 'applied index is now lower than readState.Index' (duration: 158.752725ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:11:54.41065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.291909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-09T03:11:54.410738Z","caller":"traceutil/trace.go:171","msg":"trace[1474144282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1282; }","duration":"159.415982ms","start":"2026-02-09T03:11:54.251308Z","end":"2026-02-09T03:11:54.410724Z","steps":["trace[1474144282] 'agreement among raft nodes before linearized reading' (duration: 158.980211ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:11:54.411258Z","caller":"traceutil/trace.go:171","msg":"trace[121567324] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"384.77917ms","start":"2026-02-09T03:11:54.02645Z","end":"2026-02-09T03:11:54.411229Z","steps":["trace[121567324] 'process raft request' (duration: 184.813977ms)","trace[121567324] 'compare' (duration: 198.109776ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:11:54.412749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:11:54.026431Z","time spent":"384.898333ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:11:55.018803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.943414ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-09T03:11:55.195502Z","caller":"traceutil/trace.go:171","msg":"trace[1755900419] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"175.627854ms","start":"2026-02-09T03:11:55.019857Z","end":"2026-02-09T03:11:55.195485Z","steps":["trace[1755900419] 'process raft request' (duration: 113.422194ms)","trace[1755900419] 'compare' (duration: 62.049476ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:11:55.390388Z","caller":"traceutil/trace.go:171","msg":"trace[1502373837] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"111.453002ms","start":"2026-02-09T03:11:55.278912Z","end":"2026-02-09T03:11:55.390365Z","steps":["trace[1502373837] 'process raft request' (duration: 111.285608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:11:55.65956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.008164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-09T03:11:55.659607Z","caller":"traceutil/trace.go:171","msg":"trace[570631155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1287; }","duration":"130.131257ms","start":"2026-02-09T03:11:55.529466Z","end":"2026-02-09T03:11:55.659597Z","steps":["trace[570631155] 'range keys from in-memory index tree' (duration: 129.8731ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:11:58.609112Z","caller":"traceutil/trace.go:171","msg":"trace[1819976355] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1354; }","duration":"136.56483ms","start":"2026-02-09T03:11:58.472513Z","end":"2026-02-09T03:11:58.609078Z","steps":["trace[1819976355] 'read index received' (duration: 57.061809ms)","trace[1819976355] 'applied index is now lower than readState.Index' (duration: 79.502001ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:11:58.609289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.753496ms","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-09T03:11:58.609327Z","caller":"traceutil/trace.go:171","msg":"trace[870579511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1299; }","duration":"136.808497ms","start":"2026-02-09T03:11:58.472508Z","end":"2026-02-09T03:11:58.609317Z","steps":["trace[870579511] 'agreement among raft nodes before linearized reading' (duration: 136.667733ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:11:58.609501Z","caller":"traceutil/trace.go:171","msg":"trace[1327942633] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"166.083451ms","start":"2026-02-09T03:11:58.443394Z","end":"2026-02-09T03:11:58.609477Z","steps":["trace[1327942633] 'process raft request' (duration: 86.225172ms)","trace[1327942633] 'compare' (duration: 79.366697ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:07.426906Z","caller":"traceutil/trace.go:171","msg":"trace[3846861] linearizableReadLoop","detail":"{readStateIndex:1931; appliedIndex:1930; }","duration":"197.919281ms","start":"2026-02-09T03:14:07.228925Z","end":"2026-02-09T03:14:07.426845Z","steps":["trace[3846861] 'read index received' (duration: 197.796908ms)","trace[3846861] 'applied index is now lower than readState.Index' (duration: 121.163µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:07.4271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.151357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-09T03:14:07.42715Z","caller":"traceutil/trace.go:171","msg":"trace[2137108111] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1848; }","duration":"198.213698ms","start":"2026-02-09T03:14:07.228922Z","end":"2026-02-09T03:14:07.427135Z","steps":["trace[2137108111] 'agreement among raft nodes before linearized reading' (duration: 198.063445ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:07.427268Z","caller":"traceutil/trace.go:171","msg":"trace[1264190806] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"198.739871ms","start":"2026-02-09T03:14:07.228518Z","end":"2026-02-09T03:14:07.427258Z","steps":["trace[1264190806] 'process raft request' (duration: 198.188168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:13.401893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.037419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-09T03:14:13.401982Z","caller":"traceutil/trace.go:171","msg":"trace[1504507590] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1874; }","duration":"121.176342ms","start":"2026-02-09T03:14:13.280787Z","end":"2026-02-09T03:14:13.401963Z","steps":["trace[1504507590] 'range keys from in-memory index tree' (duration: 120.810183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:13.66524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.899333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:14:13.66536Z","caller":"traceutil/trace.go:171","msg":"trace[77728967] linearizableReadLoop","detail":"{readStateIndex:1960; appliedIndex:1959; }","duration":"136.419068ms","start":"2026-02-09T03:14:13.528922Z","end":"2026-02-09T03:14:13.665341Z","steps":["trace[77728967] 'read index received' (duration: 35.431µs)","trace[77728967] 'applied index is now lower than readState.Index' (duration: 136.382267ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:13.665575Z","caller":"traceutil/trace.go:171","msg":"trace[824088584] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"181.60323ms","start":"2026-02-09T03:14:13.483947Z","end":"2026-02-09T03:14:13.66555Z","steps":["trace[824088584] 'process raft request' (duration: 37.309848ms)","trace[824088584] 'compare' (duration: 143.78363ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:13.66573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.817687ms","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-09T03:14:13.665778Z","caller":"traceutil/trace.go:171","msg":"trace[1789816180] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1876; }","duration":"136.90286ms","start":"2026-02-09T03:14:13.528861Z","end":"2026-02-09T03:14:13.665764Z","steps":["trace[1789816180] 'agreement among raft nodes before linearized reading' (duration: 136.691465ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:13.86474Z","caller":"traceutil/trace.go:171","msg":"trace[1267892022] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"192.263642ms","start":"2026-02-09T03:14:13.6724Z","end":"2026-02-09T03:14:13.864663Z","steps":["trace[1267892022] 'process raft request' (duration: 106.697557ms)","trace[1267892022] 'compare' (duration: 85.201097ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:13.865492Z","caller":"traceutil/trace.go:171","msg":"trace[2144541298] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"190.482558ms","start":"2026-02-09T03:14:13.674995Z","end":"2026-02-09T03:14:13.865477Z","steps":["trace[2144541298] 'process raft request' (duration: 189.432443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:13.868432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.019987ms","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:526"} {"level":"info","ts":"2026-02-09T03:14:13.868738Z","caller":"traceutil/trace.go:171","msg":"trace[1686193076] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1878; }","duration":"162.328705ms","start":"2026-02-09T03:14:13.706353Z","end":"2026-02-09T03:14:13.868682Z","steps":["trace[1686193076] 'agreement among raft nodes before linearized reading' (duration: 161.920775ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:13.864502Z","caller":"traceutil/trace.go:171","msg":"trace[269641738] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1960; }","duration":"158.077281ms","start":"2026-02-09T03:14:13.706402Z","end":"2026-02-09T03:14:13.86448Z","steps":["trace[269641738] 'read index received' (duration: 72.775422ms)","trace[269641738] 'applied index is now lower than readState.Index' (duration: 85.296829ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:14.143727Z","caller":"traceutil/trace.go:171","msg":"trace[1976245750] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1962; }","duration":"130.384059ms","start":"2026-02-09T03:14:14.013325Z","end":"2026-02-09T03:14:14.143709Z","steps":["trace[1976245750] 'read index received' (duration: 130.287597ms)","trace[1976245750] 'applied index is now lower than readState.Index' (duration: 95.832µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:14.143808Z","caller":"traceutil/trace.go:171","msg":"trace[2074765661] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"262.226454ms","start":"2026-02-09T03:14:13.881576Z","end":"2026-02-09T03:14:14.143802Z","steps":["trace[2074765661] 'process raft request' (duration: 262.02321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:14.143937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.602995ms","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-09T03:14:14.143951Z","caller":"traceutil/trace.go:171","msg":"trace[1480857045] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1879; }","duration":"130.661736ms","start":"2026-02-09T03:14:14.013285Z","end":"2026-02-09T03:14:14.143947Z","steps":["trace[1480857045] 'agreement among raft nodes before linearized reading' (duration: 130.590394ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:14.28033Z","caller":"traceutil/trace.go:171","msg":"trace[1059434849] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"129.420446ms","start":"2026-02-09T03:14:14.150884Z","end":"2026-02-09T03:14:14.280304Z","steps":["trace[1059434849] 'process raft request' (duration: 91.521634ms)","trace[1059434849] 'compare' (duration: 37.82452ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:14.611273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.687429ms","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-09T03:14:14.611363Z","caller":"traceutil/trace.go:171","msg":"trace[443119465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1881; }","duration":"124.814273ms","start":"2026-02-09T03:14:14.486527Z","end":"2026-02-09T03:14:14.611342Z","steps":["trace[443119465] 'range keys from in-memory index tree' (duration: 124.515255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:15.205963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.856954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2816"} {"level":"info","ts":"2026-02-09T03:14:15.206074Z","caller":"traceutil/trace.go:171","msg":"trace[1559565853] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1883; }","duration":"192.008677ms","start":"2026-02-09T03:14:15.014043Z","end":"2026-02-09T03:14:15.206051Z","steps":["trace[1559565853] 'range keys from in-memory index tree' (duration: 191.69234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:15.20653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.702543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:14:15.20657Z","caller":"traceutil/trace.go:171","msg":"trace[1177379819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1883; }","duration":"205.770615ms","start":"2026-02-09T03:14:15.000789Z","end":"2026-02-09T03:14:15.20656Z","steps":["trace[1177379819] 'range keys from in-memory index tree' (duration: 205.649852ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:15.4674Z","caller":"traceutil/trace.go:171","msg":"trace[335488083] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"215.320811ms","start":"2026-02-09T03:14:15.252061Z","end":"2026-02-09T03:14:15.467382Z","steps":["trace[335488083] 'process raft request' (duration: 159.734452ms)","trace[335488083] 'compare' (duration: 55.330443ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:17.086878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.909215ms","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-09T03:14:17.086944Z","caller":"traceutil/trace.go:171","msg":"trace[1033994263] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1886; }","duration":"801.022628ms","start":"2026-02-09T03:14:16.285909Z","end":"2026-02-09T03:14:17.086932Z","steps":["trace[1033994263] 'range keys from in-memory index tree' (duration: 800.789423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.086971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:16.285892Z","time spent":"801.07166ms","remote":"127.0.0.1:45628","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-09T03:14:17.087018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.015077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-09T03:14:17.08704Z","caller":"traceutil/trace.go:171","msg":"trace[423041048] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1886; }","duration":"697.075639ms","start":"2026-02-09T03:14:16.389958Z","end":"2026-02-09T03:14:17.087033Z","steps":["trace[423041048] 'range keys from in-memory index tree' (duration: 696.917664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.087058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:16.389932Z","time spent":"697.12123ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-09T03:14:17.087127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.411636887s","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-09T03:14:17.087147Z","caller":"traceutil/trace.go:171","msg":"trace[1887909138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1886; }","duration":"1.411685538s","start":"2026-02-09T03:14:15.675456Z","end":"2026-02-09T03:14:17.087142Z","steps":["trace[1887909138] 'range keys from in-memory index tree' (duration: 1.411526904s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.087166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:15.675427Z","time spent":"1.411734159s","remote":"127.0.0.1:45628","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-09T03:14:17.087247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.015984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:14:17.087267Z","caller":"traceutil/trace.go:171","msg":"trace[1659055575] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1886; }","duration":"430.063565ms","start":"2026-02-09T03:14:16.657197Z","end":"2026-02-09T03:14:17.087261Z","steps":["trace[1659055575] 'range keys from in-memory index tree' (duration: 429.966243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.087287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:16.657182Z","time spent":"430.099446ms","remote":"127.0.0.1:45512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-02-09T03:14:17.087456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.566576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-09T03:14:17.087466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212927825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-09T03:14:17.087475Z","caller":"traceutil/trace.go:171","msg":"trace[1269516872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1886; }","duration":"369.625667ms","start":"2026-02-09T03:14:16.717844Z","end":"2026-02-09T03:14:17.08747Z","steps":["trace[1269516872] 'range keys from in-memory index tree' (duration: 369.485754ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:17.087484Z","caller":"traceutil/trace.go:171","msg":"trace[1182042780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1886; }","duration":"1.213017709s","start":"2026-02-09T03:14:15.874461Z","end":"2026-02-09T03:14:17.087479Z","steps":["trace[1182042780] 'range keys from in-memory index tree' (duration: 1.212841393s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.0875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:15.874439Z","time spent":"1.213056759s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T03:14:17.087493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:16.71783Z","time spent":"369.657858ms","remote":"127.0.0.1:45628","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-09T03:14:17.08766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203893454s","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-09T03:14:17.087679Z","caller":"traceutil/trace.go:171","msg":"trace[138583212] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1886; }","duration":"1.203935055s","start":"2026-02-09T03:14:15.883739Z","end":"2026-02-09T03:14:17.087674Z","steps":["trace[138583212] 'range keys from in-memory index tree' (duration: 1.203826252s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.087696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:15.883726Z","time spent":"1.203964826s","remote":"127.0.0.1:45628","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":"warn","ts":"2026-02-09T03:14:17.087801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086251358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:14:17.087819Z","caller":"traceutil/trace.go:171","msg":"trace[93228418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1886; }","duration":"1.08629448s","start":"2026-02-09T03:14:16.001519Z","end":"2026-02-09T03:14:17.087814Z","steps":["trace[93228418] 'range keys from in-memory index tree' (duration: 1.086210627s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.087838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:16.001505Z","time spent":"1.08632667s","remote":"127.0.0.1:45374","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-09T03:14:17.087927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.63975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T03:14:17.087948Z","caller":"traceutil/trace.go:171","msg":"trace[1144417854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1886; }","duration":"898.714512ms","start":"2026-02-09T03:14:16.189227Z","end":"2026-02-09T03:14:17.087942Z","steps":["trace[1144417854] 'range keys from in-memory index tree' (duration: 898.547558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.087966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:16.189211Z","time spent":"898.749653ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T03:14:17.593813Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697148886633852,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T03:14:17.659236Z","caller":"traceutil/trace.go:171","msg":"trace[1839204597] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"580.534462ms","start":"2026-02-09T03:14:17.078676Z","end":"2026-02-09T03:14:17.65921Z","steps":["trace[1839204597] 'process raft request' (duration: 580.360608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.659386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:17.078659Z","time spent":"580.649246ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:14:17.766643Z","caller":"traceutil/trace.go:171","msg":"trace[1893898344] linearizableReadLoop","detail":"{readStateIndex:1979; appliedIndex:1972; }","duration":"673.842678ms","start":"2026-02-09T03:14:17.09278Z","end":"2026-02-09T03:14:17.766623Z","steps":["trace[1893898344] 'read index received' (duration: 568.26601ms)","trace[1893898344] 'applied index is now lower than readState.Index' (duration: 105.575878ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:17.767314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.747169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:14:17.767361Z","caller":"traceutil/trace.go:171","msg":"trace[283939643] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1894; }","duration":"531.820971ms","start":"2026-02-09T03:14:17.235521Z","end":"2026-02-09T03:14:17.767342Z","steps":["trace[283939643] 'agreement among raft nodes before linearized reading' (duration: 531.740649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.767386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:17.235502Z","time spent":"531.877472ms","remote":"127.0.0.1:45512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-02-09T03:14:17.767748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.963455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:14:17.767782Z","caller":"traceutil/trace.go:171","msg":"trace[698003657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1894; }","duration":"675.013037ms","start":"2026-02-09T03:14:17.092757Z","end":"2026-02-09T03:14:17.76777Z","steps":["trace[698003657] 'agreement among raft nodes before linearized reading' (duration: 674.968406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.767798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:17.092743Z","time spent":"675.050447ms","remote":"127.0.0.1:45390","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-09T03:14:17.768051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.944196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:14:17.768134Z","caller":"traceutil/trace.go:171","msg":"trace[1854539177] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1894; }","duration":"121.053048ms","start":"2026-02-09T03:14:17.647059Z","end":"2026-02-09T03:14:17.768112Z","steps":["trace[1854539177] 'agreement among raft nodes before linearized reading' (duration: 120.792342ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:17.768215Z","caller":"traceutil/trace.go:171","msg":"trace[1578350012] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"672.066262ms","start":"2026-02-09T03:14:17.096137Z","end":"2026-02-09T03:14:17.768203Z","steps":["trace[1578350012] 'process raft request' (duration: 663.127812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.76826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:17.096121Z","time spent":"672.108834ms","remote":"127.0.0.1:45628","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-09T03:14:17.768498Z","caller":"traceutil/trace.go:171","msg":"trace[697971814] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"668.641159ms","start":"2026-02-09T03:14:17.09985Z","end":"2026-02-09T03:14:17.768492Z","steps":["trace[697971814] 'process raft request' (duration: 666.473385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.768529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:17.099835Z","time spent":"668.67775ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:14:17.768843Z","caller":"traceutil/trace.go:171","msg":"trace[2005695476] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"668.913705ms","start":"2026-02-09T03:14:17.099923Z","end":"2026-02-09T03:14:17.768836Z","steps":["trace[2005695476] 'process raft request' (duration: 666.450735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.768872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:17.099915Z","time spent":"668.942937ms","remote":"127.0.0.1:45628","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-09T03:14:17.769062Z","caller":"traceutil/trace.go:171","msg":"trace[1458522166] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"667.923632ms","start":"2026-02-09T03:14:17.101133Z","end":"2026-02-09T03:14:17.769057Z","steps":["trace[1458522166] 'process raft request' (duration: 665.300407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.76909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:17.101123Z","time spent":"667.950422ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:14:17.76923Z","caller":"traceutil/trace.go:171","msg":"trace[1858869790] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"666.100487ms","start":"2026-02-09T03:14:17.103124Z","end":"2026-02-09T03:14:17.769224Z","steps":["trace[1858869790] 'process raft request' (duration: 663.357479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.769259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:17.103117Z","time spent":"666.125087ms","remote":"127.0.0.1:45628","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-09T03:14:17.769642Z","caller":"traceutil/trace.go:171","msg":"trace[1992249837] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"666.467446ms","start":"2026-02-09T03:14:17.103162Z","end":"2026-02-09T03:14:17.769629Z","steps":["trace[1992249837] 'process raft request' (duration: 663.358069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.769683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:17.103158Z","time spent":"666.499396ms","remote":"127.0.0.1:45628","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-09T03:14:17.769869Z","caller":"traceutil/trace.go:171","msg":"trace[686899860] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"665.670926ms","start":"2026-02-09T03:14:17.104187Z","end":"2026-02-09T03:14:17.769858Z","steps":["trace[686899860] 'process raft request' (duration: 662.369585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:17.7699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:17.104181Z","time spent":"665.701037ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:14:18.409393Z","caller":"traceutil/trace.go:171","msg":"trace[1351321345] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"231.399446ms","start":"2026-02-09T03:14:18.177938Z","end":"2026-02-09T03:14:18.409337Z","steps":["trace[1351321345] 'process raft request' (duration: 231.14351ms)"],"step_count":1}