{"level":"warn","ts":"2026-02-05T15:19:25.759752Z","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-05T15:19:25.75985Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.99: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.99:2380","--initial-cluster=instance=https://199.204.45.99:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.99:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.99: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-05T15:19:25.760067Z","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-05T15:19:25.760173Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-02-05T15:19:25.760235Z","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-05T15:19:25.761025Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"]} {"level":"info","ts":"2026-02-05T15:19:25.761207Z","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.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.99: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-05T15:19:25.779484Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"17.778085ms"} {"level":"info","ts":"2026-02-05T15:19:25.816869Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"21032583b6cd9182","cluster-id":"a96cbe7391f279b7"} {"level":"info","ts":"2026-02-05T15:19:25.817083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T15:19:25.817151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 0"} {"level":"info","ts":"2026-02-05T15:19:25.817166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 21032583b6cd9182 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T15:19:25.817223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 1"} {"level":"info","ts":"2026-02-05T15:19:25.822597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"warn","ts":"2026-02-05T15:19:25.835659Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T15:19:25.840383Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T15:19:25.845753Z","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-05T15:19:25.852279Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"21032583b6cd9182","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T15:19:25.852632Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"21032583b6cd9182","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T15:19:25.852964Z","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-05T15:19:25.852996Z","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-05T15:19:25.8531Z","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-05T15:19:25.854462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"info","ts":"2026-02-05T15:19:25.854564Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","added-peer-id":"21032583b6cd9182","added-peer-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-02-05T15:19:25.8729Z","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-05T15:19:25.873272Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"21032583b6cd9182","initial-advertise-peer-urls":["https://199.204.45.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T15:19:25.873307Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T15:19:25.873861Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-02-05T15:19:25.874259Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-02-05T15:19:26.123281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T15:19:26.123465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T15:19:26.123506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgPreVoteResp from 21032583b6cd9182 at term 1"} {"level":"info","ts":"2026-02-05T15:19:26.123558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became candidate at term 2"} {"level":"info","ts":"2026-02-05T15:19:26.123587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgVoteResp from 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-02-05T15:19:26.123634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became leader at term 2"} {"level":"info","ts":"2026-02-05T15:19:26.123664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 21032583b6cd9182 elected leader 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-02-05T15:19:26.127778Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"21032583b6cd9182","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.99:2379]}","request-path":"/0/members/21032583b6cd9182/attributes","cluster-id":"a96cbe7391f279b7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T15:19:26.127824Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T15:19:26.128385Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T15:19:26.128555Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T15:19:26.130093Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T15:19:26.130118Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T15:19:26.134879Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T15:19:26.146308Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.99:2379"} {"level":"info","ts":"2026-02-05T15:19:26.146556Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T15:19:26.146701Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T15:19:26.147209Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T15:19:28.804423Z","caller":"traceutil/trace.go:171","msg":"trace[1720382379] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"110.527735ms","start":"2026-02-05T15:19:28.693869Z","end":"2026-02-05T15:19:28.804397Z","steps":["trace[1720382379] 'process raft request' (duration: 65.946795ms)","trace[1720382379] 'compare' (duration: 44.354355ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:19:29.340808Z","caller":"traceutil/trace.go:171","msg":"trace[1715126330] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"122.529331ms","start":"2026-02-05T15:19:29.218261Z","end":"2026-02-05T15:19:29.34079Z","steps":["trace[1715126330] 'process raft request' (duration: 122.496591ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:29.34086Z","caller":"traceutil/trace.go:171","msg":"trace[854336524] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"124.015185ms","start":"2026-02-05T15:19:29.216797Z","end":"2026-02-05T15:19:29.340813Z","steps":["trace[854336524] 'process raft request' (duration: 70.264651ms)","trace[854336524] 'compare' (duration: 53.58594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:19:29.664807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.592029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-05T15:19:29.665078Z","caller":"traceutil/trace.go:171","msg":"trace[1666609645] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"227.717558ms","start":"2026-02-05T15:19:29.437339Z","end":"2026-02-05T15:19:29.665056Z","steps":["trace[1666609645] 'process raft request' (duration: 46.994204ms)","trace[1666609645] 'compare' (duration: 179.454596ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:19:29.666088Z","caller":"traceutil/trace.go:171","msg":"trace[468829607] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"228.558358ms","start":"2026-02-05T15:19:29.437519Z","end":"2026-02-05T15:19:29.666077Z","steps":["trace[468829607] 'process raft request' (duration: 227.479823ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:29.667423Z","caller":"traceutil/trace.go:171","msg":"trace[889160472] transaction","detail":"{read_only:false; response_revision:101; number_of_response:1; }","duration":"183.756252ms","start":"2026-02-05T15:19:29.483654Z","end":"2026-02-05T15:19:29.667411Z","steps":["trace[889160472] 'process raft request' (duration: 182.092875ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:29.665858Z","caller":"traceutil/trace.go:171","msg":"trace[2000254348] transaction","detail":"{read_only:false; number_of_response:0; response_revision:101; }","duration":"181.875281ms","start":"2026-02-05T15:19:29.483972Z","end":"2026-02-05T15:19:29.665847Z","steps":["trace[2000254348] 'process raft request' (duration: 181.84773ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:30.452085Z","caller":"traceutil/trace.go:171","msg":"trace[776459971] linearizableReadLoop","detail":"{readStateIndex:133; appliedIndex:130; }","duration":"101.331361ms","start":"2026-02-05T15:19:30.350736Z","end":"2026-02-05T15:19:30.452067Z","steps":["trace[776459971] 'read index received' (duration: 58.672984ms)","trace[776459971] 'applied index is now lower than readState.Index' (duration: 42.657657ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:19:30.452244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.472544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T15:19:30.452301Z","caller":"traceutil/trace.go:171","msg":"trace[1280029599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:126; }","duration":"101.586947ms","start":"2026-02-05T15:19:30.350705Z","end":"2026-02-05T15:19:30.452292Z","steps":["trace[1280029599] 'agreement among raft nodes before linearized reading' (duration: 101.430524ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:30.452115Z","caller":"traceutil/trace.go:171","msg":"trace[977564055] transaction","detail":"{read_only:false; response_revision:125; number_of_response:1; }","duration":"162.055389ms","start":"2026-02-05T15:19:30.29004Z","end":"2026-02-05T15:19:30.452095Z","steps":["trace[977564055] 'process raft request' (duration: 161.966507ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:30.452628Z","caller":"traceutil/trace.go:171","msg":"trace[1694231585] transaction","detail":"{read_only:false; response_revision:126; number_of_response:1; }","duration":"160.112757ms","start":"2026-02-05T15:19:30.292508Z","end":"2026-02-05T15:19:30.452621Z","steps":["trace[1694231585] 'process raft request' (duration: 159.528464ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:30.45287Z","caller":"traceutil/trace.go:171","msg":"trace[1918130329] transaction","detail":"{read_only:false; response_revision:124; number_of_response:1; }","duration":"163.103784ms","start":"2026-02-05T15:19:30.289722Z","end":"2026-02-05T15:19:30.452826Z","steps":["trace[1918130329] 'process raft request' (duration: 119.672768ms)","trace[1918130329] 'compare' (duration: 42.504585ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:19:31.400174Z","caller":"traceutil/trace.go:171","msg":"trace[1121374156] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"106.924864ms","start":"2026-02-05T15:19:31.293235Z","end":"2026-02-05T15:19:31.40016Z","steps":["trace[1121374156] 'process raft request' (duration: 95.980061ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:31.608132Z","caller":"traceutil/trace.go:171","msg":"trace[1501420650] transaction","detail":"{read_only:false; response_revision:159; number_of_response:1; }","duration":"140.529661ms","start":"2026-02-05T15:19:31.467458Z","end":"2026-02-05T15:19:31.607987Z","steps":["trace[1501420650] 'process raft request' (duration: 118.986223ms)","trace[1501420650] 'compare' (duration: 20.349392ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:19:32.117837Z","caller":"traceutil/trace.go:171","msg":"trace[699895756] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"102.66668ms","start":"2026-02-05T15:19:32.015145Z","end":"2026-02-05T15:19:32.117812Z","steps":["trace[699895756] 'process raft request' (duration: 30.664431ms)","trace[699895756] 'compare' (duration: 67.890339ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:19:32.529229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.159304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T15:19:32.5297Z","caller":"traceutil/trace.go:171","msg":"trace[968011181] transaction","detail":"{read_only:false; response_revision:182; number_of_response:1; }","duration":"155.507934ms","start":"2026-02-05T15:19:32.374174Z","end":"2026-02-05T15:19:32.529682Z","steps":["trace[968011181] 'process raft request' (duration: 23.839659ms)","trace[968011181] 'compare' (duration: 130.578641ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:19:32.726713Z","caller":"traceutil/trace.go:171","msg":"trace[1236690774] linearizableReadLoop","detail":"{readStateIndex:192; appliedIndex:190; }","duration":"138.764321ms","start":"2026-02-05T15:19:32.58793Z","end":"2026-02-05T15:19:32.726694Z","steps":["trace[1236690774] 'read index received' (duration: 81.358967ms)","trace[1236690774] 'applied index is now lower than readState.Index' (duration: 57.404534ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:19:32.727292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.887233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cluster-admin\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:19:32.728084Z","caller":"traceutil/trace.go:171","msg":"trace[2021635081] transaction","detail":"{read_only:false; response_revision:184; number_of_response:1; }","duration":"190.671944ms","start":"2026-02-05T15:19:32.537368Z","end":"2026-02-05T15:19:32.72804Z","steps":["trace[2021635081] 'process raft request' (duration: 131.978611ms)","trace[2021635081] 'compare' (duration: 56.75165ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:19:32.727882Z","caller":"traceutil/trace.go:171","msg":"trace[1285673994] range","detail":"{range_begin:/registry/clusterrolebindings/cluster-admin; range_end:; response_count:0; response_revision:185; }","duration":"139.944057ms","start":"2026-02-05T15:19:32.587924Z","end":"2026-02-05T15:19:32.727868Z","steps":["trace[1285673994] 'agreement among raft nodes before linearized reading' (duration: 138.849033ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:32.728957Z","caller":"traceutil/trace.go:171","msg":"trace[66801382] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"190.081531ms","start":"2026-02-05T15:19:32.538862Z","end":"2026-02-05T15:19:32.728943Z","steps":["trace[66801382] 'process raft request' (duration: 187.77258ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:33.029524Z","caller":"traceutil/trace.go:171","msg":"trace[839700110] transaction","detail":"{read_only:false; response_revision:190; number_of_response:1; }","duration":"116.989338ms","start":"2026-02-05T15:19:32.912512Z","end":"2026-02-05T15:19:33.029501Z","steps":["trace[839700110] 'process raft request' (duration: 116.820034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:19:33.268429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.639233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T15:19:33.26854Z","caller":"traceutil/trace.go:171","msg":"trace[1742654377] linearizableReadLoop","detail":"{readStateIndex:201; appliedIndex:200; }","duration":"117.783827ms","start":"2026-02-05T15:19:33.150737Z","end":"2026-02-05T15:19:33.268521Z","steps":["trace[1742654377] 'read index received' (duration: 4.874708ms)","trace[1742654377] 'applied index is now lower than readState.Index' (duration: 112.907589ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:19:33.268652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.921439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:19:33.268678Z","caller":"traceutil/trace.go:171","msg":"trace[14456369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:194; }","duration":"118.011202ms","start":"2026-02-05T15:19:33.150657Z","end":"2026-02-05T15:19:33.268668Z","steps":["trace[14456369] 'agreement among raft nodes before linearized reading' (duration: 117.91221ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:33.268665Z","caller":"traceutil/trace.go:171","msg":"trace[1475860966] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"200.145247ms","start":"2026-02-05T15:19:33.068481Z","end":"2026-02-05T15:19:33.268626Z","steps":["trace[1475860966] 'process raft request' (duration: 87.240538ms)","trace[1475860966] 'compare' (duration: 112.48766ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:19:33.486092Z","caller":"traceutil/trace.go:171","msg":"trace[536183715] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"128.239797ms","start":"2026-02-05T15:19:33.357816Z","end":"2026-02-05T15:19:33.486056Z","steps":["trace[536183715] 'process raft request' (duration: 123.940722ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:34.009599Z","caller":"traceutil/trace.go:171","msg":"trace[85193475] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"125.847425ms","start":"2026-02-05T15:19:33.883723Z","end":"2026-02-05T15:19:34.009571Z","steps":["trace[85193475] 'process raft request' (duration: 91.246718ms)","trace[85193475] 'compare' (duration: 34.474895ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:19:34.198668Z","caller":"traceutil/trace.go:171","msg":"trace[982566031] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"103.871567ms","start":"2026-02-05T15:19:34.094767Z","end":"2026-02-05T15:19:34.198639Z","steps":["trace[982566031] 'process raft request' (duration: 51.951903ms)","trace[982566031] 'compare' (duration: 51.563857ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:19:34.426227Z","caller":"traceutil/trace.go:171","msg":"trace[2032176833] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"126.93222ms","start":"2026-02-05T15:19:34.299275Z","end":"2026-02-05T15:19:34.426208Z","steps":["trace[2032176833] 'process raft request' (duration: 28.780979ms)","trace[2032176833] 'compare' (duration: 98.048349ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:19:34.978043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.238883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-05T15:19:34.978342Z","caller":"traceutil/trace.go:171","msg":"trace[471015337] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:233; }","duration":"103.54805ms","start":"2026-02-05T15:19:34.874776Z","end":"2026-02-05T15:19:34.978324Z","steps":["trace[471015337] 'range keys from in-memory index tree' (duration: 103.144231ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:43.15577Z","caller":"traceutil/trace.go:171","msg":"trace[90436611] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"103.801145ms","start":"2026-02-05T15:19:43.05195Z","end":"2026-02-05T15:19:43.155751Z","steps":["trace[90436611] 'process raft request' (duration: 103.643692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:19:44.541383Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515276696,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T15:19:44.752771Z","caller":"traceutil/trace.go:171","msg":"trace[731116722] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"712.413983ms","start":"2026-02-05T15:19:44.040337Z","end":"2026-02-05T15:19:44.752751Z","steps":["trace[731116722] 'read index received' (duration: 712.25086ms)","trace[731116722] 'applied index is now lower than readState.Index' (duration: 162.423µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:19:44.753064Z","caller":"traceutil/trace.go:171","msg":"trace[1614908780] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"761.308129ms","start":"2026-02-05T15:19:43.991744Z","end":"2026-02-05T15:19:44.753053Z","steps":["trace[1614908780] 'process raft request' (duration: 760.89412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:19:44.75319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:19:43.991727Z","time spent":"761.386251ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:19:44.753397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.056187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-02-05T15:19:44.753439Z","caller":"traceutil/trace.go:171","msg":"trace[372770315] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:412; }","duration":"713.097318ms","start":"2026-02-05T15:19:44.040331Z","end":"2026-02-05T15:19:44.753429Z","steps":["trace[372770315] 'agreement among raft nodes before linearized reading' (duration: 713.016196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:19:44.753469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:19:44.040247Z","time spent":"713.213781ms","remote":"127.0.0.1:44378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6917,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-05T15:19:44.753636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.531649ms","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-05T15:19:44.753669Z","caller":"traceutil/trace.go:171","msg":"trace[148724122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:412; }","duration":"383.60231ms","start":"2026-02-05T15:19:44.370059Z","end":"2026-02-05T15:19:44.753661Z","steps":["trace[148724122] 'agreement among raft nodes before linearized reading' (duration: 383.548709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:19:44.753699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:19:44.370038Z","time spent":"383.654292ms","remote":"127.0.0.1:44492","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-05T15:19:44.753901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.929137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:19:44.753936Z","caller":"traceutil/trace.go:171","msg":"trace[417241924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"125.990319ms","start":"2026-02-05T15:19:44.627937Z","end":"2026-02-05T15:19:44.753928Z","steps":["trace[417241924] 'agreement among raft nodes before linearized reading' (duration: 125.941748ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:50.976933Z","caller":"traceutil/trace.go:171","msg":"trace[1671219215] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"192.799112ms","start":"2026-02-05T15:19:50.784113Z","end":"2026-02-05T15:19:50.976913Z","steps":["trace[1671219215] 'process raft request' (duration: 192.68583ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:50.977477Z","caller":"traceutil/trace.go:171","msg":"trace[1122043750] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"193.18925ms","start":"2026-02-05T15:19:50.784276Z","end":"2026-02-05T15:19:50.977466Z","steps":["trace[1122043750] 'process raft request' (duration: 193.068878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:19:50.981694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.049694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-05T15:19:50.98212Z","caller":"traceutil/trace.go:171","msg":"trace[928505166] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:433; }","duration":"196.486854ms","start":"2026-02-05T15:19:50.785619Z","end":"2026-02-05T15:19:50.982105Z","steps":["trace[928505166] 'agreement among raft nodes before linearized reading' (duration: 196.011063ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:50.981572Z","caller":"traceutil/trace.go:171","msg":"trace[1302286978] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"195.801939ms","start":"2026-02-05T15:19:50.785621Z","end":"2026-02-05T15:19:50.981423Z","steps":["trace[1302286978] 'read index received' (duration: 191.34891ms)","trace[1302286978] 'applied index is now lower than readState.Index' (duration: 4.451679ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:19:50.983812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.87346ms","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-05T15:19:50.98461Z","caller":"traceutil/trace.go:171","msg":"trace[1946253446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:433; }","duration":"101.710008ms","start":"2026-02-05T15:19:50.882887Z","end":"2026-02-05T15:19:50.984597Z","steps":["trace[1946253446] 'agreement among raft nodes before linearized reading' (duration: 100.88971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:19:53.701031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.339477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T15:19:53.701263Z","caller":"traceutil/trace.go:171","msg":"trace[715644086] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"676.579458ms","start":"2026-02-05T15:19:53.024673Z","end":"2026-02-05T15:19:53.701253Z","steps":["trace[715644086] 'process raft request' (duration: 42.973334ms)","trace[715644086] 'compare' (duration: 632.895577ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:19:53.701294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:19:53.024655Z","time spent":"676.623998ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:20:05.823286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.296453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:05.82336Z","caller":"traceutil/trace.go:171","msg":"trace[1409517733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"197.454156ms","start":"2026-02-05T15:20:05.625893Z","end":"2026-02-05T15:20:05.823347Z","steps":["trace[1409517733] 'range keys from in-memory index tree' (duration: 197.232011ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:06.208131Z","caller":"traceutil/trace.go:171","msg":"trace[570354234] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"351.2117ms","start":"2026-02-05T15:20:05.856867Z","end":"2026-02-05T15:20:06.208078Z","steps":["trace[570354234] 'process raft request' (duration: 351.093667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:06.208332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:05.856858Z","time spent":"351.378174ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:20:07.466864Z","caller":"traceutil/trace.go:171","msg":"trace[26734828] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"224.745631ms","start":"2026-02-05T15:20:07.242103Z","end":"2026-02-05T15:20:07.466849Z","steps":["trace[26734828] 'read index received' (duration: 224.629388ms)","trace[26734828] 'applied index is now lower than readState.Index' (duration: 115.723µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:20:07.467154Z","caller":"traceutil/trace.go:171","msg":"trace[1909961705] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"372.946644ms","start":"2026-02-05T15:20:07.094197Z","end":"2026-02-05T15:20:07.467144Z","steps":["trace[1909961705] 'process raft request' (duration: 372.576305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:07.467256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:07.094169Z","time spent":"373.010555ms","remote":"127.0.0.1:44492","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-05T15:20:07.467465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.367456ms","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-05T15:20:07.467504Z","caller":"traceutil/trace.go:171","msg":"trace[1367537376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"225.440827ms","start":"2026-02-05T15:20:07.242052Z","end":"2026-02-05T15:20:07.467493Z","steps":["trace[1367537376] 'agreement among raft nodes before linearized reading' (duration: 225.381916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:07.46766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.747293ms","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-05T15:20:07.467695Z","caller":"traceutil/trace.go:171","msg":"trace[773411075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"143.782473ms","start":"2026-02-05T15:20:07.323905Z","end":"2026-02-05T15:20:07.467687Z","steps":["trace[773411075] 'agreement among raft nodes before linearized reading' (duration: 143.726582ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:08.450371Z","caller":"traceutil/trace.go:171","msg":"trace[624102881] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"236.609485ms","start":"2026-02-05T15:20:08.213746Z","end":"2026-02-05T15:20:08.450355Z","steps":["trace[624102881] 'process raft request' (duration: 236.517472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:09.637303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011833803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:09.637367Z","caller":"traceutil/trace.go:171","msg":"trace[1442252463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"1.011926815s","start":"2026-02-05T15:20:08.625424Z","end":"2026-02-05T15:20:09.637351Z","steps":["trace[1442252463] 'range keys from in-memory index tree' (duration: 1.011623779s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:09.6374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:08.625412Z","time spent":"1.011981676s","remote":"127.0.0.1:44218","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-05T15:20:09.800959Z","caller":"traceutil/trace.go:171","msg":"trace[1028550085] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:558; }","duration":"271.725076ms","start":"2026-02-05T15:20:09.529219Z","end":"2026-02-05T15:20:09.800944Z","steps":["trace[1028550085] 'read index received' (duration: 271.660184ms)","trace[1028550085] 'applied index is now lower than readState.Index' (duration: 64.242µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:20:09.801056Z","caller":"traceutil/trace.go:171","msg":"trace[872316211] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"693.670297ms","start":"2026-02-05T15:20:09.10738Z","end":"2026-02-05T15:20:09.80105Z","steps":["trace[872316211] 'process raft request' (duration: 693.470192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:09.801126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:09.10736Z","time spent":"693.712948ms","remote":"127.0.0.1:44492","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-05T15:20:09.80122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.003921ms","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-05T15:20:09.801237Z","caller":"traceutil/trace.go:171","msg":"trace[1355891262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"272.046162ms","start":"2026-02-05T15:20:09.529184Z","end":"2026-02-05T15:20:09.80123Z","steps":["trace[1355891262] 'agreement among raft nodes before linearized reading' (duration: 272.012541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:09.801293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.372857ms","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-05T15:20:09.801304Z","caller":"traceutil/trace.go:171","msg":"trace[1392070800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"248.383947ms","start":"2026-02-05T15:20:09.552917Z","end":"2026-02-05T15:20:09.801301Z","steps":["trace[1392070800] 'agreement among raft nodes before linearized reading' (duration: 248.363057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:09.801427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.803754ms","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-05T15:20:09.801442Z","caller":"traceutil/trace.go:171","msg":"trace[65789939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"247.825694ms","start":"2026-02-05T15:20:09.553611Z","end":"2026-02-05T15:20:09.801436Z","steps":["trace[65789939] 'agreement among raft nodes before linearized reading' (duration: 247.799764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:09.801433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.452339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:09.80157Z","caller":"traceutil/trace.go:171","msg":"trace[109106509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"162.627822ms","start":"2026-02-05T15:20:09.638924Z","end":"2026-02-05T15:20:09.801552Z","steps":["trace[109106509] 'agreement among raft nodes before linearized reading' (duration: 162.458768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:10.312954Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515276957,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T15:20:10.813672Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515276957,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T15:20:11.192927Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.124762113s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T15:20:11.24595Z","caller":"traceutil/trace.go:171","msg":"trace[1666739140] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.439637294s","start":"2026-02-05T15:20:09.806295Z","end":"2026-02-05T15:20:11.245932Z","steps":["trace[1666739140] 'process raft request' (duration: 1.386787101s)","trace[1666739140] 'compare' (duration: 51.667827ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:11.246058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:09.806279Z","time spent":"1.439721336s","remote":"127.0.0.1:44492","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-05T15:20:11.246921Z","caller":"traceutil/trace.go:171","msg":"trace[830133188] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.440536155s","start":"2026-02-05T15:20:09.806375Z","end":"2026-02-05T15:20:11.246911Z","steps":["trace[830133188] 'process raft request' (duration: 1.43851313s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:11.24698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:09.806371Z","time spent":"1.440583616s","remote":"127.0.0.1:44492","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-05T15:20:11.247111Z","caller":"traceutil/trace.go:171","msg":"trace[1938918759] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.440697379s","start":"2026-02-05T15:20:09.806405Z","end":"2026-02-05T15:20:11.247103Z","steps":["trace[1938918759] 'process raft request' (duration: 1.43850958s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:11.24715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:09.806402Z","time spent":"1.440730019s","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:20:11.269436Z","caller":"traceutil/trace.go:171","msg":"trace[1014911080] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:559; }","duration":"1.457241946s","start":"2026-02-05T15:20:09.812167Z","end":"2026-02-05T15:20:11.269409Z","steps":["trace[1014911080] 'read index received' (duration: 1.380996413s)","trace[1014911080] 'applied index is now lower than readState.Index' (duration: 76.242733ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:11.269616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.45742089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:11.269647Z","caller":"traceutil/trace.go:171","msg":"trace[750900389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"1.457491902s","start":"2026-02-05T15:20:09.812147Z","end":"2026-02-05T15:20:11.269639Z","steps":["trace[750900389] 'agreement among raft nodes before linearized reading' (duration: 1.45738654s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:11.269673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:09.81214Z","time spent":"1.457524442s","remote":"127.0.0.1:44218","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-05T15:20:11.269673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.635833ms","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-05T15:20:11.269715Z","caller":"traceutil/trace.go:171","msg":"trace[1508682820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:540; }","duration":"814.698104ms","start":"2026-02-05T15:20:10.455007Z","end":"2026-02-05T15:20:11.269705Z","steps":["trace[1508682820] 'agreement among raft nodes before linearized reading' (duration: 814.618743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:11.26974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:10.454995Z","time spent":"814.739376ms","remote":"127.0.0.1:44492","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-05T15:20:14.635613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121712053s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T15:20:14.635785Z","caller":"traceutil/trace.go:171","msg":"trace[760388812] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:565; }","duration":"1.334330017s","start":"2026-02-05T15:20:13.30143Z","end":"2026-02-05T15:20:14.63576Z","steps":["trace[760388812] 'read index received' (duration: 48.700623ms)","trace[760388812] 'applied index is now lower than readState.Index' (duration: 1.285628464s)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:20:14.635896Z","caller":"traceutil/trace.go:171","msg":"trace[382909026] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.378955797s","start":"2026-02-05T15:20:13.256928Z","end":"2026-02-05T15:20:14.635884Z","steps":["trace[382909026] 'process raft request' (duration: 256.902576ms)","trace[382909026] 'compare' (duration: 1.121545589s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:14.63596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:13.256915Z","time spent":"1.379013348s","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:20:14.636118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.334682164s","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-05T15:20:14.6362Z","caller":"traceutil/trace.go:171","msg":"trace[424513232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:544; }","duration":"1.334791486s","start":"2026-02-05T15:20:13.301397Z","end":"2026-02-05T15:20:14.636188Z","steps":["trace[424513232] 'agreement among raft nodes before linearized reading' (duration: 1.334663103s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:14.636278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:13.30138Z","time spent":"1.334887829s","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-05T15:20:14.636394Z","caller":"traceutil/trace.go:171","msg":"trace[1106346971] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.376307658s","start":"2026-02-05T15:20:13.260076Z","end":"2026-02-05T15:20:14.636384Z","steps":["trace[1106346971] 'process raft request' (duration: 1.375615522s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:14.63647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:13.26006Z","time spent":"1.37638084s","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:20:14.636146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007820644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:14.636582Z","caller":"traceutil/trace.go:171","msg":"trace[1869201623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"1.008285893s","start":"2026-02-05T15:20:13.628288Z","end":"2026-02-05T15:20:14.636573Z","steps":["trace[1869201623] 'agreement among raft nodes before linearized reading' (duration: 1.007830913s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:14.636624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:13.628274Z","time spent":"1.008327815s","remote":"127.0.0.1:44218","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-05T15:20:15.150766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.948067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T15:20:15.150842Z","caller":"traceutil/trace.go:171","msg":"trace[2064955426] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"502.183084ms","start":"2026-02-05T15:20:14.648648Z","end":"2026-02-05T15:20:15.150831Z","steps":["trace[2064955426] 'read index received' (duration: 335.075303ms)","trace[2064955426] 'applied index is now lower than readState.Index' (duration: 167.106881ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:15.150894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.251745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:15.15091Z","caller":"traceutil/trace.go:171","msg":"trace[10687690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"502.288905ms","start":"2026-02-05T15:20:14.648615Z","end":"2026-02-05T15:20:15.150904Z","steps":["trace[10687690] 'agreement among raft nodes before linearized reading' (duration: 502.244874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:15.150929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:14.648605Z","time spent":"502.320237ms","remote":"127.0.0.1:44218","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-05T15:20:15.151115Z","caller":"traceutil/trace.go:171","msg":"trace[144679101] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"505.989608ms","start":"2026-02-05T15:20:14.645117Z","end":"2026-02-05T15:20:15.151106Z","steps":["trace[144679101] 'process raft request' (duration: 338.650052ms)","trace[144679101] 'compare' (duration: 166.828925ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:15.151155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:14.645097Z","time spent":"506.034648ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:20:15.500842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.367292ms","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-05T15:20:15.500904Z","caller":"traceutil/trace.go:171","msg":"trace[1636548004] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"147.463314ms","start":"2026-02-05T15:20:15.353426Z","end":"2026-02-05T15:20:15.500889Z","steps":["trace[1636548004] 'range keys from in-memory index tree' (duration: 147.236679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:15.50106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.826886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.99\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-05T15:20:15.50108Z","caller":"traceutil/trace.go:171","msg":"trace[1557606940] range","detail":"{range_begin:/registry/masterleases/199.204.45.99; range_end:; response_count:1; response_revision:545; }","duration":"144.867936ms","start":"2026-02-05T15:20:15.356205Z","end":"2026-02-05T15:20:15.501073Z","steps":["trace[1557606940] 'range keys from in-memory index tree' (duration: 144.778965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:16.398565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:15.502627Z","time spent":"895.935289ms","remote":"127.0.0.1:44256","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-05T15:20:16.796838Z","caller":"traceutil/trace.go:171","msg":"trace[189308181] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:568; }","duration":"673.443947ms","start":"2026-02-05T15:20:16.123371Z","end":"2026-02-05T15:20:16.796815Z","steps":["trace[189308181] 'read index received' (duration: 275.050819ms)","trace[189308181] 'applied index is now lower than readState.Index' (duration: 398.391998ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:20:16.797074Z","caller":"traceutil/trace.go:171","msg":"trace[1062009280] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.29351548s","start":"2026-02-05T15:20:15.503543Z","end":"2026-02-05T15:20:16.797059Z","steps":["trace[1062009280] 'process raft request' (duration: 1.212547221s)","trace[1062009280] 'compare' (duration: 80.326905ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:16.797311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.921348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:16.797352Z","caller":"traceutil/trace.go:171","msg":"trace[1964785699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"674.00145ms","start":"2026-02-05T15:20:16.12334Z","end":"2026-02-05T15:20:16.797341Z","steps":["trace[1964785699] 'agreement among raft nodes before linearized reading' (duration: 673.919998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:16.797386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:16.123327Z","time spent":"674.050821ms","remote":"127.0.0.1:44218","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-05T15:20:16.797805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:15.503535Z","time spent":"1.294212186s","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:20:16.799976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.09704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:16.800057Z","caller":"traceutil/trace.go:171","msg":"trace[1055656329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"171.200442ms","start":"2026-02-05T15:20:16.628826Z","end":"2026-02-05T15:20:16.800027Z","steps":["trace[1055656329] 'agreement among raft nodes before linearized reading' (duration: 171.07468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:16.800248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.593148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T15:20:16.800292Z","caller":"traceutil/trace.go:171","msg":"trace[1005503197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:547; }","duration":"156.66054ms","start":"2026-02-05T15:20:16.64362Z","end":"2026-02-05T15:20:16.80028Z","steps":["trace[1005503197] 'agreement among raft nodes before linearized reading' (duration: 156.546957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:16.800397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.878606ms","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-05T15:20:16.800433Z","caller":"traceutil/trace.go:171","msg":"trace[1302680215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"157.914277ms","start":"2026-02-05T15:20:16.642508Z","end":"2026-02-05T15:20:16.800422Z","steps":["trace[1302680215] 'agreement among raft nodes before linearized reading' (duration: 157.846836ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:16.800466Z","caller":"traceutil/trace.go:171","msg":"trace[395075301] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"400.855203ms","start":"2026-02-05T15:20:16.3996Z","end":"2026-02-05T15:20:16.800455Z","steps":["trace[395075301] 'process raft request' (duration: 400.166618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:16.800532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:16.399585Z","time spent":"400.906665ms","remote":"127.0.0.1:44256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:20:20.625457Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515277012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T15:20:21.127664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515277012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T15:20:21.628561Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515277012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T15:20:22.129246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515277012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T15:20:22.561267Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.691237574s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T15:20:22.561688Z","caller":"traceutil/trace.go:171","msg":"trace[364367664] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"2.691740835s","start":"2026-02-05T15:20:19.869926Z","end":"2026-02-05T15:20:22.561667Z","steps":["trace[364367664] 'process raft request' (duration: 2.691592042s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:22.561796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:19.869904Z","time spent":"2.691823347s","remote":"127.0.0.1:44492","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-05T15:20:22.629959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000722896s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-05T15:20:22.630102Z","caller":"traceutil/trace.go:171","msg":"trace[1281302743] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000923211s","start":"2026-02-05T15:20:20.62916Z","end":"2026-02-05T15:20:22.630083Z","steps":["trace[1281302743] 'agreement among raft nodes before linearized reading' (duration: 2.000747527s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:22.630154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:20.629143Z","time spent":"2.001000182s","remote":"127.0.0.1:44218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T15:20:22.681619Z","caller":"traceutil/trace.go:171","msg":"trace[2046753583] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"2.556725497s","start":"2026-02-05T15:20:20.124879Z","end":"2026-02-05T15:20:22.681604Z","steps":["trace[2046753583] 'read index received' (duration: 2.437350575s)","trace[2046753583] 'applied index is now lower than readState.Index' (duration: 119.374242ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:22.681783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.556907861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p6hnd\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-05T15:20:22.681805Z","caller":"traceutil/trace.go:171","msg":"trace[450573370] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p6hnd; range_end:; response_count:1; response_revision:559; }","duration":"2.556974932s","start":"2026-02-05T15:20:20.124825Z","end":"2026-02-05T15:20:22.6818Z","steps":["trace[450573370] 'agreement among raft nodes before linearized reading' (duration: 2.55686292s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:22.681823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:20.124805Z","time spent":"2.557014063s","remote":"127.0.0.1:44378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-p6hnd\" "} {"level":"warn","ts":"2026-02-05T15:20:22.681988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.873039991s","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-05T15:20:22.682007Z","caller":"traceutil/trace.go:171","msg":"trace[1709033849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"1.873058892s","start":"2026-02-05T15:20:20.808943Z","end":"2026-02-05T15:20:22.682002Z","steps":["trace[1709033849] 'agreement among raft nodes before linearized reading' (duration: 1.873014021s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:22.682019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:20.808889Z","time spent":"1.873127034s","remote":"127.0.0.1:44492","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-05T15:20:22.682189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.503607916s","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-05T15:20:22.682208Z","caller":"traceutil/trace.go:171","msg":"trace[1780721214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"1.503666347s","start":"2026-02-05T15:20:21.178537Z","end":"2026-02-05T15:20:22.682203Z","steps":["trace[1780721214] 'agreement among raft nodes before linearized reading' (duration: 1.503623456s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:22.682222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:21.178516Z","time spent":"1.503702718s","remote":"127.0.0.1:44492","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-05T15:20:22.682295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.85229901s","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-05T15:20:22.682311Z","caller":"traceutil/trace.go:171","msg":"trace[638845165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"1.852335591s","start":"2026-02-05T15:20:20.829972Z","end":"2026-02-05T15:20:22.682308Z","steps":["trace[638845165] 'agreement among raft nodes before linearized reading' (duration: 1.852298911s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:22.682334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:20.829962Z","time spent":"1.852368992s","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T15:20:22.682628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.855193205s","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-05T15:20:22.682649Z","caller":"traceutil/trace.go:171","msg":"trace[21680791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:559; }","duration":"1.855232396s","start":"2026-02-05T15:20:20.827412Z","end":"2026-02-05T15:20:22.682644Z","steps":["trace[21680791] 'agreement among raft nodes before linearized reading' (duration: 1.855184545s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:22.682662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:20.827404Z","time spent":"1.855254746s","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-05T15:20:27.219087Z","caller":"traceutil/trace.go:171","msg":"trace[515569945] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"399.872831ms","start":"2026-02-05T15:20:26.819202Z","end":"2026-02-05T15:20:27.219075Z","steps":["trace[515569945] 'process raft request' (duration: 399.762999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:27.21917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:26.819186Z","time spent":"399.945682ms","remote":"127.0.0.1:44284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T15:20:27.233235Z","caller":"traceutil/trace.go:171","msg":"trace[211135891] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"187.414942ms","start":"2026-02-05T15:20:27.045805Z","end":"2026-02-05T15:20:27.23322Z","steps":["trace[211135891] 'read index received' (duration: 173.620236ms)","trace[211135891] 'applied index is now lower than readState.Index' (duration: 13.794156ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:20:27.233403Z","caller":"traceutil/trace.go:171","msg":"trace[1250738426] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"213.285427ms","start":"2026-02-05T15:20:27.020112Z","end":"2026-02-05T15:20:27.233397Z","steps":["trace[1250738426] 'process raft request' (duration: 213.001341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:27.233623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.807392ms","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-05T15:20:27.23364Z","caller":"traceutil/trace.go:171","msg":"trace[571040686] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:625; }","duration":"187.874774ms","start":"2026-02-05T15:20:27.04576Z","end":"2026-02-05T15:20:27.233635Z","steps":["trace[571040686] 'agreement among raft nodes before linearized reading' (duration: 187.781472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.117544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515277124,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T15:20:28.46008Z","caller":"traceutil/trace.go:171","msg":"trace[457632297] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"931.910447ms","start":"2026-02-05T15:20:27.528155Z","end":"2026-02-05T15:20:28.460065Z","steps":["trace[457632297] 'process raft request' (duration: 931.774544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.460175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:27.528136Z","time spent":"931.996659ms","remote":"127.0.0.1:44284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T15:20:28.955736Z","caller":"traceutil/trace.go:171","msg":"trace[24815959] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"1.338937958s","start":"2026-02-05T15:20:27.616782Z","end":"2026-02-05T15:20:28.95572Z","steps":["trace[24815959] 'read index received' (duration: 843.815322ms)","trace[24815959] 'applied index is now lower than readState.Index' (duration: 495.121956ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:20:28.955884Z","caller":"traceutil/trace.go:171","msg":"trace[1353240219] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.02441008s","start":"2026-02-05T15:20:27.931466Z","end":"2026-02-05T15:20:28.955876Z","steps":["trace[1353240219] 'process raft request' (duration: 1.021487366s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.955974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:27.93145Z","time spent":"1.024452041s","remote":"127.0.0.1:44492","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-05T15:20:28.956204Z","caller":"traceutil/trace.go:171","msg":"trace[1379739156] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"512.134213ms","start":"2026-02-05T15:20:28.444063Z","end":"2026-02-05T15:20:28.956197Z","steps":["trace[1379739156] 'process raft request' (duration: 511.51541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.956241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:28.444045Z","time spent":"512.176635ms","remote":"127.0.0.1:44284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T15:20:28.956391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339606184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-05T15:20:28.956417Z","caller":"traceutil/trace.go:171","msg":"trace[373381805] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:636; }","duration":"1.339653725s","start":"2026-02-05T15:20:27.616755Z","end":"2026-02-05T15:20:28.956409Z","steps":["trace[373381805] 'agreement among raft nodes before linearized reading' (duration: 1.339565812s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.956436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:27.616743Z","time spent":"1.339688676s","remote":"127.0.0.1:44284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-05T15:20:28.96374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.338207072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:28.9638Z","caller":"traceutil/trace.go:171","msg":"trace[1103539198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"1.338291824s","start":"2026-02-05T15:20:27.625495Z","end":"2026-02-05T15:20:28.963786Z","steps":["trace[1103539198] 'agreement among raft nodes before linearized reading' (duration: 1.338192262s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.964526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.226364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-05T15:20:28.964606Z","caller":"traceutil/trace.go:171","msg":"trace[936286567] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:637; }","duration":"348.345696ms","start":"2026-02-05T15:20:28.616251Z","end":"2026-02-05T15:20:28.964597Z","steps":["trace[936286567] 'agreement among raft nodes before linearized reading' (duration: 348.196893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.964629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:28.616232Z","time spent":"348.389568ms","remote":"127.0.0.1:44284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-05T15:20:28.964624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:27.625487Z","time spent":"1.338337105s","remote":"127.0.0.1:44218","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-05T15:20:28.964811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.93375ms","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-05T15:20:28.964828Z","caller":"traceutil/trace.go:171","msg":"trace[591778563] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:637; }","duration":"565.989701ms","start":"2026-02-05T15:20:28.398833Z","end":"2026-02-05T15:20:28.964822Z","steps":["trace[591778563] 'agreement among raft nodes before linearized reading' (duration: 565.92831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.964842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:28.398819Z","time spent":"566.019632ms","remote":"127.0.0.1:44734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":78,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-05T15:20:28.965018Z","caller":"traceutil/trace.go:171","msg":"trace[1885594859] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"411.195133ms","start":"2026-02-05T15:20:28.553816Z","end":"2026-02-05T15:20:28.965011Z","steps":["trace[1885594859] 'process raft request' (duration: 409.582127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.965061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:28.5538Z","time spent":"411.233724ms","remote":"127.0.0.1:44284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:20:28.965149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.172564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-05T15:20:28.965165Z","caller":"traceutil/trace.go:171","msg":"trace[1784481968] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:637; }","duration":"149.206204ms","start":"2026-02-05T15:20:28.815953Z","end":"2026-02-05T15:20:28.96516Z","steps":["trace[1784481968] 'agreement among raft nodes before linearized reading' (duration: 149.159953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.965243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.967123ms","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-05T15:20:28.965255Z","caller":"traceutil/trace.go:171","msg":"trace[1620321563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"237.003404ms","start":"2026-02-05T15:20:28.728248Z","end":"2026-02-05T15:20:28.965251Z","steps":["trace[1620321563] 'agreement among raft nodes before linearized reading' (duration: 236.962633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.965331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.425468ms","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-05T15:20:28.965343Z","caller":"traceutil/trace.go:171","msg":"trace[537251193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"248.450428ms","start":"2026-02-05T15:20:28.716889Z","end":"2026-02-05T15:20:28.965339Z","steps":["trace[537251193] 'agreement among raft nodes before linearized reading' (duration: 248.416127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.96543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.381378ms","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-05T15:20:28.965442Z","caller":"traceutil/trace.go:171","msg":"trace[433024199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"253.412928ms","start":"2026-02-05T15:20:28.712025Z","end":"2026-02-05T15:20:28.965438Z","steps":["trace[433024199] 'agreement among raft nodes before linearized reading' (duration: 253.376248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.965512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.137081ms","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-05T15:20:28.965522Z","caller":"traceutil/trace.go:171","msg":"trace[1521793758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:637; }","duration":"257.185312ms","start":"2026-02-05T15:20:28.708334Z","end":"2026-02-05T15:20:28.965519Z","steps":["trace[1521793758] 'agreement among raft nodes before linearized reading' (duration: 257.153191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.965741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.771765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-05T15:20:28.965756Z","caller":"traceutil/trace.go:171","msg":"trace[237582158] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:637; }","duration":"949.812885ms","start":"2026-02-05T15:20:28.015938Z","end":"2026-02-05T15:20:28.965751Z","steps":["trace[237582158] 'agreement among raft nodes before linearized reading' (duration: 949.771234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.965801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:28.015926Z","time spent":"949.871267ms","remote":"127.0.0.1:44284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-05T15:20:28.965869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.007398ms","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-05T15:20:28.965882Z","caller":"traceutil/trace.go:171","msg":"trace[380065631] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:637; }","duration":"727.045848ms","start":"2026-02-05T15:20:28.238832Z","end":"2026-02-05T15:20:28.965878Z","steps":["trace[380065631] 'agreement among raft nodes before linearized reading' (duration: 727.018917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.965895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:28.238825Z","time spent":"727.067009ms","remote":"127.0.0.1:44370","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":"warn","ts":"2026-02-05T15:20:28.965972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.412287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-05T15:20:28.965983Z","caller":"traceutil/trace.go:171","msg":"trace[364671928] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:637; }","duration":"750.423537ms","start":"2026-02-05T15:20:28.215556Z","end":"2026-02-05T15:20:28.96598Z","steps":["trace[364671928] 'agreement among raft nodes before linearized reading' (duration: 750.387736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.965994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:28.215548Z","time spent":"750.444047ms","remote":"127.0.0.1:44284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-05T15:20:28.967134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.975224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:28.96716Z","caller":"traceutil/trace.go:171","msg":"trace[904655582] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:637; }","duration":"975.027356ms","start":"2026-02-05T15:20:27.992126Z","end":"2026-02-05T15:20:28.967154Z","steps":["trace[904655582] 'agreement among raft nodes before linearized reading' (duration: 974.979505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.967177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:27.992113Z","time spent":"975.059826ms","remote":"127.0.0.1:44510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-05T15:20:28.967356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151624908s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-05T15:20:28.967378Z","caller":"traceutil/trace.go:171","msg":"trace[1768181181] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:637; }","duration":"1.151646169s","start":"2026-02-05T15:20:27.815725Z","end":"2026-02-05T15:20:28.967371Z","steps":["trace[1768181181] 'agreement among raft nodes before linearized reading' (duration: 1.151550976s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.967391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:27.815682Z","time spent":"1.15170506s","remote":"127.0.0.1:44284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-05T15:20:28.967954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.146645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p6hnd\" ","response":"range_response_count:1 size:17914"} {"level":"info","ts":"2026-02-05T15:20:28.96802Z","caller":"traceutil/trace.go:171","msg":"trace[24631235] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p6hnd; range_end:; response_count:1; response_revision:637; }","duration":"770.247598ms","start":"2026-02-05T15:20:28.197766Z","end":"2026-02-05T15:20:28.968014Z","steps":["trace[24631235] 'agreement among raft nodes before linearized reading' (duration: 770.143935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:28.969105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:28.197751Z","time spent":"771.345491ms","remote":"127.0.0.1:44378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17937,"request content":"key:\"/registry/pods/kube-system/cilium-p6hnd\" "} {"level":"info","ts":"2026-02-05T15:20:38.377747Z","caller":"traceutil/trace.go:171","msg":"trace[1437772955] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"193.484747ms","start":"2026-02-05T15:20:38.184238Z","end":"2026-02-05T15:20:38.377723Z","steps":["trace[1437772955] 'process raft request' (duration: 193.350634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:38.965045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.356778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:38.965103Z","caller":"traceutil/trace.go:171","msg":"trace[1337561445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"335.425769ms","start":"2026-02-05T15:20:38.629664Z","end":"2026-02-05T15:20:38.96509Z","steps":["trace[1337561445] 'range keys from in-memory index tree' (duration: 335.284356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:38.965132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:38.629615Z","time spent":"335.509471ms","remote":"127.0.0.1:44218","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-05T15:20:43.61007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515277386,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T15:20:43.973109Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.149304787s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T15:20:43.97366Z","caller":"traceutil/trace.go:171","msg":"trace[2143139908] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"863.832356ms","start":"2026-02-05T15:20:43.109776Z","end":"2026-02-05T15:20:43.973608Z","steps":["trace[2143139908] 'read index received' (duration: 863.549999ms)","trace[2143139908] 'applied index is now lower than readState.Index' (duration: 281.437µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:43.974346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.513229ms","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-05T15:20:43.974469Z","caller":"traceutil/trace.go:171","msg":"trace[64048362] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:748; }","duration":"854.687343ms","start":"2026-02-05T15:20:43.119759Z","end":"2026-02-05T15:20:43.974446Z","steps":["trace[64048362] 'agreement among raft nodes before linearized reading' (duration: 854.334735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:43.974564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:43.119752Z","time spent":"854.792116ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-05T15:20:43.975015Z","caller":"traceutil/trace.go:171","msg":"trace[1812993083] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"1.151221809s","start":"2026-02-05T15:20:42.823745Z","end":"2026-02-05T15:20:43.974967Z","steps":["trace[1812993083] 'process raft request' (duration: 1.149662884s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:43.975156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:42.823728Z","time spent":"1.151322642s","remote":"127.0.0.1:44492","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-05T15:20:43.975499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.716969ms","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-05T15:20:43.975589Z","caller":"traceutil/trace.go:171","msg":"trace[1567459266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:748; }","duration":"865.80482ms","start":"2026-02-05T15:20:43.10977Z","end":"2026-02-05T15:20:43.975575Z","steps":["trace[1567459266] 'agreement among raft nodes before linearized reading' (duration: 865.551665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:43.975665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:43.109726Z","time spent":"865.927253ms","remote":"127.0.0.1:44492","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-05T15:20:43.97585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.173945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:43.975891Z","caller":"traceutil/trace.go:171","msg":"trace[226682185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"349.233907ms","start":"2026-02-05T15:20:43.626644Z","end":"2026-02-05T15:20:43.975878Z","steps":["trace[226682185] 'agreement among raft nodes before linearized reading' (duration: 349.142685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:43.975975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:43.626634Z","time spent":"349.330939ms","remote":"127.0.0.1:44218","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-05T15:20:43.976515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.877096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T15:20:43.976541Z","caller":"traceutil/trace.go:171","msg":"trace[1616417800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"808.957067ms","start":"2026-02-05T15:20:43.167577Z","end":"2026-02-05T15:20:43.976534Z","steps":["trace[1616417800] 'agreement among raft nodes before linearized reading' (duration: 808.240852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:43.976578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:43.167569Z","time spent":"809.002698ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T15:20:43.976534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.07133ms","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-05T15:20:43.976619Z","caller":"traceutil/trace.go:171","msg":"trace[110187533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:748; }","duration":"809.212853ms","start":"2026-02-05T15:20:43.167393Z","end":"2026-02-05T15:20:43.976606Z","steps":["trace[110187533] 'agreement among raft nodes before linearized reading' (duration: 809.005798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:43.976686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.39574ms","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-05T15:20:43.976701Z","caller":"traceutil/trace.go:171","msg":"trace[1647333764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:748; }","duration":"448.43672ms","start":"2026-02-05T15:20:43.528259Z","end":"2026-02-05T15:20:43.976696Z","steps":["trace[1647333764] 'agreement among raft nodes before linearized reading' (duration: 448.392709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:43.977264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:43.528244Z","time spent":"449.012232ms","remote":"127.0.0.1:44492","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-05T15:20:43.976693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:43.167374Z","time spent":"809.306556ms","remote":"127.0.0.1:44492","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-05T15:20:44.372417Z","caller":"traceutil/trace.go:171","msg":"trace[1380946453] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"385.750837ms","start":"2026-02-05T15:20:43.986649Z","end":"2026-02-05T15:20:44.372399Z","steps":["trace[1380946453] 'process raft request' (duration: 385.723617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:44.372526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:43.98664Z","time spent":"385.83196ms","remote":"127.0.0.1:44492","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-05T15:20:44.372807Z","caller":"traceutil/trace.go:171","msg":"trace[1387135368] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"386.345411ms","start":"2026-02-05T15:20:43.986455Z","end":"2026-02-05T15:20:44.3728Z","steps":["trace[1387135368] 'process raft request' (duration: 385.743037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:44.372845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:43.986445Z","time spent":"386.379221ms","remote":"127.0.0.1:44492","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-05T15:20:44.372931Z","caller":"traceutil/trace.go:171","msg":"trace[445556862] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"386.416603ms","start":"2026-02-05T15:20:43.986507Z","end":"2026-02-05T15:20:44.372924Z","steps":["trace[445556862] 'process raft request' (duration: 385.81908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:44.372963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:43.986501Z","time spent":"386.443963ms","remote":"127.0.0.1:44492","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-05T15:20:44.373043Z","caller":"traceutil/trace.go:171","msg":"trace[1437095372] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"386.441723ms","start":"2026-02-05T15:20:43.986594Z","end":"2026-02-05T15:20:44.373035Z","steps":["trace[1437095372] 'process raft request' (duration: 385.757178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:44.373074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:43.986587Z","time spent":"386.469703ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:20:45.590222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.229558ms","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-05T15:20:45.590571Z","caller":"traceutil/trace.go:171","msg":"trace[273629992] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:753; }","duration":"359.611107ms","start":"2026-02-05T15:20:45.230936Z","end":"2026-02-05T15:20:45.590547Z","steps":["trace[273629992] 'count revisions from in-memory index tree' (duration: 359.179177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:45.59082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:45.230922Z","time spent":"359.874342ms","remote":"127.0.0.1:44290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":98,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-05T15:20:45.590277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.2214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.99\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-05T15:20:45.591142Z","caller":"traceutil/trace.go:171","msg":"trace[944827804] range","detail":"{range_begin:/registry/masterleases/199.204.45.99; range_end:; response_count:1; response_revision:753; }","duration":"234.109681ms","start":"2026-02-05T15:20:45.357015Z","end":"2026-02-05T15:20:45.591125Z","steps":["trace[944827804] 'range keys from in-memory index tree' (duration: 233.102117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:45.590335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.587129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T15:20:45.591371Z","caller":"traceutil/trace.go:171","msg":"trace[716435145] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:753; }","duration":"221.653773ms","start":"2026-02-05T15:20:45.369707Z","end":"2026-02-05T15:20:45.591361Z","steps":["trace[716435145] 'count revisions from in-memory index tree' (duration: 220.552308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:46.13071Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515277399,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T15:20:46.631796Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515277399,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T15:20:46.787929Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.029012896s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-05T15:20:46.788161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:45.593238Z","time spent":"1.19491883s","remote":"127.0.0.1:44256","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-05T15:20:47.168571Z","caller":"traceutil/trace.go:171","msg":"trace[590032095] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"379.51804ms","start":"2026-02-05T15:20:46.789036Z","end":"2026-02-05T15:20:47.168554Z","steps":["trace[590032095] 'process raft request' (duration: 379.458969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:47.168667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:46.789021Z","time spent":"379.596022ms","remote":"127.0.0.1:44256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:20:47.16881Z","caller":"traceutil/trace.go:171","msg":"trace[1456066362] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"1.324540429s","start":"2026-02-05T15:20:45.844259Z","end":"2026-02-05T15:20:47.1688Z","steps":["trace[1456066362] 'process raft request' (duration: 1.248619692s)","trace[1456066362] 'compare' (duration: 75.391545ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:47.168842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:45.844244Z","time spent":"1.32458398s","remote":"127.0.0.1:44290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T15:20:47.168935Z","caller":"traceutil/trace.go:171","msg":"trace[759350738] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"1.539063804s","start":"2026-02-05T15:20:45.629862Z","end":"2026-02-05T15:20:47.168926Z","steps":["trace[759350738] 'read index received' (duration: 1.158294957s)","trace[759350738] 'applied index is now lower than readState.Index' (duration: 380.767827ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:47.169066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539207437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:47.169089Z","caller":"traceutil/trace.go:171","msg":"trace[165304545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"1.539261679s","start":"2026-02-05T15:20:45.62982Z","end":"2026-02-05T15:20:47.169082Z","steps":["trace[165304545] 'agreement among raft nodes before linearized reading' (duration: 1.539205297s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:47.169106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:45.629803Z","time spent":"1.539298449s","remote":"127.0.0.1:44218","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-05T15:20:47.169266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157339466s","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-05T15:20:47.169286Z","caller":"traceutil/trace.go:171","msg":"trace[1279395028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"1.157389266s","start":"2026-02-05T15:20:46.011891Z","end":"2026-02-05T15:20:47.16928Z","steps":["trace[1279395028] 'agreement among raft nodes before linearized reading' (duration: 1.157302234s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:47.169305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:46.011877Z","time spent":"1.157423377s","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T15:20:47.169457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.440435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:47.169476Z","caller":"traceutil/trace.go:171","msg":"trace[1979675455] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:755; }","duration":"382.502006ms","start":"2026-02-05T15:20:46.786969Z","end":"2026-02-05T15:20:47.169471Z","steps":["trace[1979675455] 'agreement among raft nodes before linearized reading' (duration: 382.464925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:47.169494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:46.786927Z","time spent":"382.560898ms","remote":"127.0.0.1:44678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-05T15:20:47.169608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.355149ms","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-05T15:20:47.169626Z","caller":"traceutil/trace.go:171","msg":"trace[121732950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"792.381589ms","start":"2026-02-05T15:20:46.377239Z","end":"2026-02-05T15:20:47.169621Z","steps":["trace[121732950] 'agreement among raft nodes before linearized reading' (duration: 792.329788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:47.169642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:46.377236Z","time spent":"792.40217ms","remote":"127.0.0.1:44492","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-05T15:20:47.169772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.544443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T15:20:47.16979Z","caller":"traceutil/trace.go:171","msg":"trace[482601101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"792.574574ms","start":"2026-02-05T15:20:46.37721Z","end":"2026-02-05T15:20:47.169785Z","steps":["trace[482601101] 'agreement among raft nodes before linearized reading' (duration: 792.522153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:47.169806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:46.377203Z","time spent":"792.599044ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T15:20:47.16991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.375482ms","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-05T15:20:47.169928Z","caller":"traceutil/trace.go:171","msg":"trace[757548446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:755; }","duration":"793.401302ms","start":"2026-02-05T15:20:46.376521Z","end":"2026-02-05T15:20:47.169923Z","steps":["trace[757548446] 'agreement among raft nodes before linearized reading' (duration: 793.352671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:47.169945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:46.376516Z","time spent":"793.423952ms","remote":"127.0.0.1:44492","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-05T15:20:47.170052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.541495ms","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-05T15:20:47.17007Z","caller":"traceutil/trace.go:171","msg":"trace[327827610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:755; }","duration":"793.589566ms","start":"2026-02-05T15:20:46.376475Z","end":"2026-02-05T15:20:47.170065Z","steps":["trace[327827610] 'agreement among raft nodes before linearized reading' (duration: 793.538205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:47.170087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:46.376461Z","time spent":"793.621337ms","remote":"127.0.0.1:44492","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-05T15:20:47.170187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.971803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T15:20:47.170205Z","caller":"traceutil/trace.go:171","msg":"trace[1371613585] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:755; }","duration":"888.044914ms","start":"2026-02-05T15:20:46.282155Z","end":"2026-02-05T15:20:47.1702Z","steps":["trace[1371613585] 'agreement among raft nodes before linearized reading' (duration: 888.012453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:47.170222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:46.282144Z","time spent":"888.073044ms","remote":"127.0.0.1:44614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-02-05T15:20:47.398069Z","caller":"traceutil/trace.go:171","msg":"trace[2049267560] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"222.421702ms","start":"2026-02-05T15:20:47.175617Z","end":"2026-02-05T15:20:47.398039Z","steps":["trace[2049267560] 'process raft request' (duration: 139.277225ms)","trace[2049267560] 'compare' (duration: 82.919212ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:20:47.398108Z","caller":"traceutil/trace.go:171","msg":"trace[1111813790] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"213.866591ms","start":"2026-02-05T15:20:47.184226Z","end":"2026-02-05T15:20:47.398092Z","steps":["trace[1111813790] 'process raft request' (duration: 213.8445ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:47.39823Z","caller":"traceutil/trace.go:171","msg":"trace[2026780547] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"222.185806ms","start":"2026-02-05T15:20:47.176038Z","end":"2026-02-05T15:20:47.398224Z","steps":["trace[2026780547] 'read index received' (duration: 138.866155ms)","trace[2026780547] 'applied index is now lower than readState.Index' (duration: 83.318881ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:20:47.398275Z","caller":"traceutil/trace.go:171","msg":"trace[1230767993] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"222.213387ms","start":"2026-02-05T15:20:47.176056Z","end":"2026-02-05T15:20:47.398269Z","steps":["trace[1230767993] 'process raft request' (duration: 221.93338ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:47.398382Z","caller":"traceutil/trace.go:171","msg":"trace[145718995] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"219.385674ms","start":"2026-02-05T15:20:47.178992Z","end":"2026-02-05T15:20:47.398378Z","steps":["trace[145718995] 'process raft request' (duration: 219.026646ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:47.398426Z","caller":"traceutil/trace.go:171","msg":"trace[852215485] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"219.410034ms","start":"2026-02-05T15:20:47.179011Z","end":"2026-02-05T15:20:47.398421Z","steps":["trace[852215485] 'process raft request' (duration: 219.041516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:47.398529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.478032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-05T15:20:47.398544Z","caller":"traceutil/trace.go:171","msg":"trace[149339623] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:760; }","duration":"222.516244ms","start":"2026-02-05T15:20:47.176023Z","end":"2026-02-05T15:20:47.39854Z","steps":["trace[149339623] 'agreement among raft nodes before linearized reading' (duration: 222.439302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:47.398628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.552663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:47.398641Z","caller":"traceutil/trace.go:171","msg":"trace[2105965677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"204.587605ms","start":"2026-02-05T15:20:47.194049Z","end":"2026-02-05T15:20:47.398636Z","steps":["trace[2105965677] 'agreement among raft nodes before linearized reading' (duration: 204.566164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:47.398788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.192191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T15:20:47.398802Z","caller":"traceutil/trace.go:171","msg":"trace[690377218] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:760; }","duration":"143.247152ms","start":"2026-02-05T15:20:47.255551Z","end":"2026-02-05T15:20:47.398798Z","steps":["trace[690377218] 'agreement among raft nodes before linearized reading' (duration: 143.222012ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:49.60673Z","caller":"traceutil/trace.go:171","msg":"trace[616077710] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"200.007182ms","start":"2026-02-05T15:20:49.4067Z","end":"2026-02-05T15:20:49.606708Z","steps":["trace[616077710] 'process raft request' (duration: 199.89049ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:49.60729Z","caller":"traceutil/trace.go:171","msg":"trace[2110807170] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:794; }","duration":"200.058813ms","start":"2026-02-05T15:20:49.40722Z","end":"2026-02-05T15:20:49.607279Z","steps":["trace[2110807170] 'read index received' (duration: 200.054163ms)","trace[2110807170] 'applied index is now lower than readState.Index' (duration: 3.89µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:49.60743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.198666ms","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-05T15:20:49.60746Z","caller":"traceutil/trace.go:171","msg":"trace[61988556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:761; }","duration":"200.260157ms","start":"2026-02-05T15:20:49.407191Z","end":"2026-02-05T15:20:49.607451Z","steps":["trace[61988556] 'agreement among raft nodes before linearized reading' (duration: 200.144505ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:49.903092Z","caller":"traceutil/trace.go:171","msg":"trace[1307197436] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:794; }","duration":"295.760599ms","start":"2026-02-05T15:20:49.607319Z","end":"2026-02-05T15:20:49.903079Z","steps":["trace[1307197436] 'read index received' (duration: 295.670337ms)","trace[1307197436] 'applied index is now lower than readState.Index' (duration: 89.732µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:20:49.903116Z","caller":"traceutil/trace.go:171","msg":"trace[1318148819] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"471.39686ms","start":"2026-02-05T15:20:49.431705Z","end":"2026-02-05T15:20:49.903101Z","steps":["trace[1318148819] 'process raft request' (duration: 471.291468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:49.903297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.191397ms","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-05T15:20:49.903314Z","caller":"traceutil/trace.go:171","msg":"trace[1433448381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:762; }","duration":"490.220158ms","start":"2026-02-05T15:20:49.413089Z","end":"2026-02-05T15:20:49.903309Z","steps":["trace[1433448381] 'agreement among raft nodes before linearized reading' (duration: 490.100785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:49.903328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:49.413086Z","time spent":"490.238618ms","remote":"127.0.0.1:44492","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-05T15:20:49.903327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:49.43169Z","time spent":"471.525473ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:20:49.903486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.317222ms","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-05T15:20:49.903508Z","caller":"traceutil/trace.go:171","msg":"trace[1110053885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:762; }","duration":"495.346102ms","start":"2026-02-05T15:20:49.408155Z","end":"2026-02-05T15:20:49.903501Z","steps":["trace[1110053885] 'agreement among raft nodes before linearized reading' (duration: 495.288571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:49.903529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:49.40815Z","time spent":"495.373083ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T15:20:49.903602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.519814ms","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-05T15:20:49.903622Z","caller":"traceutil/trace.go:171","msg":"trace[1557271266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"490.552706ms","start":"2026-02-05T15:20:49.413064Z","end":"2026-02-05T15:20:49.903616Z","steps":["trace[1557271266] 'agreement among raft nodes before linearized reading' (duration: 490.503725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:49.903637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:49.413052Z","time spent":"490.581986ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T15:20:50.388497Z","caller":"traceutil/trace.go:171","msg":"trace[1002076989] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"485.300008ms","start":"2026-02-05T15:20:49.903178Z","end":"2026-02-05T15:20:50.388478Z","steps":["trace[1002076989] 'read index received' (duration: 477.004314ms)","trace[1002076989] 'applied index is now lower than readState.Index' (duration: 8.295084ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:50.388626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.208191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:50.38866Z","caller":"traceutil/trace.go:171","msg":"trace[1873415884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"763.267022ms","start":"2026-02-05T15:20:49.625382Z","end":"2026-02-05T15:20:50.388649Z","steps":["trace[1873415884] 'agreement among raft nodes before linearized reading' (duration: 763.16773ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:50.388679Z","caller":"traceutil/trace.go:171","msg":"trace[1571403777] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"773.54327ms","start":"2026-02-05T15:20:49.615122Z","end":"2026-02-05T15:20:50.388665Z","steps":["trace[1571403777] 'process raft request' (duration: 765.051192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:50.38869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:49.625376Z","time spent":"763.306053ms","remote":"127.0.0.1:44218","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-05T15:20:50.388751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:49.615102Z","time spent":"773.603172ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:20:50.39401Z","caller":"traceutil/trace.go:171","msg":"trace[1005087137] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"487.297283ms","start":"2026-02-05T15:20:49.9067Z","end":"2026-02-05T15:20:50.393997Z","steps":["trace[1005087137] 'process raft request' (duration: 486.952116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:50.394089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:49.906686Z","time spent":"487.360854ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:20:50.394212Z","caller":"traceutil/trace.go:171","msg":"trace[1248151945] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"486.792843ms","start":"2026-02-05T15:20:49.907409Z","end":"2026-02-05T15:20:50.394202Z","steps":["trace[1248151945] 'process raft request' (duration: 486.313082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:50.39427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:49.9074Z","time spent":"486.842053ms","remote":"127.0.0.1:44492","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-05T15:20:50.394402Z","caller":"traceutil/trace.go:171","msg":"trace[1832394016] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"486.087486ms","start":"2026-02-05T15:20:49.908305Z","end":"2026-02-05T15:20:50.394392Z","steps":["trace[1832394016] 'process raft request' (duration: 485.447052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:50.394459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:49.908297Z","time spent":"486.132287ms","remote":"127.0.0.1:44492","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-05T15:21:00.696934Z","caller":"traceutil/trace.go:171","msg":"trace[461774110] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"258.88281ms","start":"2026-02-05T15:21:00.438029Z","end":"2026-02-05T15:21:00.696912Z","steps":["trace[461774110] 'read index received' (duration: 258.652185ms)","trace[461774110] 'applied index is now lower than readState.Index' (duration: 229.705µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:21:00.697273Z","caller":"traceutil/trace.go:171","msg":"trace[1568828093] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"269.930035ms","start":"2026-02-05T15:21:00.427331Z","end":"2026-02-05T15:21:00.697261Z","steps":["trace[1568828093] 'process raft request' (duration: 269.399323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:00.697514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.468213ms","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-05T15:21:00.697548Z","caller":"traceutil/trace.go:171","msg":"trace[1873880654] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"259.523505ms","start":"2026-02-05T15:21:00.438011Z","end":"2026-02-05T15:21:00.697535Z","steps":["trace[1873880654] 'agreement among raft nodes before linearized reading' (duration: 259.408232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:00.697806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.196119ms","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-05T15:21:00.697889Z","caller":"traceutil/trace.go:171","msg":"trace[796139517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"251.318931ms","start":"2026-02-05T15:21:00.446549Z","end":"2026-02-05T15:21:00.697868Z","steps":["trace[796139517] 'agreement among raft nodes before linearized reading' (duration: 251.087126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:00.698126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.746993ms","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-05T15:21:00.69816Z","caller":"traceutil/trace.go:171","msg":"trace[1024671036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"247.820894ms","start":"2026-02-05T15:21:00.45033Z","end":"2026-02-05T15:21:00.698151Z","steps":["trace[1024671036] 'agreement among raft nodes before linearized reading' (duration: 247.736282ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:01.960128Z","caller":"traceutil/trace.go:171","msg":"trace[1192586730] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"294.45583ms","start":"2026-02-05T15:21:01.665649Z","end":"2026-02-05T15:21:01.960104Z","steps":["trace[1192586730] 'process raft request' (duration: 294.315217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:02.477782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.58524ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-05T15:21:02.928538Z","caller":"traceutil/trace.go:171","msg":"trace[541823156] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"200.303868ms","start":"2026-02-05T15:21:02.728216Z","end":"2026-02-05T15:21:02.92852Z","steps":["trace[541823156] 'read index received' (duration: 200.153095ms)","trace[541823156] 'applied index is now lower than readState.Index' (duration: 149.983µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:21:02.928618Z","caller":"traceutil/trace.go:171","msg":"trace[1019369029] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"220.512088ms","start":"2026-02-05T15:21:02.708099Z","end":"2026-02-05T15:21:02.928611Z","steps":["trace[1019369029] 'process raft request' (duration: 220.306233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:02.928831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.613227ms","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-05T15:21:02.928866Z","caller":"traceutil/trace.go:171","msg":"trace[1591022936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:801; }","duration":"200.660028ms","start":"2026-02-05T15:21:02.7282Z","end":"2026-02-05T15:21:02.92886Z","steps":["trace[1591022936] 'agreement among raft nodes before linearized reading' (duration: 200.554266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:02.929014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.120641ms","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-05T15:21:02.929044Z","caller":"traceutil/trace.go:171","msg":"trace[1491032686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"185.151903ms","start":"2026-02-05T15:21:02.743885Z","end":"2026-02-05T15:21:02.929037Z","steps":["trace[1491032686] 'agreement among raft nodes before linearized reading' (duration: 185.068131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:03.284673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.80279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T15:21:03.284809Z","caller":"traceutil/trace.go:171","msg":"trace[285494295] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"343.748735ms","start":"2026-02-05T15:21:02.941049Z","end":"2026-02-05T15:21:03.284797Z","steps":["trace[285494295] 'process raft request' (duration: 343.724265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:03.284852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:02.94104Z","time spent":"343.791216ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:21:03.28504Z","caller":"traceutil/trace.go:171","msg":"trace[1458218435] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"352.023609ms","start":"2026-02-05T15:21:02.93301Z","end":"2026-02-05T15:21:03.285033Z","steps":["trace[1458218435] 'process raft request' (duration: 55.80405ms)","trace[1458218435] 'compare' (duration: 295.720018ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:03.290034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:02.932992Z","time spent":"357.01033ms","remote":"127.0.0.1:44492","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-05T15:21:03.289828Z","caller":"traceutil/trace.go:171","msg":"trace[1840810279] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"355.902215ms","start":"2026-02-05T15:21:02.933909Z","end":"2026-02-05T15:21:03.289812Z","steps":["trace[1840810279] 'process raft request' (duration: 350.833033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:03.290161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:02.933889Z","time spent":"356.255033ms","remote":"127.0.0.1:44492","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-05T15:21:15.849122Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515277716,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T15:21:15.912026Z","caller":"traceutil/trace.go:171","msg":"trace[1772993870] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"563.90501ms","start":"2026-02-05T15:21:15.348103Z","end":"2026-02-05T15:21:15.912008Z","steps":["trace[1772993870] 'read index received' (duration: 563.742497ms)","trace[1772993870] 'applied index is now lower than readState.Index' (duration: 161.783µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:21:15.912109Z","caller":"traceutil/trace.go:171","msg":"trace[575975621] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"937.518838ms","start":"2026-02-05T15:21:14.974585Z","end":"2026-02-05T15:21:15.912104Z","steps":["trace[575975621] 'process raft request' (duration: 937.304954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:15.912193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:14.974576Z","time spent":"937.549039ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:21:15.912318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.213767ms","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-05T15:21:15.912335Z","caller":"traceutil/trace.go:171","msg":"trace[1175135010] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:887; }","duration":"564.253178ms","start":"2026-02-05T15:21:15.348075Z","end":"2026-02-05T15:21:15.912329Z","steps":["trace[1175135010] 'agreement among raft nodes before linearized reading' (duration: 564.187567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:15.912349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:15.348062Z","time spent":"564.284359ms","remote":"127.0.0.1:44492","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-05T15:21:15.912535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.411819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.99\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-05T15:21:15.912587Z","caller":"traceutil/trace.go:171","msg":"trace[320265632] range","detail":"{range_begin:/registry/masterleases/199.204.45.99; range_end:; response_count:1; response_revision:887; }","duration":"554.482241ms","start":"2026-02-05T15:21:15.35809Z","end":"2026-02-05T15:21:15.912573Z","steps":["trace[320265632] 'agreement among raft nodes before linearized reading' (duration: 554.345198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:15.912601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.320635ms","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-05T15:21:15.912619Z","caller":"traceutil/trace.go:171","msg":"trace[1005229850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:887; }","duration":"553.346625ms","start":"2026-02-05T15:21:15.359267Z","end":"2026-02-05T15:21:15.912614Z","steps":["trace[1005229850] 'agreement among raft nodes before linearized reading' (duration: 553.303044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:15.912636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:15.359228Z","time spent":"553.404397ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T15:21:15.912703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.606053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:21:15.912727Z","caller":"traceutil/trace.go:171","msg":"trace[1107872237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:887; }","duration":"287.655734ms","start":"2026-02-05T15:21:15.625068Z","end":"2026-02-05T15:21:15.912724Z","steps":["trace[1107872237] 'agreement among raft nodes before linearized reading' (duration: 287.623703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:15.912759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.590003ms","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-05T15:21:15.912615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:15.358085Z","time spent":"554.524352ms","remote":"127.0.0.1:44256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.99\" "} {"level":"info","ts":"2026-02-05T15:21:15.912781Z","caller":"traceutil/trace.go:171","msg":"trace[1501805027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:887; }","duration":"554.619234ms","start":"2026-02-05T15:21:15.358155Z","end":"2026-02-05T15:21:15.912775Z","steps":["trace[1501805027] 'agreement among raft nodes before linearized reading' (duration: 554.474951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:15.9128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:15.358152Z","time spent":"554.643154ms","remote":"127.0.0.1:44492","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-05T15:21:16.392815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:15.918012Z","time spent":"474.799658ms","remote":"127.0.0.1:44256","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-05T15:21:16.392904Z","caller":"traceutil/trace.go:171","msg":"trace[448571823] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:926; }","duration":"474.678335ms","start":"2026-02-05T15:21:15.918211Z","end":"2026-02-05T15:21:16.392889Z","steps":["trace[448571823] 'read index received' (duration: 463.009908ms)","trace[448571823] 'applied index is now lower than readState.Index' (duration: 11.667717ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:21:16.393122Z","caller":"traceutil/trace.go:171","msg":"trace[1584663225] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"475.031672ms","start":"2026-02-05T15:21:15.918081Z","end":"2026-02-05T15:21:16.393113Z","steps":["trace[1584663225] 'process raft request' (duration: 474.660004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:16.393193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:15.918066Z","time spent":"475.075803ms","remote":"127.0.0.1:44492","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-05T15:21:16.393283Z","caller":"traceutil/trace.go:171","msg":"trace[253077001] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"475.136305ms","start":"2026-02-05T15:21:15.918141Z","end":"2026-02-05T15:21:16.393277Z","steps":["trace[253077001] 'process raft request' (duration: 474.679875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:16.393316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:15.918138Z","time spent":"475.160555ms","remote":"127.0.0.1:44492","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-05T15:21:16.393414Z","caller":"traceutil/trace.go:171","msg":"trace[118251527] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"475.243467ms","start":"2026-02-05T15:21:15.918165Z","end":"2026-02-05T15:21:16.393408Z","steps":["trace[118251527] 'process raft request' (duration: 474.700495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:16.393446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:15.91816Z","time spent":"475.268437ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:21:16.393578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.362119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:21:16.393603Z","caller":"traceutil/trace.go:171","msg":"trace[1933881976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"475.402581ms","start":"2026-02-05T15:21:15.918193Z","end":"2026-02-05T15:21:16.393596Z","steps":["trace[1933881976] 'agreement among raft nodes before linearized reading' (duration: 475.36219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:16.393622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:15.918188Z","time spent":"475.428731ms","remote":"127.0.0.1:44230","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-05T15:21:16.39386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.282228ms","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-05T15:21:16.393884Z","caller":"traceutil/trace.go:171","msg":"trace[161550334] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:890; }","duration":"357.37075ms","start":"2026-02-05T15:21:16.036505Z","end":"2026-02-05T15:21:16.393876Z","steps":["trace[161550334] 'agreement among raft nodes before linearized reading' (duration: 357.267518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:16.393903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:16.036484Z","time spent":"357.41355ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T15:21:18.898621Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515277737,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T15:21:19.399575Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515277737,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T15:21:19.479308Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.558672009s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T15:21:19.479594Z","caller":"traceutil/trace.go:171","msg":"trace[1621300618] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"1.081791586s","start":"2026-02-05T15:21:18.397787Z","end":"2026-02-05T15:21:19.479579Z","steps":["trace[1621300618] 'read index received' (duration: 1.081629312s)","trace[1621300618] 'applied index is now lower than readState.Index' (duration: 161.734µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:21:19.479778Z","caller":"traceutil/trace.go:171","msg":"trace[346626006] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"1.559218891s","start":"2026-02-05T15:21:17.920554Z","end":"2026-02-05T15:21:19.479773Z","steps":["trace[346626006] 'process raft request' (duration: 1.558916484s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:19.479834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:17.920538Z","time spent":"1.559253851s","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:21:19.479968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082178994s","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-05T15:21:19.479982Z","caller":"traceutil/trace.go:171","msg":"trace[1118716251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:893; }","duration":"1.082217634s","start":"2026-02-05T15:21:18.39776Z","end":"2026-02-05T15:21:19.479978Z","steps":["trace[1118716251] 'agreement among raft nodes before linearized reading' (duration: 1.082157773s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:19.479995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:18.397749Z","time spent":"1.082243605s","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T15:21:19.480085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08200916s","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-05T15:21:19.4801Z","caller":"traceutil/trace.go:171","msg":"trace[777451519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:893; }","duration":"1.082032151s","start":"2026-02-05T15:21:18.398063Z","end":"2026-02-05T15:21:19.480095Z","steps":["trace[777451519] 'agreement among raft nodes before linearized reading' (duration: 1.08198125s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:19.480113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:18.398059Z","time spent":"1.082049821s","remote":"127.0.0.1:44492","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-05T15:21:19.480224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.04899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:21:19.48024Z","caller":"traceutil/trace.go:171","msg":"trace[132807507] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:893; }","duration":"103.109711ms","start":"2026-02-05T15:21:19.377125Z","end":"2026-02-05T15:21:19.480235Z","steps":["trace[132807507] 'agreement among raft nodes before linearized reading' (duration: 103.08574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:19.480315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.953524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:21:19.480328Z","caller":"traceutil/trace.go:171","msg":"trace[1485480990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:893; }","duration":"851.995345ms","start":"2026-02-05T15:21:18.628329Z","end":"2026-02-05T15:21:19.480325Z","steps":["trace[1485480990] 'agreement among raft nodes before linearized reading' (duration: 851.975264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:19.48034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:18.628313Z","time spent":"852.023005ms","remote":"127.0.0.1:44218","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-05T15:21:19.480424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.06790903s","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-05T15:21:19.480436Z","caller":"traceutil/trace.go:171","msg":"trace[1921553825] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:893; }","duration":"1.06793034s","start":"2026-02-05T15:21:18.412502Z","end":"2026-02-05T15:21:19.480432Z","steps":["trace[1921553825] 'agreement among raft nodes before linearized reading' (duration: 1.067896089s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:19.480447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:18.412497Z","time spent":"1.06794739s","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T15:21:19.480523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081980239s","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-05T15:21:19.480534Z","caller":"traceutil/trace.go:171","msg":"trace[790833159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:893; }","duration":"1.082000399s","start":"2026-02-05T15:21:18.39853Z","end":"2026-02-05T15:21:19.480531Z","steps":["trace[790833159] 'agreement among raft nodes before linearized reading' (duration: 1.081963738s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:19.480545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:18.398526Z","time spent":"1.082016401s","remote":"127.0.0.1:44492","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-05T15:21:19.987957Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515277740,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T15:21:20.319114Z","caller":"traceutil/trace.go:171","msg":"trace[587384401] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"832.018524ms","start":"2026-02-05T15:21:19.487072Z","end":"2026-02-05T15:21:20.31909Z","steps":["trace[587384401] 'process raft request' (duration: 831.83143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:20.319302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:19.48706Z","time spent":"832.122327ms","remote":"127.0.0.1:44492","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-05T15:21:20.785938Z","caller":"traceutil/trace.go:171","msg":"trace[826909832] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"1.298629942s","start":"2026-02-05T15:21:19.48729Z","end":"2026-02-05T15:21:20.785919Z","steps":["trace[826909832] 'process raft request' (duration: 1.278661522s)","trace[826909832] 'compare' (duration: 19.713344ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:20.786032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:19.487285Z","time spent":"1.298697973s","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:21:20.786129Z","caller":"traceutil/trace.go:171","msg":"trace[761784299] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"1.298782285s","start":"2026-02-05T15:21:19.487315Z","end":"2026-02-05T15:21:20.786098Z","steps":["trace[761784299] 'process raft request' (duration: 1.29857808s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:20.786224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:19.487313Z","time spent":"1.298858406s","remote":"127.0.0.1:44492","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-05T15:21:20.786451Z","caller":"traceutil/trace.go:171","msg":"trace[1103285700] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"1.295107403s","start":"2026-02-05T15:21:19.491337Z","end":"2026-02-05T15:21:20.786444Z","steps":["trace[1103285700] 'process raft request' (duration: 1.294712395s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:20.786491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:19.49133Z","time spent":"1.295138264s","remote":"127.0.0.1:44492","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-05T15:21:20.786626Z","caller":"traceutil/trace.go:171","msg":"trace[510362700] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:934; }","duration":"1.299406218s","start":"2026-02-05T15:21:19.487213Z","end":"2026-02-05T15:21:20.786619Z","steps":["trace[510362700] 'read index received' (duration: 832.547237ms)","trace[510362700] 'applied index is now lower than readState.Index' (duration: 466.858201ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:20.800993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313766464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:21:20.801564Z","caller":"traceutil/trace.go:171","msg":"trace[178451876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"1.314337207s","start":"2026-02-05T15:21:19.4872Z","end":"2026-02-05T15:21:20.801537Z","steps":["trace[178451876] 'agreement among raft nodes before linearized reading' (duration: 1.299441008s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:20.801637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:19.487195Z","time spent":"1.3144327s","remote":"127.0.0.1:44230","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-05T15:21:20.801822Z","caller":"traceutil/trace.go:171","msg":"trace[1976562848] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"205.078937ms","start":"2026-02-05T15:21:20.596736Z","end":"2026-02-05T15:21:20.801815Z","steps":["trace[1976562848] 'process raft request' (duration: 203.998033ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:25.936273Z","caller":"traceutil/trace.go:171","msg":"trace[1470221972] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"310.660542ms","start":"2026-02-05T15:21:25.625595Z","end":"2026-02-05T15:21:25.936256Z","steps":["trace[1470221972] 'read index received' (duration: 310.480357ms)","trace[1470221972] 'applied index is now lower than readState.Index' (duration: 179.695µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:25.936357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.746774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:21:25.936378Z","caller":"traceutil/trace.go:171","msg":"trace[900325922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"310.809235ms","start":"2026-02-05T15:21:25.62556Z","end":"2026-02-05T15:21:25.936369Z","steps":["trace[900325922] 'agreement among raft nodes before linearized reading' (duration: 310.754094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:25.936409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:25.625544Z","time spent":"310.857156ms","remote":"127.0.0.1:44218","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-05T15:21:25.936541Z","caller":"traceutil/trace.go:171","msg":"trace[811058831] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"433.449007ms","start":"2026-02-05T15:21:25.503073Z","end":"2026-02-05T15:21:25.936522Z","steps":["trace[811058831] 'process raft request' (duration: 433.046067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:25.93666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:25.50305Z","time spent":"433.560839ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:21:26.932837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.917673ms","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-05T15:21:26.932901Z","caller":"traceutil/trace.go:171","msg":"trace[1242295874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:912; }","duration":"121.019545ms","start":"2026-02-05T15:21:26.811866Z","end":"2026-02-05T15:21:26.932886Z","steps":["trace[1242295874] 'range keys from in-memory index tree' (duration: 120.817621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:26.932928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.086527ms","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-05T15:21:26.93297Z","caller":"traceutil/trace.go:171","msg":"trace[1556575914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:912; }","duration":"121.165379ms","start":"2026-02-05T15:21:26.811794Z","end":"2026-02-05T15:21:26.932959Z","steps":["trace[1556575914] 'range keys from in-memory index tree' (duration: 120.937133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:26.933115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.246501ms","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-05T15:21:26.933128Z","caller":"traceutil/trace.go:171","msg":"trace[624539513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:912; }","duration":"117.270132ms","start":"2026-02-05T15:21:26.815854Z","end":"2026-02-05T15:21:26.933124Z","steps":["trace[624539513] 'range keys from in-memory index tree' (duration: 117.201001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:26.933168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.789936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:21:26.933189Z","caller":"traceutil/trace.go:171","msg":"trace[515202996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:912; }","duration":"306.836438ms","start":"2026-02-05T15:21:26.626346Z","end":"2026-02-05T15:21:26.933183Z","steps":["trace[515202996] 'range keys from in-memory index tree' (duration: 306.753376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:26.933209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:26.626334Z","time spent":"306.868308ms","remote":"127.0.0.1:44218","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-05T15:21:28.197769Z","caller":"traceutil/trace.go:171","msg":"trace[1060946769] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"256.552192ms","start":"2026-02-05T15:21:27.941202Z","end":"2026-02-05T15:21:28.197754Z","steps":["trace[1060946769] 'process raft request' (duration: 256.440279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:29.176912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.449887ms","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-05T15:21:29.17696Z","caller":"traceutil/trace.go:171","msg":"trace[313177949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:917; }","duration":"221.536888ms","start":"2026-02-05T15:21:28.955412Z","end":"2026-02-05T15:21:29.176949Z","steps":["trace[313177949] 'range keys from in-memory index tree' (duration: 221.321964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:29.176952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.943595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:21:29.177011Z","caller":"traceutil/trace.go:171","msg":"trace[1296319133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"552.032417ms","start":"2026-02-05T15:21:28.624962Z","end":"2026-02-05T15:21:29.176994Z","steps":["trace[1296319133] 'range keys from in-memory index tree' (duration: 551.855654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:29.177038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:28.624936Z","time spent":"552.095999ms","remote":"127.0.0.1:44218","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-05T15:21:29.1771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.062329ms","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-05T15:21:29.177116Z","caller":"traceutil/trace.go:171","msg":"trace[128305273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:917; }","duration":"221.087379ms","start":"2026-02-05T15:21:28.956023Z","end":"2026-02-05T15:21:29.177111Z","steps":["trace[128305273] 'range keys from in-memory index tree' (duration: 221.019637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:29.177199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.089708ms","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-05T15:21:29.177212Z","caller":"traceutil/trace.go:171","msg":"trace[301543607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:917; }","duration":"221.12792ms","start":"2026-02-05T15:21:28.95608Z","end":"2026-02-05T15:21:29.177208Z","steps":["trace[301543607] 'range keys from in-memory index tree' (duration: 221.015316ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:30.91499Z","caller":"traceutil/trace.go:171","msg":"trace[1990956636] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"290.10983ms","start":"2026-02-05T15:21:30.624865Z","end":"2026-02-05T15:21:30.914975Z","steps":["trace[1990956636] 'read index received' (duration: 290.001568ms)","trace[1990956636] 'applied index is now lower than readState.Index' (duration: 107.702µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:21:30.915097Z","caller":"traceutil/trace.go:171","msg":"trace[1143411492] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"454.828428ms","start":"2026-02-05T15:21:30.460261Z","end":"2026-02-05T15:21:30.91509Z","steps":["trace[1143411492] 'process raft request' (duration: 454.610813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:30.915217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:30.460252Z","time spent":"454.877758ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:21:30.915218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.339245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:21:30.915285Z","caller":"traceutil/trace.go:171","msg":"trace[373694116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"290.438957ms","start":"2026-02-05T15:21:30.624836Z","end":"2026-02-05T15:21:30.915275Z","steps":["trace[373694116] 'agreement among raft nodes before linearized reading' (duration: 290.345615ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:31.047134Z","caller":"traceutil/trace.go:171","msg":"trace[381650072] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"132.081489ms","start":"2026-02-05T15:21:30.915036Z","end":"2026-02-05T15:21:31.047118Z","steps":["trace[381650072] 'read index received' (duration: 46.045264ms)","trace[381650072] 'applied index is now lower than readState.Index' (duration: 86.033955ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:21:31.047251Z","caller":"traceutil/trace.go:171","msg":"trace[1574058597] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"229.042074ms","start":"2026-02-05T15:21:30.818167Z","end":"2026-02-05T15:21:31.047209Z","steps":["trace[1574058597] 'process raft request' (duration: 142.966788ms)","trace[1574058597] 'compare' (duration: 85.913603ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:31.047291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.711585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T15:21:31.047312Z","caller":"traceutil/trace.go:171","msg":"trace[421708405] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:923; }","duration":"415.760096ms","start":"2026-02-05T15:21:30.631547Z","end":"2026-02-05T15:21:31.047307Z","steps":["trace[421708405] 'agreement among raft nodes before linearized reading' (duration: 415.658884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:31.047332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:30.631541Z","time spent":"415.785767ms","remote":"127.0.0.1:44508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-05T15:21:31.313466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.835252ms","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-05T15:21:31.31351Z","caller":"traceutil/trace.go:171","msg":"trace[1263258303] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:923; }","duration":"110.912563ms","start":"2026-02-05T15:21:31.202586Z","end":"2026-02-05T15:21:31.313499Z","steps":["trace[1263258303] 'range keys from in-memory index tree' (duration: 110.741009ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:31.526502Z","caller":"traceutil/trace.go:171","msg":"trace[1126667423] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"208.699666ms","start":"2026-02-05T15:21:31.317787Z","end":"2026-02-05T15:21:31.526487Z","steps":["trace[1126667423] 'process raft request' (duration: 208.609124ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:31.537638Z","caller":"traceutil/trace.go:171","msg":"trace[288895017] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"218.271807ms","start":"2026-02-05T15:21:31.319352Z","end":"2026-02-05T15:21:31.537623Z","steps":["trace[288895017] 'process raft request' (duration: 218.246967ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:31.537853Z","caller":"traceutil/trace.go:171","msg":"trace[782020979] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"219.285469ms","start":"2026-02-05T15:21:31.318563Z","end":"2026-02-05T15:21:31.537848Z","steps":["trace[782020979] 'process raft request' (duration: 218.969722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:35.594043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515277847,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T15:21:35.668341Z","caller":"traceutil/trace.go:171","msg":"trace[1395703989] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"575.072736ms","start":"2026-02-05T15:21:35.093252Z","end":"2026-02-05T15:21:35.668324Z","steps":["trace[1395703989] 'read index received' (duration: 574.934403ms)","trace[1395703989] 'applied index is now lower than readState.Index' (duration: 136.283µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:21:35.668403Z","caller":"traceutil/trace.go:171","msg":"trace[791981808] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"664.928406ms","start":"2026-02-05T15:21:35.00347Z","end":"2026-02-05T15:21:35.668398Z","steps":["trace[791981808] 'process raft request' (duration: 664.721461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:35.668461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:35.003452Z","time spent":"664.963656ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:21:35.668616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.775068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.99\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-05T15:21:35.668663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.545983ms","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-05T15:21:35.668658Z","caller":"traceutil/trace.go:171","msg":"trace[1522185893] range","detail":"{range_begin:/registry/masterleases/199.204.45.99; range_end:; response_count:1; response_revision:933; }","duration":"302.85307ms","start":"2026-02-05T15:21:35.365791Z","end":"2026-02-05T15:21:35.668644Z","steps":["trace[1522185893] 'agreement among raft nodes before linearized reading' (duration: 302.733987ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:35.668679Z","caller":"traceutil/trace.go:171","msg":"trace[406041247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"104.574774ms","start":"2026-02-05T15:21:35.564099Z","end":"2026-02-05T15:21:35.668674Z","steps":["trace[406041247] 'agreement among raft nodes before linearized reading' (duration: 104.525883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:35.668689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:35.365774Z","time spent":"302.907431ms","remote":"127.0.0.1:44256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.99\" "} {"level":"warn","ts":"2026-02-05T15:21:35.668761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.512055ms","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-05T15:21:35.668774Z","caller":"traceutil/trace.go:171","msg":"trace[44886455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:933; }","duration":"575.546376ms","start":"2026-02-05T15:21:35.093224Z","end":"2026-02-05T15:21:35.66877Z","steps":["trace[44886455] 'agreement among raft nodes before linearized reading' (duration: 575.510255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:35.668787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:35.093213Z","time spent":"575.571336ms","remote":"127.0.0.1:44492","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-05T15:21:35.668869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.385441ms","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-05T15:21:35.668896Z","caller":"traceutil/trace.go:171","msg":"trace[475348378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:933; }","duration":"105.432012ms","start":"2026-02-05T15:21:35.563456Z","end":"2026-02-05T15:21:35.668888Z","steps":["trace[475348378] 'agreement among raft nodes before linearized reading' (duration: 105.357481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:35.669023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.892834ms","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-05T15:21:35.669037Z","caller":"traceutil/trace.go:171","msg":"trace[741133953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:933; }","duration":"111.942145ms","start":"2026-02-05T15:21:35.557091Z","end":"2026-02-05T15:21:35.669033Z","steps":["trace[741133953] 'agreement among raft nodes before linearized reading' (duration: 111.904115ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:38.621532Z","caller":"traceutil/trace.go:171","msg":"trace[1603681103] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"283.548704ms","start":"2026-02-05T15:21:38.337968Z","end":"2026-02-05T15:21:38.621516Z","steps":["trace[1603681103] 'process raft request' (duration: 283.462063ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:39.990158Z","caller":"traceutil/trace.go:171","msg":"trace[821187795] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"287.92243ms","start":"2026-02-05T15:21:39.702219Z","end":"2026-02-05T15:21:39.990141Z","steps":["trace[821187795] 'process raft request' (duration: 287.826358ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:39.990555Z","caller":"traceutil/trace.go:171","msg":"trace[1460522452] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:998; }","duration":"285.788004ms","start":"2026-02-05T15:21:39.704759Z","end":"2026-02-05T15:21:39.990547Z","steps":["trace[1460522452] 'read index received' (duration: 285.784824ms)","trace[1460522452] 'applied index is now lower than readState.Index' (duration: 2.7µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:39.990662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.887466ms","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-05T15:21:39.990686Z","caller":"traceutil/trace.go:171","msg":"trace[1614955662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:953; }","duration":"285.934017ms","start":"2026-02-05T15:21:39.704746Z","end":"2026-02-05T15:21:39.99068Z","steps":["trace[1614955662] 'agreement among raft nodes before linearized reading' (duration: 285.842915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:40.491461Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515277900,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T15:21:40.992445Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114605515277900,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T15:21:41.009733Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.019726568s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T15:21:41.010385Z","caller":"traceutil/trace.go:171","msg":"trace[1424250108] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"1.257436182s","start":"2026-02-05T15:21:39.752928Z","end":"2026-02-05T15:21:41.010364Z","steps":["trace[1424250108] 'process raft request' (duration: 1.257248328s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:41.010491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:39.752903Z","time spent":"1.257515995s","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4907,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:21:41.568312Z","caller":"traceutil/trace.go:171","msg":"trace[352652559] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.353426707s","start":"2026-02-05T15:21:40.214845Z","end":"2026-02-05T15:21:41.568272Z","steps":["trace[352652559] 'process raft request' (duration: 1.275642074s)","trace[352652559] 'compare' (duration: 72.66743ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:41.568434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:40.214825Z","time spent":"1.35354353s","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:21:41.76305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.528065ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-05T15:21:41.763364Z","caller":"traceutil/trace.go:171","msg":"trace[966045377] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:998; }","duration":"1.772760722s","start":"2026-02-05T15:21:39.990578Z","end":"2026-02-05T15:21:41.763338Z","steps":["trace[966045377] 'read index received' (duration: 1.019659946s)","trace[966045377] 'applied index is now lower than readState.Index' (duration: 753.098916ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:41.764578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.05912017s","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-05T15:21:41.764647Z","caller":"traceutil/trace.go:171","msg":"trace[1804967337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"2.059203621s","start":"2026-02-05T15:21:39.705426Z","end":"2026-02-05T15:21:41.764629Z","steps":["trace[1804967337] 'agreement among raft nodes before linearized reading' (duration: 2.059005367s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:41.764697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:39.705421Z","time spent":"2.059262792s","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T15:21:41.765505Z","caller":"traceutil/trace.go:171","msg":"trace[1476944287] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"493.58184ms","start":"2026-02-05T15:21:41.271908Z","end":"2026-02-05T15:21:41.76549Z","steps":["trace[1476944287] 'process raft request' (duration: 491.240488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:41.765655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:41.271892Z","time spent":"493.698783ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:21:42.164293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536477238s","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-05T15:21:42.164353Z","caller":"traceutil/trace.go:171","msg":"trace[1278279995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:956; }","duration":"1.536580151s","start":"2026-02-05T15:21:40.627757Z","end":"2026-02-05T15:21:42.164337Z","steps":["trace[1278279995] 'agreement among raft nodes before linearized reading' (duration: 1.137693936s)","trace[1278279995] 'range keys from in-memory index tree' (duration: 398.733802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:42.164383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:40.627738Z","time spent":"1.536638162s","remote":"127.0.0.1:44492","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-05T15:21:42.164533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.450813775s","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-05T15:21:42.164552Z","caller":"traceutil/trace.go:171","msg":"trace[1776451309] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:956; }","duration":"2.450866146s","start":"2026-02-05T15:21:39.713679Z","end":"2026-02-05T15:21:42.164546Z","steps":["trace[1776451309] 'agreement among raft nodes before linearized reading' (duration: 2.051728316s)","trace[1776451309] 'range keys from in-memory index tree' (duration: 399.081019ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:42.164571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:39.713661Z","time spent":"2.450904568s","remote":"127.0.0.1:44492","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-05T15:21:42.164711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.534468115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:21:42.164731Z","caller":"traceutil/trace.go:171","msg":"trace[664357061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"1.534517496s","start":"2026-02-05T15:21:40.630207Z","end":"2026-02-05T15:21:42.164724Z","steps":["trace[664357061] 'agreement among raft nodes before linearized reading' (duration: 1.13561356s)","trace[664357061] 'range keys from in-memory index tree' (duration: 398.870966ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:42.16475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:40.630197Z","time spent":"1.534545977s","remote":"127.0.0.1:44218","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-05T15:21:42.165023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.36471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T15:21:42.165064Z","caller":"traceutil/trace.go:171","msg":"trace[638475874] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"171.103988ms","start":"2026-02-05T15:21:41.993954Z","end":"2026-02-05T15:21:42.165058Z","steps":["trace[638475874] 'read index received' (duration: 11.603896ms)","trace[638475874] 'applied index is now lower than readState.Index' (duration: 159.499282ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:42.165134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.17985ms","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-05T15:21:42.165154Z","caller":"traceutil/trace.go:171","msg":"trace[1489630937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:957; }","duration":"171.20041ms","start":"2026-02-05T15:21:41.993948Z","end":"2026-02-05T15:21:42.165149Z","steps":["trace[1489630937] 'agreement among raft nodes before linearized reading' (duration: 171.130449ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:42.165338Z","caller":"traceutil/trace.go:171","msg":"trace[584464574] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"275.727743ms","start":"2026-02-05T15:21:41.889599Z","end":"2026-02-05T15:21:42.165327Z","steps":["trace[584464574] 'process raft request' (duration: 116.021746ms)","trace[584464574] 'compare' (duration: 159.309019ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:21:43.482187Z","caller":"traceutil/trace.go:171","msg":"trace[851664554] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"304.911516ms","start":"2026-02-05T15:21:43.177259Z","end":"2026-02-05T15:21:43.482171Z","steps":["trace[851664554] 'process raft request' (duration: 304.815854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:43.482282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:43.177247Z","time spent":"304.986587ms","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5047,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:21:46.225444Z","caller":"traceutil/trace.go:171","msg":"trace[951964991] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"246.945388ms","start":"2026-02-05T15:21:45.978474Z","end":"2026-02-05T15:21:46.22542Z","steps":["trace[951964991] 'process raft request' (duration: 245.511817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:46.656602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.057667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T15:21:46.657016Z","caller":"traceutil/trace.go:171","msg":"trace[189008233] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"424.320705ms","start":"2026-02-05T15:21:46.232679Z","end":"2026-02-05T15:21:46.657Z","steps":["trace[189008233] 'process raft request' (duration: 424.211823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:46.657206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:46.23267Z","time spent":"424.461138ms","remote":"127.0.0.1:44492","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-05T15:21:46.657703Z","caller":"traceutil/trace.go:171","msg":"trace[1261004878] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"426.255348ms","start":"2026-02-05T15:21:46.231409Z","end":"2026-02-05T15:21:46.657664Z","steps":["trace[1261004878] 'process raft request' (duration: 136.053316ms)","trace[1261004878] 'compare' (duration: 288.428603ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:46.657818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:46.2314Z","time spent":"426.3867ms","remote":"127.0.0.1:44492","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-05T15:21:46.658057Z","caller":"traceutil/trace.go:171","msg":"trace[366601148] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"426.419002ms","start":"2026-02-05T15:21:46.231628Z","end":"2026-02-05T15:21:46.658047Z","steps":["trace[366601148] 'read index received' (duration: 135.844662ms)","trace[366601148] 'applied index is now lower than readState.Index' (duration: 290.57288ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:46.658493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.855041ms","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-05T15:21:46.658573Z","caller":"traceutil/trace.go:171","msg":"trace[1873005426] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:989; }","duration":"426.962434ms","start":"2026-02-05T15:21:46.231599Z","end":"2026-02-05T15:21:46.658562Z","steps":["trace[1873005426] 'agreement among raft nodes before linearized reading' (duration: 426.75913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:46.658646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:46.231587Z","time spent":"427.023635ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T15:21:46.713058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.678088ms","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-05T15:21:46.734575Z","caller":"traceutil/trace.go:171","msg":"trace[953474560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:987; }","duration":"481.799321ms","start":"2026-02-05T15:21:46.231326Z","end":"2026-02-05T15:21:46.713125Z","steps":["trace[953474560] 'range keys from in-memory index tree' (duration: 424.378767ms)","trace[953474560] 'range keys from bolt db' (duration: 57.072217ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:46.734695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:46.231313Z","time spent":"503.352166ms","remote":"127.0.0.1:44492","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-05T15:21:47.414893Z","caller":"traceutil/trace.go:171","msg":"trace[2082072806] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"750.257253ms","start":"2026-02-05T15:21:46.664616Z","end":"2026-02-05T15:21:47.414873Z","steps":["trace[2082072806] 'process raft request' (duration: 693.706488ms)","trace[2082072806] 'compare' (duration: 56.192298ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:47.414989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:46.664594Z","time spent":"750.342966ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:21:47.443408Z","caller":"traceutil/trace.go:171","msg":"trace[48995267] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"701.008938ms","start":"2026-02-05T15:21:46.742385Z","end":"2026-02-05T15:21:47.443394Z","steps":["trace[48995267] 'process raft request' (duration: 696.938338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:47.443499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:46.742353Z","time spent":"701.0979ms","remote":"127.0.0.1:44492","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-05T15:21:49.898256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.944305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-05T15:21:49.898289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.529974ms","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-05T15:21:49.898359Z","caller":"traceutil/trace.go:171","msg":"trace[1051345002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1005; }","duration":"451.636837ms","start":"2026-02-05T15:21:49.446703Z","end":"2026-02-05T15:21:49.898339Z","steps":["trace[1051345002] 'range keys from in-memory index tree' (duration: 451.382601ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:49.898372Z","caller":"traceutil/trace.go:171","msg":"trace[2020100664] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"300.796335ms","start":"2026-02-05T15:21:49.597568Z","end":"2026-02-05T15:21:49.898364Z","steps":["trace[2020100664] 'read index received' (duration: 278.669457ms)","trace[2020100664] 'applied index is now lower than readState.Index' (duration: 22.126148ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:49.8984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:49.446688Z","time spent":"451.701918ms","remote":"127.0.0.1:44492","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-05T15:21:49.898464Z","caller":"traceutil/trace.go:171","msg":"trace[457251607] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"301.360007ms","start":"2026-02-05T15:21:49.597096Z","end":"2026-02-05T15:21:49.898456Z","steps":["trace[457251607] 'process raft request' (duration: 279.129787ms)","trace[457251607] 'compare' (duration: 21.677818ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:49.898619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:49.597072Z","time spent":"301.49805ms","remote":"127.0.0.1:44290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T15:21:49.898313Z","caller":"traceutil/trace.go:171","msg":"trace[1303255165] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1005; }","duration":"438.032706ms","start":"2026-02-05T15:21:49.460266Z","end":"2026-02-05T15:21:49.898299Z","steps":["trace[1303255165] 'range keys from in-memory index tree' (duration: 437.873224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:49.898698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:49.460252Z","time spent":"438.429475ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-05T15:21:49.899163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.592523ms","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-05T15:21:49.899296Z","caller":"traceutil/trace.go:171","msg":"trace[721170524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1006; }","duration":"301.760777ms","start":"2026-02-05T15:21:49.597526Z","end":"2026-02-05T15:21:49.899287Z","steps":["trace[721170524] 'agreement among raft nodes before linearized reading' (duration: 301.542841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:49.899329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:49.597507Z","time spent":"301.816277ms","remote":"127.0.0.1:44492","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-05T15:21:49.899347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.414007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:21:49.899386Z","caller":"traceutil/trace.go:171","msg":"trace[1362210297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"270.513088ms","start":"2026-02-05T15:21:49.62886Z","end":"2026-02-05T15:21:49.899374Z","steps":["trace[1362210297] 'agreement among raft nodes before linearized reading' (duration: 270.455707ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:52.915421Z","caller":"traceutil/trace.go:171","msg":"trace[1810911205] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"195.70213ms","start":"2026-02-05T15:21:52.719681Z","end":"2026-02-05T15:21:52.915383Z","steps":["trace[1810911205] 'process raft request' (duration: 188.459221ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:52.915427Z","caller":"traceutil/trace.go:171","msg":"trace[251472811] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1081; }","duration":"170.918485ms","start":"2026-02-05T15:21:52.744492Z","end":"2026-02-05T15:21:52.91541Z","steps":["trace[251472811] 'read index received' (duration: 163.817139ms)","trace[251472811] 'applied index is now lower than readState.Index' (duration: 7.100706ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:21:52.916732Z","caller":"traceutil/trace.go:171","msg":"trace[1107312305] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"191.845226ms","start":"2026-02-05T15:21:52.724872Z","end":"2026-02-05T15:21:52.916717Z","steps":["trace[1107312305] 'process raft request' (duration: 190.480165ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:52.917317Z","caller":"traceutil/trace.go:171","msg":"trace[2146146006] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"197.325117ms","start":"2026-02-05T15:21:52.719968Z","end":"2026-02-05T15:21:52.917293Z","steps":["trace[2146146006] 'process raft request' (duration: 195.041416ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:52.917886Z","caller":"traceutil/trace.go:171","msg":"trace[1684065207] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"196.072458ms","start":"2026-02-05T15:21:52.721799Z","end":"2026-02-05T15:21:52.917871Z","steps":["trace[1684065207] 'process raft request' (duration: 193.514112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:52.919503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.497511ms","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-05T15:21:52.919838Z","caller":"traceutil/trace.go:171","msg":"trace[1076067471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1037; }","duration":"133.592583ms","start":"2026-02-05T15:21:52.785961Z","end":"2026-02-05T15:21:52.919553Z","steps":["trace[1076067471] 'agreement among raft nodes before linearized reading' (duration: 133.384508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:52.927004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.488889ms","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-05T15:21:52.9276Z","caller":"traceutil/trace.go:171","msg":"trace[265531038] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1037; }","duration":"183.118543ms","start":"2026-02-05T15:21:52.744469Z","end":"2026-02-05T15:21:52.927587Z","steps":["trace[265531038] 'agreement among raft nodes before linearized reading' (duration: 175.731721ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:25:01.865192Z","caller":"traceutil/trace.go:171","msg":"trace[345391083] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"133.677844ms","start":"2026-02-05T15:25:01.731478Z","end":"2026-02-05T15:25:01.865156Z","steps":["trace[345391083] 'process raft request' (duration: 133.393907ms)"],"step_count":1}