{"level":"warn","ts":"2026-04-17T05:57:23.025014Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T05:57:23.025115Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-04-17T05:57:23.025195Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T05:57:23.025212Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-04-17T05:57:23.025235Z","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-04-17T05:57:23.025877Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-04-17T05:57:23.02595Z","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":16,"max-cpu-available":16,"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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-04-17T05:57:23.031833Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.667693ms"} {"level":"info","ts":"2026-04-17T05:57:23.041527Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-04-17T05:57:23.041615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T05:57:23.041636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-04-17T05:57:23.041646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T05:57:23.041655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-04-17T05:57:23.041695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-04-17T05:57:23.052004Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T05:57:23.05359Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T05:57:23.054983Z","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-04-17T05:57:23.058614Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T05:57:23.062667Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T05:57:23.064574Z","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-04-17T05:57:23.065073Z","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-04-17T05:57:23.065097Z","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-04-17T05:57:23.065506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-04-17T05:57:23.065614Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-04-17T05:57:23.066745Z","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-04-17T05:57:23.066887Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-04-17T05:57:23.066948Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-04-17T05:57:23.067055Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T05:57:23.067113Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T05:57:24.043016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T05:57:24.043073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T05:57:24.043098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-04-17T05:57:24.043139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-04-17T05:57:24.043165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-04-17T05:57:24.043193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-04-17T05:57:24.043213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-04-17T05:57:24.044336Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T05:57:24.045525Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T05:57:24.045678Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T05:57:24.045739Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T05:57:24.047275Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T05:57:24.047397Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T05:57:24.047448Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T05:57:24.049302Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T05:57:24.049897Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-04-17T05:57:24.057643Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T05:57:24.057685Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-04-17T05:57:29.056495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.330804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-17T05:57:29.056587Z","caller":"traceutil/trace.go:171","msg":"trace[382070834] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:251; }","duration":"108.449951ms","start":"2026-04-17T05:57:28.948118Z","end":"2026-04-17T05:57:29.056568Z","steps":["trace[382070834] 'range keys from in-memory index tree' (duration: 108.241617ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:39.810414Z","caller":"traceutil/trace.go:171","msg":"trace[1166394589] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"121.517344ms","start":"2026-04-17T05:57:39.688865Z","end":"2026-04-17T05:57:39.810382Z","steps":["trace[1166394589] 'process raft request' (duration: 121.314489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:40.075335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.779686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:57:40.075451Z","caller":"traceutil/trace.go:171","msg":"trace[1252760009] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:415; }","duration":"246.483506ms","start":"2026-04-17T05:57:39.82895Z","end":"2026-04-17T05:57:40.075433Z","steps":["trace[1252760009] 'read index received' (duration: 89.57948ms)","trace[1252760009] 'applied index is now lower than readState.Index' (duration: 156.902546ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:57:40.075515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.575898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:57:40.075548Z","caller":"traceutil/trace.go:171","msg":"trace[293550751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:402; }","duration":"246.662741ms","start":"2026-04-17T05:57:39.828876Z","end":"2026-04-17T05:57:40.075538Z","steps":["trace[293550751] 'agreement among raft nodes before linearized reading' (duration: 246.60789ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:40.07556Z","caller":"traceutil/trace.go:171","msg":"trace[1653291450] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"260.844805ms","start":"2026-04-17T05:57:39.814651Z","end":"2026-04-17T05:57:40.075495Z","steps":["trace[1653291450] 'process raft request' (duration: 103.98591ms)","trace[1653291450] 'compare' (duration: 155.594921ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:57:50.688223Z","caller":"traceutil/trace.go:171","msg":"trace[2084239428] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"127.332264ms","start":"2026-04-17T05:57:50.560866Z","end":"2026-04-17T05:57:50.688199Z","steps":["trace[2084239428] 'process raft request' (duration: 123.538321ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:55.321167Z","caller":"traceutil/trace.go:171","msg":"trace[208065393] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"145.889057ms","start":"2026-04-17T05:57:55.175252Z","end":"2026-04-17T05:57:55.321141Z","steps":["trace[208065393] 'process raft request' (duration: 140.736737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:55.626721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.409157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:57:55.626827Z","caller":"traceutil/trace.go:171","msg":"trace[454281591] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"241.007108ms","start":"2026-04-17T05:57:55.385803Z","end":"2026-04-17T05:57:55.62681Z","steps":["trace[454281591] 'process raft request' (duration: 51.436216ms)","trace[454281591] 'compare' (duration: 189.289334ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:34.895357Z","caller":"traceutil/trace.go:171","msg":"trace[1709205715] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"241.259145ms","start":"2026-04-17T05:58:34.654074Z","end":"2026-04-17T05:58:34.895333Z","steps":["trace[1709205715] 'process raft request' (duration: 240.935696ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:40.370612Z","caller":"traceutil/trace.go:171","msg":"trace[374998507] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"232.681488ms","start":"2026-04-17T05:58:40.137891Z","end":"2026-04-17T05:58:40.370572Z","steps":["trace[374998507] 'process raft request' (duration: 150.925021ms)","trace[374998507] 'compare' (duration: 81.483139ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:58:40.3707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.579601ms","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-04-17T05:58:40.370754Z","caller":"traceutil/trace.go:171","msg":"trace[540308747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:917; }","duration":"261.664193ms","start":"2026-04-17T05:58:40.109075Z","end":"2026-04-17T05:58:40.370739Z","steps":["trace[540308747] 'range keys from in-memory index tree' (duration: 261.238061ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:47.210045Z","caller":"traceutil/trace.go:171","msg":"trace[923305652] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"107.658515ms","start":"2026-04-17T05:58:47.102347Z","end":"2026-04-17T05:58:47.210006Z","steps":["trace[923305652] 'process raft request' (duration: 67.61623ms)","trace[923305652] 'compare' (duration: 39.86499ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:49.612117Z","caller":"traceutil/trace.go:171","msg":"trace[577626932] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"138.273026ms","start":"2026-04-17T05:58:49.473806Z","end":"2026-04-17T05:58:49.612079Z","steps":["trace[577626932] 'read index received' (duration: 137.968987ms)","trace[577626932] 'applied index is now lower than readState.Index' (duration: 302.439µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:49.612161Z","caller":"traceutil/trace.go:171","msg":"trace[1901278085] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"164.859493ms","start":"2026-04-17T05:58:49.447263Z","end":"2026-04-17T05:58:49.612122Z","steps":["trace[1901278085] 'process raft request' (duration: 164.555134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:49.61242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.593595ms","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-04-17T05:58:49.612536Z","caller":"traceutil/trace.go:171","msg":"trace[1300416884] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:952; }","duration":"138.753239ms","start":"2026-04-17T05:58:49.473763Z","end":"2026-04-17T05:58:49.612516Z","steps":["trace[1300416884] 'agreement among raft nodes before linearized reading' (duration: 138.452041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:51.004089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.75358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:58:51.004169Z","caller":"traceutil/trace.go:171","msg":"trace[232388657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"172.875404ms","start":"2026-04-17T05:58:50.831278Z","end":"2026-04-17T05:58:51.004154Z","steps":["trace[232388657] 'range keys from in-memory index tree' (duration: 172.653677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:51.004186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.123332ms","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-04-17T05:58:51.004254Z","caller":"traceutil/trace.go:171","msg":"trace[127383843] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:957; }","duration":"104.258696ms","start":"2026-04-17T05:58:50.89998Z","end":"2026-04-17T05:58:51.004239Z","steps":["trace[127383843] 'count revisions from in-memory index tree' (duration: 104.06078ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:52.641566Z","caller":"traceutil/trace.go:171","msg":"trace[1845483155] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"126.833907ms","start":"2026-04-17T05:58:52.514708Z","end":"2026-04-17T05:58:52.641542Z","steps":["trace[1845483155] 'process raft request' (duration: 126.681493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:53.081881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.569162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T05:58:53.081917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.405468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T05:58:53.081965Z","caller":"traceutil/trace.go:171","msg":"trace[133662054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"252.742217ms","start":"2026-04-17T05:58:52.8292Z","end":"2026-04-17T05:58:53.081943Z","steps":["trace[133662054] 'range keys from in-memory index tree' (duration: 252.453609ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:53.08202Z","caller":"traceutil/trace.go:171","msg":"trace[508011039] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:961; }","duration":"106.635454ms","start":"2026-04-17T05:58:52.97536Z","end":"2026-04-17T05:58:53.081996Z","steps":["trace[508011039] 'count revisions from in-memory index tree' (duration: 106.294674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:53.082247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.148484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-17T05:58:53.082268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.911813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T05:58:53.082328Z","caller":"traceutil/trace.go:171","msg":"trace[1707053761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:961; }","duration":"368.261476ms","start":"2026-04-17T05:58:52.714042Z","end":"2026-04-17T05:58:53.082303Z","steps":["trace[1707053761] 'range keys from in-memory index tree' (duration: 367.956268ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:53.082438Z","caller":"traceutil/trace.go:171","msg":"trace[1369058163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:961; }","duration":"311.138479ms","start":"2026-04-17T05:58:52.771253Z","end":"2026-04-17T05:58:53.082392Z","steps":["trace[1369058163] 'range keys from in-memory index tree' (duration: 310.645006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:53.08239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:52.714026Z","time spent":"368.354419ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T05:58:53.082511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:52.771237Z","time spent":"311.255443ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T05:58:53.387593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.676676ms","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-04-17T05:58:53.387725Z","caller":"traceutil/trace.go:171","msg":"trace[137383578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:963; }","duration":"191.900302ms","start":"2026-04-17T05:58:53.195796Z","end":"2026-04-17T05:58:53.387697Z","steps":["trace[137383578] 'range keys from in-memory index tree' (duration: 191.398198ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:53.867213Z","caller":"traceutil/trace.go:171","msg":"trace[658596858] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"154.915965ms","start":"2026-04-17T05:58:53.712241Z","end":"2026-04-17T05:58:53.867157Z","steps":["trace[658596858] 'process raft request' (duration: 154.673379ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:00.905403Z","caller":"traceutil/trace.go:171","msg":"trace[1119429821] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"100.536478ms","start":"2026-04-17T05:59:00.804829Z","end":"2026-04-17T05:59:00.905365Z","steps":["trace[1119429821] 'process raft request' (duration: 100.291691ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:07.545117Z","caller":"traceutil/trace.go:171","msg":"trace[137294708] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"106.303075ms","start":"2026-04-17T05:59:07.438796Z","end":"2026-04-17T05:59:07.545099Z","steps":["trace[137294708] 'process raft request' (duration: 106.215173ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:07.545276Z","caller":"traceutil/trace.go:171","msg":"trace[1488732585] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1050; }","duration":"100.496257ms","start":"2026-04-17T05:59:07.444745Z","end":"2026-04-17T05:59:07.545242Z","steps":["trace[1488732585] 'read index received' (duration: 100.485127ms)","trace[1488732585] 'applied index is now lower than readState.Index' (duration: 8.94µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:59:07.545592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.858248ms","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-04-17T05:59:07.545623Z","caller":"traceutil/trace.go:171","msg":"trace[2134026205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"100.93815ms","start":"2026-04-17T05:59:07.444678Z","end":"2026-04-17T05:59:07.545616Z","steps":["trace[2134026205] 'agreement among raft nodes before linearized reading' (duration: 100.699933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:08.017231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.258681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:59:08.017607Z","caller":"traceutil/trace.go:171","msg":"trace[1022776375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"185.400135ms","start":"2026-04-17T05:59:07.831902Z","end":"2026-04-17T05:59:08.017302Z","steps":["trace[1022776375] 'range keys from in-memory index tree' (duration: 185.176899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:10.056787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.299568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:59:10.056868Z","caller":"traceutil/trace.go:171","msg":"trace[1368761617] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"228.538279ms","start":"2026-04-17T05:59:09.828317Z","end":"2026-04-17T05:59:10.056856Z","steps":["trace[1368761617] 'read index received' (duration: 40.131µs)","trace[1368761617] 'applied index is now lower than readState.Index' (duration: 228.497268ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:59:10.056909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.59285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:59:10.056928Z","caller":"traceutil/trace.go:171","msg":"trace[754484405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"228.643611ms","start":"2026-04-17T05:59:09.828277Z","end":"2026-04-17T05:59:10.056921Z","steps":["trace[754484405] 'agreement among raft nodes before linearized reading' (duration: 228.612371ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:10.05702Z","caller":"traceutil/trace.go:171","msg":"trace[422284116] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"387.200972ms","start":"2026-04-17T05:59:09.669737Z","end":"2026-04-17T05:59:10.056938Z","steps":["trace[422284116] 'process raft request' (duration: 112.672237ms)","trace[422284116] 'compare' (duration: 274.217516ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:59:10.057279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:59:09.669712Z","time spent":"387.422499ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:59:10.349382Z","caller":"traceutil/trace.go:171","msg":"trace[1996488294] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"113.700188ms","start":"2026-04-17T05:59:10.235666Z","end":"2026-04-17T05:59:10.349366Z","steps":["trace[1996488294] 'process raft request' (duration: 113.607825ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:13.995859Z","caller":"traceutil/trace.go:171","msg":"trace[762713675] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1071; }","duration":"165.688057ms","start":"2026-04-17T05:59:13.830154Z","end":"2026-04-17T05:59:13.995843Z","steps":["trace[762713675] 'read index received' (duration: 165.518822ms)","trace[762713675] 'applied index is now lower than readState.Index' (duration: 168.635µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:59:13.99591Z","caller":"traceutil/trace.go:171","msg":"trace[38502993] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"292.613206ms","start":"2026-04-17T05:59:13.70327Z","end":"2026-04-17T05:59:13.995883Z","steps":["trace[38502993] 'process raft request' (duration: 292.42055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:13.995947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.781579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:59:13.995967Z","caller":"traceutil/trace.go:171","msg":"trace[351008691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1030; }","duration":"165.866712ms","start":"2026-04-17T05:59:13.830095Z","end":"2026-04-17T05:59:13.995962Z","steps":["trace[351008691] 'agreement among raft nodes before linearized reading' (duration: 165.8139ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:18.337109Z","caller":"traceutil/trace.go:171","msg":"trace[367261936] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"102.931368ms","start":"2026-04-17T05:59:18.234143Z","end":"2026-04-17T05:59:18.337074Z","steps":["trace[367261936] 'read index received' (duration: 60.981859ms)","trace[367261936] 'applied index is now lower than readState.Index' (duration: 41.947929ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:59:18.33722Z","caller":"traceutil/trace.go:171","msg":"trace[1854203994] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"167.549571ms","start":"2026-04-17T05:59:18.169628Z","end":"2026-04-17T05:59:18.337178Z","steps":["trace[1854203994] 'process raft request' (duration: 125.52289ms)","trace[1854203994] 'compare' (duration: 41.768654ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:59:18.337459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.289429ms","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-04-17T05:59:18.337576Z","caller":"traceutil/trace.go:171","msg":"trace[1326604705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1044; }","duration":"103.472703ms","start":"2026-04-17T05:59:18.234074Z","end":"2026-04-17T05:59:18.337547Z","steps":["trace[1326604705] 'agreement among raft nodes before linearized reading' (duration: 103.128394ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:20.531885Z","caller":"traceutil/trace.go:171","msg":"trace[332892707] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"128.548286ms","start":"2026-04-17T05:59:20.403311Z","end":"2026-04-17T05:59:20.531859Z","steps":["trace[332892707] 'process raft request' (duration: 128.398061ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:20.71926Z","caller":"traceutil/trace.go:171","msg":"trace[1458553696] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"170.894487ms","start":"2026-04-17T05:59:20.548333Z","end":"2026-04-17T05:59:20.719228Z","steps":["trace[1458553696] 'process raft request' (duration: 163.053162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:22.93021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.911132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:59:22.930322Z","caller":"traceutil/trace.go:171","msg":"trace[329772185] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"205.515505ms","start":"2026-04-17T05:59:22.724788Z","end":"2026-04-17T05:59:22.930303Z","steps":["trace[329772185] 'read index received' (duration: 57.602µs)","trace[329772185] 'applied index is now lower than readState.Index' (duration: 205.456333ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:59:22.93039Z","caller":"traceutil/trace.go:171","msg":"trace[1574142462] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"429.819242ms","start":"2026-04-17T05:59:22.50051Z","end":"2026-04-17T05:59:22.93033Z","steps":["trace[1574142462] 'process raft request' (duration: 143.705434ms)","trace[1574142462] 'compare' (duration: 285.634984ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:59:22.930735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.936878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-17T05:59:22.930823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:59:22.500491Z","time spent":"430.218083ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:59:22.930832Z","caller":"traceutil/trace.go:171","msg":"trace[1719171720] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1059; }","duration":"206.126733ms","start":"2026-04-17T05:59:22.724686Z","end":"2026-04-17T05:59:22.930812Z","steps":["trace[1719171720] 'agreement among raft nodes before linearized reading' (duration: 205.703071ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:25.121895Z","caller":"traceutil/trace.go:171","msg":"trace[1350930682] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"173.323904ms","start":"2026-04-17T05:59:24.948535Z","end":"2026-04-17T05:59:25.121859Z","steps":["trace[1350930682] 'read index received' (duration: 172.959814ms)","trace[1350930682] 'applied index is now lower than readState.Index' (duration: 362.52µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:59:25.12196Z","caller":"traceutil/trace.go:171","msg":"trace[323511024] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"181.099438ms","start":"2026-04-17T05:59:24.940825Z","end":"2026-04-17T05:59:25.121925Z","steps":["trace[323511024] 'process raft request' (duration: 180.82146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:25.122175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.595702ms","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-04-17T05:59:25.122227Z","caller":"traceutil/trace.go:171","msg":"trace[1584075745] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1065; }","duration":"173.699274ms","start":"2026-04-17T05:59:24.948515Z","end":"2026-04-17T05:59:25.122214Z","steps":["trace[1584075745] 'agreement among raft nodes before linearized reading' (duration: 173.472608ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:30.389781Z","caller":"traceutil/trace.go:171","msg":"trace[792632144] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1126; }","duration":"183.814725ms","start":"2026-04-17T05:59:30.205944Z","end":"2026-04-17T05:59:30.389759Z","steps":["trace[792632144] 'read index received' (duration: 183.620089ms)","trace[792632144] 'applied index is now lower than readState.Index' (duration: 193.726µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:59:30.389895Z","caller":"traceutil/trace.go:171","msg":"trace[1984054740] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"423.443144ms","start":"2026-04-17T05:59:29.966442Z","end":"2026-04-17T05:59:30.389886Z","steps":["trace[1984054740] 'process raft request' (duration: 423.170405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:30.389993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:59:29.966417Z","time spent":"423.504796ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:59:30.390076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.083696ms","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-04-17T05:59:30.390189Z","caller":"traceutil/trace.go:171","msg":"trace[1044868323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1082; }","duration":"101.20798ms","start":"2026-04-17T05:59:30.288926Z","end":"2026-04-17T05:59:30.390134Z","steps":["trace[1044868323] 'agreement among raft nodes before linearized reading' (duration: 101.021225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:30.390225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.276198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:59:30.390264Z","caller":"traceutil/trace.go:171","msg":"trace[1567199673] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1082; }","duration":"184.36104ms","start":"2026-04-17T05:59:30.205893Z","end":"2026-04-17T05:59:30.390254Z","steps":["trace[1567199673] 'agreement among raft nodes before linearized reading' (duration: 184.280538ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:31.600319Z","caller":"traceutil/trace.go:171","msg":"trace[1134467792] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"245.040036ms","start":"2026-04-17T05:59:31.355255Z","end":"2026-04-17T05:59:31.600295Z","steps":["trace[1134467792] 'process raft request' (duration: 244.893621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:41.912049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.306317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:59:41.912219Z","caller":"traceutil/trace.go:171","msg":"trace[1639731986] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"215.514186ms","start":"2026-04-17T05:59:41.696661Z","end":"2026-04-17T05:59:41.912175Z","steps":["trace[1639731986] 'process raft request' (duration: 61.977072ms)","trace[1639731986] 'compare' (duration: 153.146672ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:59:46.784161Z","caller":"traceutil/trace.go:171","msg":"trace[1513062403] linearizableReadLoop","detail":"{readStateIndex:1183; appliedIndex:1182; }","duration":"137.890514ms","start":"2026-04-17T05:59:46.646244Z","end":"2026-04-17T05:59:46.784135Z","steps":["trace[1513062403] 'read index received' (duration: 137.666778ms)","trace[1513062403] 'applied index is now lower than readState.Index' (duration: 222.656µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:59:46.78425Z","caller":"traceutil/trace.go:171","msg":"trace[63119654] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"150.699533ms","start":"2026-04-17T05:59:46.633505Z","end":"2026-04-17T05:59:46.784205Z","steps":["trace[63119654] 'process raft request' (duration: 150.486927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:46.784326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.057369ms","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-04-17T05:59:46.78436Z","caller":"traceutil/trace.go:171","msg":"trace[183098028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1135; }","duration":"138.12179ms","start":"2026-04-17T05:59:46.646228Z","end":"2026-04-17T05:59:46.78435Z","steps":["trace[183098028] 'agreement among raft nodes before linearized reading' (duration: 137.996697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:46.993866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.655045ms","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-04-17T05:59:46.993934Z","caller":"traceutil/trace.go:171","msg":"trace[738756718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1136; }","duration":"139.779379ms","start":"2026-04-17T05:59:46.85414Z","end":"2026-04-17T05:59:46.993919Z","steps":["trace[738756718] 'range keys from in-memory index tree' (duration: 139.487671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:47.396239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.713406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:59:47.396501Z","caller":"traceutil/trace.go:171","msg":"trace[1839767878] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"399.190745ms","start":"2026-04-17T05:59:46.997274Z","end":"2026-04-17T05:59:47.396465Z","steps":["trace[1839767878] 'process raft request' (duration: 163.131709ms)","trace[1839767878] 'compare' (duration: 235.4849ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:59:47.396682Z","caller":"traceutil/trace.go:171","msg":"trace[1802774123] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"340.251021ms","start":"2026-04-17T05:59:47.056405Z","end":"2026-04-17T05:59:47.396656Z","steps":["trace[1802774123] 'process raft request' (duration: 340.018374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:47.396791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:59:46.99726Z","time spent":"399.314659ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:59:47.396845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:59:47.056383Z","time spent":"340.386305ms","remote":"127.0.0.1:51670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:59:47.766616Z","caller":"traceutil/trace.go:171","msg":"trace[1363422806] linearizableReadLoop","detail":"{readStateIndex:1188; appliedIndex:1187; }","duration":"143.973609ms","start":"2026-04-17T05:59:47.622615Z","end":"2026-04-17T05:59:47.766589Z","steps":["trace[1363422806] 'read index received' (duration: 79.614309ms)","trace[1363422806] 'applied index is now lower than readState.Index' (duration: 64.35841ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:59:47.766857Z","caller":"traceutil/trace.go:171","msg":"trace[1539301228] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"318.30931ms","start":"2026-04-17T05:59:47.448364Z","end":"2026-04-17T05:59:47.766674Z","steps":["trace[1539301228] 'process raft request' (duration: 253.982532ms)","trace[1539301228] 'compare' (duration: 64.128503ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:59:47.766924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.289108ms","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-04-17T05:59:47.767012Z","caller":"traceutil/trace.go:171","msg":"trace[2144795369] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1139; }","duration":"144.397491ms","start":"2026-04-17T05:59:47.622595Z","end":"2026-04-17T05:59:47.766992Z","steps":["trace[2144795369] 'agreement among raft nodes before linearized reading' (duration: 144.103092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:47.767081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:59:47.448353Z","time spent":"318.574919ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:59:47.924203Z","caller":"traceutil/trace.go:171","msg":"trace[348501118] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"152.978368ms","start":"2026-04-17T05:59:47.771183Z","end":"2026-04-17T05:59:47.924162Z","steps":["trace[348501118] 'process raft request' (duration: 123.395947ms)","trace[348501118] 'compare' (duration: 29.351365ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:59:49.509155Z","caller":"traceutil/trace.go:171","msg":"trace[728900599] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"104.846384ms","start":"2026-04-17T05:59:49.404275Z","end":"2026-04-17T05:59:49.509121Z","steps":["trace[728900599] 'process raft request' (duration: 25.05008ms)","trace[728900599] 'compare' (duration: 79.66208ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:59:50.122798Z","caller":"traceutil/trace.go:171","msg":"trace[850628228] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"189.374564ms","start":"2026-04-17T05:59:49.93339Z","end":"2026-04-17T05:59:50.122765Z","steps":["trace[850628228] 'process raft request' (duration: 189.181777ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:50.305457Z","caller":"traceutil/trace.go:171","msg":"trace[1543463829] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"219.192612ms","start":"2026-04-17T05:59:50.086244Z","end":"2026-04-17T05:59:50.305436Z","steps":["trace[1543463829] 'process raft request' (duration: 138.325047ms)","trace[1543463829] 'compare' (duration: 80.764292ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:59:50.585749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.391592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:59:50.585919Z","caller":"traceutil/trace.go:171","msg":"trace[380842183] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1148; }","duration":"103.67798ms","start":"2026-04-17T05:59:50.482196Z","end":"2026-04-17T05:59:50.585874Z","steps":["trace[380842183] 'count revisions from in-memory index tree' (duration: 103.269849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:52.572483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.34096ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-17T05:59:52.573064Z","caller":"traceutil/trace.go:171","msg":"trace[41144172] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"261.837417ms","start":"2026-04-17T05:59:52.311212Z","end":"2026-04-17T05:59:52.57305Z","steps":["trace[41144172] 'process raft request' (duration: 261.744394ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:53.731315Z","caller":"traceutil/trace.go:171","msg":"trace[996425712] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"118.533909ms","start":"2026-04-17T05:59:53.612759Z","end":"2026-04-17T05:59:53.731293Z","steps":["trace[996425712] 'process raft request' (duration: 118.392525ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:55.045669Z","caller":"traceutil/trace.go:171","msg":"trace[1449201352] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"103.922557ms","start":"2026-04-17T05:59:54.941717Z","end":"2026-04-17T05:59:55.045639Z","steps":["trace[1449201352] 'process raft request' (duration: 103.696971ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:59.392532Z","caller":"traceutil/trace.go:171","msg":"trace[860863184] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"138.327826ms","start":"2026-04-17T05:59:59.254178Z","end":"2026-04-17T05:59:59.392506Z","steps":["trace[860863184] 'process raft request' (duration: 138.159681ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:59.932499Z","caller":"traceutil/trace.go:171","msg":"trace[1107866866] linearizableReadLoop","detail":"{readStateIndex:1229; appliedIndex:1228; }","duration":"100.850249ms","start":"2026-04-17T05:59:59.831625Z","end":"2026-04-17T05:59:59.932475Z","steps":["trace[1107866866] 'read index received' (duration: 100.674354ms)","trace[1107866866] 'applied index is now lower than readState.Index' (duration: 174.515µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:59:59.932631Z","caller":"traceutil/trace.go:171","msg":"trace[553852808] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"117.109987ms","start":"2026-04-17T05:59:59.815483Z","end":"2026-04-17T05:59:59.932593Z","steps":["trace[553852808] 'process raft request' (duration: 116.821469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:59.932646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.994563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:59:59.932716Z","caller":"traceutil/trace.go:171","msg":"trace[748072721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1178; }","duration":"101.117067ms","start":"2026-04-17T05:59:59.831586Z","end":"2026-04-17T05:59:59.932703Z","steps":["trace[748072721] 'agreement among raft nodes before linearized reading' (duration: 100.995683ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:00.206717Z","caller":"traceutil/trace.go:171","msg":"trace[1050027900] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"156.51023ms","start":"2026-04-17T06:00:00.05015Z","end":"2026-04-17T06:00:00.20666Z","steps":["trace[1050027900] 'process raft request' (duration: 156.381266ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:00.797753Z","caller":"traceutil/trace.go:171","msg":"trace[189561904] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"162.691687ms","start":"2026-04-17T06:00:00.635035Z","end":"2026-04-17T06:00:00.797726Z","steps":["trace[189561904] 'process raft request' (duration: 162.526912ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:01.302664Z","caller":"traceutil/trace.go:171","msg":"trace[1328033180] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"105.037489ms","start":"2026-04-17T06:00:01.197599Z","end":"2026-04-17T06:00:01.302637Z","steps":["trace[1328033180] 'process raft request' (duration: 104.870894ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:01.55419Z","caller":"traceutil/trace.go:171","msg":"trace[1595221378] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1233; }","duration":"157.92859ms","start":"2026-04-17T06:00:01.396235Z","end":"2026-04-17T06:00:01.554164Z","steps":["trace[1595221378] 'read index received' (duration: 122.060579ms)","trace[1595221378] 'applied index is now lower than readState.Index' (duration: 35.867091ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:00:01.554296Z","caller":"traceutil/trace.go:171","msg":"trace[997914188] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"191.929517ms","start":"2026-04-17T06:00:01.36231Z","end":"2026-04-17T06:00:01.55424Z","steps":["trace[997914188] 'process raft request' (duration: 156.076236ms)","trace[997914188] 'compare' (duration: 35.589534ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:01.554415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.154586ms","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-04-17T06:00:01.554672Z","caller":"traceutil/trace.go:171","msg":"trace[809848416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1183; }","duration":"158.446205ms","start":"2026-04-17T06:00:01.396197Z","end":"2026-04-17T06:00:01.554644Z","steps":["trace[809848416] 'agreement among raft nodes before linearized reading' (duration: 158.062044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:02.040793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.930078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:00:02.04092Z","caller":"traceutil/trace.go:171","msg":"trace[560576933] linearizableReadLoop","detail":"{readStateIndex:1235; appliedIndex:1234; }","duration":"401.978266ms","start":"2026-04-17T06:00:01.638923Z","end":"2026-04-17T06:00:02.040901Z","steps":["trace[560576933] 'read index received' (duration: 98.714398ms)","trace[560576933] 'applied index is now lower than readState.Index' (duration: 303.262298ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:00:02.040972Z","caller":"traceutil/trace.go:171","msg":"trace[1334998400] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"482.241894ms","start":"2026-04-17T06:00:01.558689Z","end":"2026-04-17T06:00:02.040931Z","steps":["trace[1334998400] 'process raft request' (duration: 179.044588ms)","trace[1334998400] 'compare' (duration: 302.28631ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:02.041101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.177682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T06:00:02.041149Z","caller":"traceutil/trace.go:171","msg":"trace[1610124526] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1184; }","duration":"402.291106ms","start":"2026-04-17T06:00:01.638847Z","end":"2026-04-17T06:00:02.041139Z","steps":["trace[1610124526] 'agreement among raft nodes before linearized reading' (duration: 402.163372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:02.04118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:01.558664Z","time spent":"482.430329ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:02.041203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:01.638816Z","time spent":"402.377238ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:00:02.04135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.531542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:02.041409Z","caller":"traceutil/trace.go:171","msg":"trace[230889904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1184; }","duration":"210.628925ms","start":"2026-04-17T06:00:01.830766Z","end":"2026-04-17T06:00:02.041394Z","steps":["trace[230889904] 'agreement among raft nodes before linearized reading' (duration: 210.542403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:02.041478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.962627ms","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-04-17T06:00:02.041519Z","caller":"traceutil/trace.go:171","msg":"trace[266471161] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1184; }","duration":"105.036719ms","start":"2026-04-17T06:00:01.936473Z","end":"2026-04-17T06:00:02.04151Z","steps":["trace[266471161] 'agreement among raft nodes before linearized reading' (duration: 104.885085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:02.738698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.211101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:00:02.739083Z","caller":"traceutil/trace.go:171","msg":"trace[1099887261] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"692.243311ms","start":"2026-04-17T06:00:02.046749Z","end":"2026-04-17T06:00:02.738993Z","steps":["trace[1099887261] 'process raft request' (duration: 247.628478ms)","trace[1099887261] 'compare' (duration: 444.046526ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:00:02.739112Z","caller":"traceutil/trace.go:171","msg":"trace[1195365350] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1235; }","duration":"528.015549ms","start":"2026-04-17T06:00:02.21106Z","end":"2026-04-17T06:00:02.739075Z","steps":["trace[1195365350] 'read index received' (duration: 83.329325ms)","trace[1195365350] 'applied index is now lower than readState.Index' (duration: 444.683234ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:02.739379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:02.046716Z","time spent":"692.507848ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:02.990535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.662335ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-17T06:00:02.990721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.637252ms","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-04-17T06:00:02.990821Z","caller":"traceutil/trace.go:171","msg":"trace[2062939782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1185; }","duration":"779.797397ms","start":"2026-04-17T06:00:02.210997Z","end":"2026-04-17T06:00:02.990795Z","steps":["trace[2062939782] 'agreement among raft nodes before linearized reading' (duration: 528.160353ms)","trace[2062939782] 'range keys from in-memory index tree' (duration: 251.381956ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:02.990868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:02.210971Z","time spent":"779.887269ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T06:00:02.990907Z","caller":"traceutil/trace.go:171","msg":"trace[956892896] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"251.521211ms","start":"2026-04-17T06:00:02.7393Z","end":"2026-04-17T06:00:02.990821Z","steps":["trace[956892896] 'read index received' (duration: 71.892µs)","trace[956892896] 'applied index is now lower than readState.Index' (duration: 251.445779ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:02.991078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.856159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-17T06:00:02.991144Z","caller":"traceutil/trace.go:171","msg":"trace[1844087226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1185; }","duration":"539.977843ms","start":"2026-04-17T06:00:02.45114Z","end":"2026-04-17T06:00:02.991118Z","steps":["trace[1844087226] 'agreement among raft nodes before linearized reading' (duration: 539.782748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:02.991196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:02.451115Z","time spent":"540.072816ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T06:00:02.991371Z","caller":"traceutil/trace.go:171","msg":"trace[228775635] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"188.595161ms","start":"2026-04-17T06:00:02.802758Z","end":"2026-04-17T06:00:02.991353Z","steps":["trace[228775635] 'process raft request' (duration: 187.924832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:03.51174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.126578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:00:03.511858Z","caller":"traceutil/trace.go:171","msg":"trace[1566048990] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"655.00911ms","start":"2026-04-17T06:00:02.856824Z","end":"2026-04-17T06:00:03.511833Z","steps":["trace[1566048990] 'process raft request' (duration: 410.690387ms)","trace[1566048990] 'compare' (duration: 243.720506ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:03.511916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:02.85679Z","time spent":"655.099423ms","remote":"127.0.0.1:51812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11315,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:04.386508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.776114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:00:04.386713Z","caller":"traceutil/trace.go:171","msg":"trace[1655256281] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1239; }","duration":"1.107639032s","start":"2026-04-17T06:00:03.279057Z","end":"2026-04-17T06:00:04.386696Z","steps":["trace[1655256281] 'read index received' (duration: 452.57027ms)","trace[1655256281] 'applied index is now lower than readState.Index' (duration: 655.067882ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:00:04.386813Z","caller":"traceutil/trace.go:171","msg":"trace[251748575] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"1.391481881s","start":"2026-04-17T06:00:02.995319Z","end":"2026-04-17T06:00:04.386801Z","steps":["trace[251748575] 'process raft request' (duration: 736.339687ms)","trace[251748575] 'compare' (duration: 654.612139ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:04.386876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:02.99529Z","time spent":"1.391555274s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:04.387256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.699503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-17T06:00:04.387242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.281333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:04.387239Z","caller":"traceutil/trace.go:171","msg":"trace[1611464834] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"1.390857824s","start":"2026-04-17T06:00:02.996336Z","end":"2026-04-17T06:00:04.387194Z","steps":["trace[1611464834] 'process raft request' (duration: 1.390273877s)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:04.387297Z","caller":"traceutil/trace.go:171","msg":"trace[1345532252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1189; }","duration":"341.816936ms","start":"2026-04-17T06:00:04.045469Z","end":"2026-04-17T06:00:04.387286Z","steps":["trace[1345532252] 'agreement among raft nodes before linearized reading' (duration: 341.697043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:04.387303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108227859s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-17T06:00:04.387311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.961314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:04.387382Z","caller":"traceutil/trace.go:171","msg":"trace[1333093336] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1189; }","duration":"1.108355252s","start":"2026-04-17T06:00:03.279003Z","end":"2026-04-17T06:00:04.387359Z","steps":["trace[1333093336] 'agreement among raft nodes before linearized reading' (duration: 1.108214688s)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:04.387394Z","caller":"traceutil/trace.go:171","msg":"trace[1870908899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1189; }","duration":"794.082108ms","start":"2026-04-17T06:00:03.593295Z","end":"2026-04-17T06:00:04.387377Z","steps":["trace[1870908899] 'agreement among raft nodes before linearized reading' (duration: 793.942344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:04.387402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:02.996307Z","time spent":"1.391009978s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:04.387437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:03.278984Z","time spent":"1.108437346s","remote":"127.0.0.1:55920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:00:04.387324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:04.045446Z","time spent":"341.871178ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T06:00:04.387447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:03.593273Z","time spent":"794.156011ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T06:00:04.387419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080751518s","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-04-17T06:00:04.387545Z","caller":"traceutil/trace.go:171","msg":"trace[1878252968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1189; }","duration":"1.080938405s","start":"2026-04-17T06:00:03.306575Z","end":"2026-04-17T06:00:04.387513Z","steps":["trace[1878252968] 'agreement among raft nodes before linearized reading' (duration: 1.080588874s)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:04.387315Z","caller":"traceutil/trace.go:171","msg":"trace[233222423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1189; }","duration":"555.386535ms","start":"2026-04-17T06:00:03.831911Z","end":"2026-04-17T06:00:04.387298Z","steps":["trace[233222423] 'agreement among raft nodes before linearized reading' (duration: 555.263252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:04.387691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:03.83189Z","time spent":"555.786048ms","remote":"127.0.0.1:51624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T06:00:04.387617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:03.306554Z","time spent":"1.081052848s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T06:00:04.923514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.810568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:00:04.923632Z","caller":"traceutil/trace.go:171","msg":"trace[2111535239] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"531.803019ms","start":"2026-04-17T06:00:04.391807Z","end":"2026-04-17T06:00:04.92361Z","steps":["trace[2111535239] 'process raft request' (duration: 302.818016ms)","trace[2111535239] 'compare' (duration: 228.692445ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:04.923693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:04.391784Z","time spent":"531.879331ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:00:04.927404Z","caller":"traceutil/trace.go:171","msg":"trace[1484220069] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1242; }","duration":"211.902921ms","start":"2026-04-17T06:00:04.715479Z","end":"2026-04-17T06:00:04.927382Z","steps":["trace[1484220069] 'read index received' (duration: 211.729706ms)","trace[1484220069] 'applied index is now lower than readState.Index' (duration: 172.345µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:00:04.927448Z","caller":"traceutil/trace.go:171","msg":"trace[1552305554] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"535.402581ms","start":"2026-04-17T06:00:04.392023Z","end":"2026-04-17T06:00:04.927426Z","steps":["trace[1552305554] 'process raft request' (duration: 535.235247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:04.927631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.764762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-17T06:00:04.927649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.114308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:04.927705Z","caller":"traceutil/trace.go:171","msg":"trace[869259553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1191; }","duration":"184.874625ms","start":"2026-04-17T06:00:04.742813Z","end":"2026-04-17T06:00:04.927688Z","steps":["trace[869259553] 'agreement among raft nodes before linearized reading' (duration: 184.70425ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:04.927714Z","caller":"traceutil/trace.go:171","msg":"trace[1664642042] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1191; }","duration":"212.281532ms","start":"2026-04-17T06:00:04.715412Z","end":"2026-04-17T06:00:04.927694Z","steps":["trace[1664642042] 'agreement among raft nodes before linearized reading' (duration: 212.117627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:04.927663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:04.391985Z","time spent":"535.601288ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:00:05.291151Z","caller":"traceutil/trace.go:171","msg":"trace[211601881] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"358.756163ms","start":"2026-04-17T06:00:04.932363Z","end":"2026-04-17T06:00:05.291119Z","steps":["trace[211601881] 'process raft request' (duration: 354.111699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:05.291331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:04.932346Z","time spent":"358.890217ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:00:05.291153Z","caller":"traceutil/trace.go:171","msg":"trace[1679105548] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"295.190725ms","start":"2026-04-17T06:00:04.995937Z","end":"2026-04-17T06:00:05.291128Z","steps":["trace[1679105548] 'process raft request' (duration: 295.040961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:06.010579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.066371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:06.010636Z","caller":"traceutil/trace.go:171","msg":"trace[1139345501] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1193; }","duration":"583.163164ms","start":"2026-04-17T06:00:05.42746Z","end":"2026-04-17T06:00:06.010623Z","steps":["trace[1139345501] 'count revisions from in-memory index tree' (duration: 583.01009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:06.010663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:05.427444Z","time spent":"583.211926ms","remote":"127.0.0.1:51722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-04-17T06:00:06.831217Z","caller":"traceutil/trace.go:171","msg":"trace[1823399468] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"435.402998ms","start":"2026-04-17T06:00:06.395787Z","end":"2026-04-17T06:00:06.83119Z","steps":["trace[1823399468] 'process raft request' (duration: 435.234193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:06.83158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:06.395752Z","time spent":"435.547982ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:00:07.688879Z","caller":"traceutil/trace.go:171","msg":"trace[397456253] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1245; }","duration":"860.58538ms","start":"2026-04-17T06:00:06.828276Z","end":"2026-04-17T06:00:07.688862Z","steps":["trace[397456253] 'read index received' (duration: 2.744949ms)","trace[397456253] 'applied index is now lower than readState.Index' (duration: 857.839651ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:00:07.688981Z","caller":"traceutil/trace.go:171","msg":"trace[1955191327] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"1.292821845s","start":"2026-04-17T06:00:06.396114Z","end":"2026-04-17T06:00:07.688936Z","steps":["trace[1955191327] 'process raft request' (duration: 1.2804633s)","trace[1955191327] 'compare' (duration: 12.032766ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:07.689146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:06.396091Z","time spent":"1.292959089s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:07.689008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.706783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:07.689269Z","caller":"traceutil/trace.go:171","msg":"trace[469470835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1195; }","duration":"861.022122ms","start":"2026-04-17T06:00:06.828236Z","end":"2026-04-17T06:00:07.689258Z","steps":["trace[469470835] 'agreement among raft nodes before linearized reading' (duration: 860.699033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:07.689297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:06.828216Z","time spent":"861.072854ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T06:00:08.985687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.186114ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:08.985813Z","caller":"traceutil/trace.go:171","msg":"trace[787417673] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1195; }","duration":"137.322378ms","start":"2026-04-17T06:00:08.848473Z","end":"2026-04-17T06:00:08.985795Z","steps":["trace[787417673] 'range keys from in-memory index tree' (duration: 137.171893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:08.986287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075719595s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:00:08.98644Z","caller":"traceutil/trace.go:171","msg":"trace[1458955687] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1247; }","duration":"1.29749109s","start":"2026-04-17T06:00:07.688923Z","end":"2026-04-17T06:00:08.986414Z","steps":["trace[1458955687] 'read index received' (duration: 221.455096ms)","trace[1458955687] 'applied index is now lower than readState.Index' (duration: 1.076034024s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:08.986765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001909811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-17T06:00:08.986887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.05373253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-17T06:00:08.986918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.945193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T06:00:08.986974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.059446195s","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-04-17T06:00:08.987043Z","caller":"traceutil/trace.go:171","msg":"trace[1065528473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1196; }","duration":"164.113877ms","start":"2026-04-17T06:00:08.822911Z","end":"2026-04-17T06:00:08.987025Z","steps":["trace[1065528473] 'agreement among raft nodes before linearized reading' (duration: 163.931982ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:08.986802Z","caller":"traceutil/trace.go:171","msg":"trace[2021383943] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"1.689674715s","start":"2026-04-17T06:00:07.297093Z","end":"2026-04-17T06:00:08.986767Z","steps":["trace[2021383943] 'process raft request' (duration: 613.316052ms)","trace[2021383943] 'compare' (duration: 1.075430497s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:08.987064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.993309ms","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-04-17T06:00:08.987115Z","caller":"traceutil/trace.go:171","msg":"trace[1097024478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1196; }","duration":"152.066742ms","start":"2026-04-17T06:00:08.835034Z","end":"2026-04-17T06:00:08.987101Z","steps":["trace[1097024478] 'agreement among raft nodes before linearized reading' (duration: 151.787244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:08.987242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:07.297072Z","time spent":"1.690029015s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:00:08.986985Z","caller":"traceutil/trace.go:171","msg":"trace[1935681270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1196; }","duration":"2.053854194s","start":"2026-04-17T06:00:06.933117Z","end":"2026-04-17T06:00:08.986971Z","steps":["trace[1935681270] 'agreement among raft nodes before linearized reading' (duration: 2.053615957s)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:08.987055Z","caller":"traceutil/trace.go:171","msg":"trace[925994259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1196; }","duration":"2.05961462s","start":"2026-04-17T06:00:06.927423Z","end":"2026-04-17T06:00:08.987038Z","steps":["trace[925994259] 'agreement among raft nodes before linearized reading' (duration: 2.05926165s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:08.987505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:06.927408Z","time spent":"2.060079224s","remote":"127.0.0.1:51920","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-04-17T06:00:08.98679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.726198334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T06:00:08.986879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296892963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:08.98761Z","caller":"traceutil/trace.go:171","msg":"trace[429199793] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1196; }","duration":"1.727029889s","start":"2026-04-17T06:00:07.260536Z","end":"2026-04-17T06:00:08.987566Z","steps":["trace[429199793] 'agreement among raft nodes before linearized reading' (duration: 1.726202004s)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:08.98765Z","caller":"traceutil/trace.go:171","msg":"trace[426759118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1196; }","duration":"1.297724268s","start":"2026-04-17T06:00:07.689917Z","end":"2026-04-17T06:00:08.987641Z","steps":["trace[426759118] 'agreement among raft nodes before linearized reading' (duration: 1.296887143s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:08.98768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:07.689898Z","time spent":"1.297774279s","remote":"127.0.0.1:51624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T06:00:08.987692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:07.260518Z","time spent":"1.727131592s","remote":"127.0.0.1:48058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:00:08.986765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.690710335s","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-04-17T06:00:08.987742Z","caller":"traceutil/trace.go:171","msg":"trace[428608097] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1196; }","duration":"1.691721073s","start":"2026-04-17T06:00:07.296006Z","end":"2026-04-17T06:00:08.987727Z","steps":["trace[428608097] 'agreement among raft nodes before linearized reading' (duration: 1.690651012s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:08.987789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:07.295988Z","time spent":"1.691789196s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T06:00:08.987305Z","caller":"traceutil/trace.go:171","msg":"trace[632263069] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:1196; }","duration":"2.002117066s","start":"2026-04-17T06:00:06.984804Z","end":"2026-04-17T06:00:08.986921Z","steps":["trace[632263069] 'agreement among raft nodes before linearized reading' (duration: 2.001852689s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:08.988231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:06.984787Z","time spent":"2.003364622s","remote":"127.0.0.1:51670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"warn","ts":"2026-04-17T06:00:08.987378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:06.933105Z","time spent":"2.054254176s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T06:00:09.302379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.618177ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-04-17T06:00:09.302499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:08.989605Z","time spent":"312.889635ms","remote":"127.0.0.1:51670","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-17T06:00:09.49069Z","caller":"traceutil/trace.go:171","msg":"trace[1051468826] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"500.292822ms","start":"2026-04-17T06:00:08.990372Z","end":"2026-04-17T06:00:09.490665Z","steps":["trace[1051468826] 'process raft request' (duration: 421.687233ms)","trace[1051468826] 'compare' (duration: 78.407934ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:09.490883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:08.99035Z","time spent":"500.411675ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:00:09.491136Z","caller":"traceutil/trace.go:171","msg":"trace[426489922] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"498.187841ms","start":"2026-04-17T06:00:08.992916Z","end":"2026-04-17T06:00:09.491104Z","steps":["trace[426489922] 'process raft request' (duration: 497.992455ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:09.491128Z","caller":"traceutil/trace.go:171","msg":"trace[227791271] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"499.363685ms","start":"2026-04-17T06:00:08.991728Z","end":"2026-04-17T06:00:09.491092Z","steps":["trace[227791271] 'process raft request' (duration: 498.862701ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:09.491156Z","caller":"traceutil/trace.go:171","msg":"trace[619213523] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"498.116139ms","start":"2026-04-17T06:00:08.99301Z","end":"2026-04-17T06:00:09.491126Z","steps":["trace[619213523] 'process raft request' (duration: 498.025017ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:09.49118Z","caller":"traceutil/trace.go:171","msg":"trace[1159989948] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1248; }","duration":"496.05472ms","start":"2026-04-17T06:00:08.995106Z","end":"2026-04-17T06:00:09.491161Z","steps":["trace[1159989948] 'read index received' (duration: 98.630336ms)","trace[1159989948] 'applied index is now lower than readState.Index' (duration: 397.423084ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:09.491242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:08.992904Z","time spent":"498.283604ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:09.491322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:08.991715Z","time spent":"499.492619ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:09.49135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.241816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:09.491328Z","caller":"traceutil/trace.go:171","msg":"trace[115577063] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"187.648455ms","start":"2026-04-17T06:00:09.303638Z","end":"2026-04-17T06:00:09.491287Z","steps":["trace[115577063] 'process raft request' (duration: 187.46647ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:09.491397Z","caller":"traceutil/trace.go:171","msg":"trace[1024460423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1201; }","duration":"496.340539ms","start":"2026-04-17T06:00:08.995043Z","end":"2026-04-17T06:00:09.491384Z","steps":["trace[1024460423] 'agreement among raft nodes before linearized reading' (duration: 496.231316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:09.49143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:08.995034Z","time spent":"496.38922ms","remote":"127.0.0.1:51624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T06:00:09.491524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:08.99299Z","time spent":"498.226192ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:09.805157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.01185ms","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-04-17T06:00:09.805275Z","caller":"traceutil/trace.go:171","msg":"trace[1928390484] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1201; }","duration":"112.212715ms","start":"2026-04-17T06:00:09.693035Z","end":"2026-04-17T06:00:09.805248Z","steps":["trace[1928390484] 'range keys from in-memory index tree' (duration: 111.786784ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:10.034568Z","caller":"traceutil/trace.go:171","msg":"trace[1525544563] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1254; }","duration":"203.958903ms","start":"2026-04-17T06:00:09.830584Z","end":"2026-04-17T06:00:10.034542Z","steps":["trace[1525544563] 'read index received' (duration: 203.905761ms)","trace[1525544563] 'applied index is now lower than readState.Index' (duration: 52.142µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:00:10.034719Z","caller":"traceutil/trace.go:171","msg":"trace[1582695731] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"224.35382ms","start":"2026-04-17T06:00:09.810331Z","end":"2026-04-17T06:00:10.034685Z","steps":["trace[1582695731] 'process raft request' (duration: 224.053901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:10.034779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.175789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:10.034813Z","caller":"traceutil/trace.go:171","msg":"trace[1328572154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1202; }","duration":"204.271442ms","start":"2026-04-17T06:00:09.830531Z","end":"2026-04-17T06:00:10.034803Z","steps":["trace[1328572154] 'agreement among raft nodes before linearized reading' (duration: 204.167729ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:10.307385Z","caller":"traceutil/trace.go:171","msg":"trace[1946224386] linearizableReadLoop","detail":"{readStateIndex:1256; appliedIndex:1255; }","duration":"272.715511ms","start":"2026-04-17T06:00:10.034638Z","end":"2026-04-17T06:00:10.307354Z","steps":["trace[1946224386] 'read index received' (duration: 217.434771ms)","trace[1946224386] 'applied index is now lower than readState.Index' (duration: 55.27904ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:00:10.307394Z","caller":"traceutil/trace.go:171","msg":"trace[896684113] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"343.955148ms","start":"2026-04-17T06:00:09.963405Z","end":"2026-04-17T06:00:10.30736Z","steps":["trace[896684113] 'process raft request' (duration: 288.73457ms)","trace[896684113] 'compare' (duration: 54.981291ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:10.307497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.09446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:10.307562Z","caller":"traceutil/trace.go:171","msg":"trace[1111883100] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1203; }","duration":"465.236964ms","start":"2026-04-17T06:00:09.842306Z","end":"2026-04-17T06:00:10.307543Z","steps":["trace[1111883100] 'agreement among raft nodes before linearized reading' (duration: 465.10494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:10.307596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:09.842296Z","time spent":"465.291156ms","remote":"127.0.0.1:48046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:00:10.307631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:09.963375Z","time spent":"344.166074ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:10.308117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.70001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:10.30817Z","caller":"traceutil/trace.go:171","msg":"trace[1778211740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1203; }","duration":"272.34861ms","start":"2026-04-17T06:00:10.035809Z","end":"2026-04-17T06:00:10.308158Z","steps":["trace[1778211740] 'agreement among raft nodes before linearized reading' (duration: 271.704441ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:11.917148Z","caller":"traceutil/trace.go:171","msg":"trace[2146851272] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"417.846473ms","start":"2026-04-17T06:00:11.499263Z","end":"2026-04-17T06:00:11.91711Z","steps":["trace[2146851272] 'process raft request' (duration: 417.567695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:11.91739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:11.499244Z","time spent":"418.014237ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:00:11.917586Z","caller":"traceutil/trace.go:171","msg":"trace[721554330] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"418.262865ms","start":"2026-04-17T06:00:11.499287Z","end":"2026-04-17T06:00:11.91755Z","steps":["trace[721554330] 'process raft request' (duration: 417.76103ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:11.917619Z","caller":"traceutil/trace.go:171","msg":"trace[611384991] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"418.277105ms","start":"2026-04-17T06:00:11.49931Z","end":"2026-04-17T06:00:11.917587Z","steps":["trace[611384991] 'process raft request' (duration: 418.12667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:11.917761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:11.499259Z","time spent":"418.404799ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:11.917808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:11.49929Z","time spent":"418.42089ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:12.226305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.281023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:00:12.226435Z","caller":"traceutil/trace.go:171","msg":"trace[41780827] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1258; }","duration":"395.17543ms","start":"2026-04-17T06:00:11.831211Z","end":"2026-04-17T06:00:12.226387Z","steps":["trace[41780827] 'read index received' (duration: 85.632781ms)","trace[41780827] 'applied index is now lower than readState.Index' (duration: 309.541699ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:00:12.226496Z","caller":"traceutil/trace.go:171","msg":"trace[1854553886] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"602.093719ms","start":"2026-04-17T06:00:11.624369Z","end":"2026-04-17T06:00:12.226463Z","steps":["trace[1854553886] 'process raft request' (duration: 333.58226ms)","trace[1854553886] 'compare' (duration: 268.159929ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:12.226545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.326015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T06:00:12.226596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.340586ms","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-04-17T06:00:12.226622Z","caller":"traceutil/trace.go:171","msg":"trace[42536144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1209; }","duration":"395.471239ms","start":"2026-04-17T06:00:11.831133Z","end":"2026-04-17T06:00:12.226604Z","steps":["trace[42536144] 'agreement among raft nodes before linearized reading' (duration: 395.355936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:12.226798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:11.831102Z","time spent":"395.684725ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T06:00:12.226632Z","caller":"traceutil/trace.go:171","msg":"trace[735521841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1209; }","duration":"187.413918ms","start":"2026-04-17T06:00:12.039211Z","end":"2026-04-17T06:00:12.226625Z","steps":["trace[735521841] 'agreement among raft nodes before linearized reading' (duration: 187.295155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:12.226638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:11.624349Z","time spent":"602.215162ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:12.774156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.981153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:00:12.774467Z","caller":"traceutil/trace.go:171","msg":"trace[2127083271] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"543.120023ms","start":"2026-04-17T06:00:12.231313Z","end":"2026-04-17T06:00:12.774433Z","steps":["trace[2127083271] 'process raft request' (duration: 98.734848ms)","trace[2127083271] 'compare' (duration: 443.827629ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:12.774701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:12.231291Z","time spent":"543.315489ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:12.93267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.358103ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-17T06:00:12.932836Z","caller":"traceutil/trace.go:171","msg":"trace[25901150] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1263; }","duration":"101.60109ms","start":"2026-04-17T06:00:12.831211Z","end":"2026-04-17T06:00:12.932813Z","steps":["trace[25901150] 'read index received' (duration: 56.961µs)","trace[25901150] 'applied index is now lower than readState.Index' (duration: 101.542119ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:12.93298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.744555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:12.933074Z","caller":"traceutil/trace.go:171","msg":"trace[418215401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1210; }","duration":"101.900099ms","start":"2026-04-17T06:00:12.831152Z","end":"2026-04-17T06:00:12.933052Z","steps":["trace[418215401] 'agreement among raft nodes before linearized reading' (duration: 101.717284ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:13.80131Z","caller":"traceutil/trace.go:171","msg":"trace[119176771] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"208.810324ms","start":"2026-04-17T06:00:13.59246Z","end":"2026-04-17T06:00:13.80127Z","steps":["trace[119176771] 'process raft request' (duration: 208.624748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:14.067458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.770781ms","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-04-17T06:00:14.067537Z","caller":"traceutil/trace.go:171","msg":"trace[1619810952] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1212; }","duration":"145.890134ms","start":"2026-04-17T06:00:13.921629Z","end":"2026-04-17T06:00:14.067519Z","steps":["trace[1619810952] 'range keys from in-memory index tree' (duration: 145.580405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:14.067664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.277895ms","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-04-17T06:00:14.067904Z","caller":"traceutil/trace.go:171","msg":"trace[842766988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1212; }","duration":"146.543513ms","start":"2026-04-17T06:00:13.921298Z","end":"2026-04-17T06:00:14.067842Z","steps":["trace[842766988] 'range keys from in-memory index tree' (duration: 146.045538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:14.067714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.079159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T06:00:14.068014Z","caller":"traceutil/trace.go:171","msg":"trace[1833127298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1212; }","duration":"146.426889ms","start":"2026-04-17T06:00:13.921577Z","end":"2026-04-17T06:00:14.068003Z","steps":["trace[1833127298] 'range keys from in-memory index tree' (duration: 145.881604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:36.416586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315255560203654,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T06:00:36.725476Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.7730893s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T06:00:36.726139Z","caller":"traceutil/trace.go:171","msg":"trace[1210361873] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"1.773838341s","start":"2026-04-17T06:00:34.952268Z","end":"2026-04-17T06:00:36.726106Z","steps":["trace[1210361873] 'process raft request' (duration: 1.773636715s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:36.726317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:34.952249Z","time spent":"1.773985164s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:37.3911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.060326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:00:37.391227Z","caller":"traceutil/trace.go:171","msg":"trace[1063726453] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1340; }","duration":"1.47494896s","start":"2026-04-17T06:00:35.916256Z","end":"2026-04-17T06:00:37.391205Z","steps":["trace[1063726453] 'read index received' (duration: 809.572211ms)","trace[1063726453] 'applied index is now lower than readState.Index' (duration: 665.375129ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:00:37.391326Z","caller":"traceutil/trace.go:171","msg":"trace[866846671] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"2.195252647s","start":"2026-04-17T06:00:35.196059Z","end":"2026-04-17T06:00:37.391312Z","steps":["trace[866846671] 'process raft request' (duration: 2.067898083s)","trace[866846671] 'compare' (duration: 126.935303ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:37.391406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:35.196043Z","time spent":"2.19531569s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:37.391507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11301757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T06:00:37.391565Z","caller":"traceutil/trace.go:171","msg":"trace[1213642693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1284; }","duration":"1.113160905s","start":"2026-04-17T06:00:36.278391Z","end":"2026-04-17T06:00:37.391552Z","steps":["trace[1213642693] 'agreement among raft nodes before linearized reading' (duration: 1.11298564s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:37.391532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238206542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T06:00:37.391592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:36.278372Z","time spent":"1.113214347s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T06:00:37.3916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206957946s","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-04-17T06:00:37.391631Z","caller":"traceutil/trace.go:171","msg":"trace[1991055973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1284; }","duration":"1.238409257s","start":"2026-04-17T06:00:36.153194Z","end":"2026-04-17T06:00:37.391603Z","steps":["trace[1991055973] 'agreement among raft nodes before linearized reading' (duration: 1.238190381s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:37.39162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475335151s","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-04-17T06:00:37.391655Z","caller":"traceutil/trace.go:171","msg":"trace[285134026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1284; }","duration":"1.207018357s","start":"2026-04-17T06:00:36.184622Z","end":"2026-04-17T06:00:37.39164Z","steps":["trace[285134026] 'agreement among raft nodes before linearized reading' (duration: 1.206827582s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:37.391672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199023001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-17T06:00:37.391692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:36.153176Z","time spent":"1.238502151s","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T06:00:37.391715Z","caller":"traceutil/trace.go:171","msg":"trace[33503544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1284; }","duration":"1.475471975s","start":"2026-04-17T06:00:35.916219Z","end":"2026-04-17T06:00:37.391691Z","steps":["trace[33503544] 'agreement among raft nodes before linearized reading' (duration: 1.475116025s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:37.391533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.980368ms","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-04-17T06:00:37.391752Z","caller":"traceutil/trace.go:171","msg":"trace[1547684524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1284; }","duration":"1.199137394s","start":"2026-04-17T06:00:36.192594Z","end":"2026-04-17T06:00:37.391731Z","steps":["trace[1547684524] 'agreement among raft nodes before linearized reading' (duration: 1.198841666s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:37.391766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:35.916205Z","time spent":"1.475552337s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T06:00:37.391778Z","caller":"traceutil/trace.go:171","msg":"trace[1895122916] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1284; }","duration":"874.315627ms","start":"2026-04-17T06:00:36.517453Z","end":"2026-04-17T06:00:37.391768Z","steps":["trace[1895122916] 'agreement among raft nodes before linearized reading' (duration: 873.954807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:37.391711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:36.184546Z","time spent":"1.207156541s","remote":"127.0.0.1:51920","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-04-17T06:00:37.391792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:36.192581Z","time spent":"1.199203176s","remote":"127.0.0.1:51920","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-04-17T06:00:37.391816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:36.517439Z","time spent":"874.365899ms","remote":"127.0.0.1:55876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:00:37.392124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.512656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-17T06:00:37.392199Z","caller":"traceutil/trace.go:171","msg":"trace[1338562877] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:1284; }","duration":"405.629559ms","start":"2026-04-17T06:00:36.986557Z","end":"2026-04-17T06:00:37.392186Z","steps":["trace[1338562877] 'agreement among raft nodes before linearized reading' (duration: 404.824926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:37.392229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:36.986533Z","time spent":"405.687511ms","remote":"127.0.0.1:51670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"info","ts":"2026-04-17T06:00:37.951386Z","caller":"traceutil/trace.go:171","msg":"trace[1706097113] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"541.121863ms","start":"2026-04-17T06:00:37.410238Z","end":"2026-04-17T06:00:37.95136Z","steps":["trace[1706097113] 'process raft request' (duration: 496.895218ms)","trace[1706097113] 'compare' (duration: 44.05505ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:37.951485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:37.410223Z","time spent":"541.209256ms","remote":"127.0.0.1:51670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:37.951539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.307032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:37.951615Z","caller":"traceutil/trace.go:171","msg":"trace[1407584978] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1289; }","duration":"275.432856ms","start":"2026-04-17T06:00:37.676165Z","end":"2026-04-17T06:00:37.951598Z","steps":["trace[1407584978] 'agreement among raft nodes before linearized reading' (duration: 275.257311ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:37.951856Z","caller":"traceutil/trace.go:171","msg":"trace[997683843] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1347; }","duration":"275.626432ms","start":"2026-04-17T06:00:37.676219Z","end":"2026-04-17T06:00:37.951845Z","steps":["trace[997683843] 'read index received' (duration: 230.919813ms)","trace[997683843] 'applied index is now lower than readState.Index' (duration: 44.196704ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:00:39.769152Z","caller":"traceutil/trace.go:171","msg":"trace[1543710543] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"320.990518ms","start":"2026-04-17T06:00:39.448135Z","end":"2026-04-17T06:00:39.769126Z","steps":["trace[1543710543] 'process raft request' (duration: 316.936602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:39.769305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:39.448116Z","time spent":"321.118131ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:00:39.770405Z","caller":"traceutil/trace.go:171","msg":"trace[1807724411] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"321.79106ms","start":"2026-04-17T06:00:39.448593Z","end":"2026-04-17T06:00:39.770384Z","steps":["trace[1807724411] 'process raft request' (duration: 321.667157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:39.770541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:39.448547Z","time spent":"321.924674ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:00:39.770653Z","caller":"traceutil/trace.go:171","msg":"trace[891645302] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"322.044678ms","start":"2026-04-17T06:00:39.448598Z","end":"2026-04-17T06:00:39.770643Z","steps":["trace[891645302] 'process raft request' (duration: 321.756259ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:39.770672Z","caller":"traceutil/trace.go:171","msg":"trace[322292865] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"322.027847ms","start":"2026-04-17T06:00:39.448626Z","end":"2026-04-17T06:00:39.770654Z","steps":["trace[322292865] 'process raft request' (duration: 321.943044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:39.770701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:39.448551Z","time spent":"322.12574ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:39.77078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:39.448608Z","time spent":"322.12135ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:40.082372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.844221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T06:00:40.082458Z","caller":"traceutil/trace.go:171","msg":"trace[1811495819] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1295; }","duration":"106.984265ms","start":"2026-04-17T06:00:39.975458Z","end":"2026-04-17T06:00:40.082442Z","steps":["trace[1811495819] 'count revisions from in-memory index tree' (duration: 106.747998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:41.331772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315255560203690,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T06:00:41.832832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315255560203690,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T06:00:42.333579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315255560203690,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T06:00:42.621717Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.872364836s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T06:00:42.622107Z","caller":"traceutil/trace.go:171","msg":"trace[1010959125] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"1.87284316s","start":"2026-04-17T06:00:40.749239Z","end":"2026-04-17T06:00:42.622082Z","steps":["trace[1010959125] 'process raft request' (duration: 1.872689945s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:42.622299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:40.749224Z","time spent":"1.872962604s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:00:42.811708Z","caller":"traceutil/trace.go:171","msg":"trace[2045870083] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"1.833687739s","start":"2026-04-17T06:00:40.977973Z","end":"2026-04-17T06:00:42.811661Z","steps":["trace[2045870083] 'process raft request' (duration: 1.812854268s)","trace[2045870083] 'compare' (duration: 20.616685ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:00:42.811758Z","caller":"traceutil/trace.go:171","msg":"trace[2143104270] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"1.203294953s","start":"2026-04-17T06:00:41.608417Z","end":"2026-04-17T06:00:42.811712Z","steps":["trace[2143104270] 'process raft request' (duration: 1.203145639s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:42.811869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:40.977951Z","time spent":"1.833841263s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:42.811924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:41.608396Z","time spent":"1.203439537s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:00:42.818176Z","caller":"traceutil/trace.go:171","msg":"trace[1695200607] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1354; }","duration":"1.987105661s","start":"2026-04-17T06:00:40.831054Z","end":"2026-04-17T06:00:42.81816Z","steps":["trace[1695200607] 'read index received' (duration: 1.790820652s)","trace[1695200607] 'applied index is now lower than readState.Index' (duration: 196.284219ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:42.818316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.740608107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T06:00:42.818379Z","caller":"traceutil/trace.go:171","msg":"trace[1895027562] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1298; }","duration":"1.74070915s","start":"2026-04-17T06:00:41.07766Z","end":"2026-04-17T06:00:42.818369Z","steps":["trace[1895027562] 'agreement among raft nodes before linearized reading' (duration: 1.740612568s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:42.818387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045108673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-17T06:00:42.818413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:41.077644Z","time spent":"1.740761482s","remote":"127.0.0.1:51978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-04-17T06:00:42.818448Z","caller":"traceutil/trace.go:171","msg":"trace[1563183131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1298; }","duration":"1.045187106s","start":"2026-04-17T06:00:41.773245Z","end":"2026-04-17T06:00:42.818432Z","steps":["trace[1563183131] 'agreement among raft nodes before linearized reading' (duration: 1.045037152s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:42.818475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:41.773233Z","time spent":"1.045227707s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T06:00:42.81856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046173234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-17T06:00:42.818565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044270031s","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-04-17T06:00:42.818647Z","caller":"traceutil/trace.go:171","msg":"trace[209899056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1298; }","duration":"1.046303717s","start":"2026-04-17T06:00:41.772322Z","end":"2026-04-17T06:00:42.818625Z","steps":["trace[209899056] 'agreement among raft nodes before linearized reading' (duration: 1.046002439s)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:42.818665Z","caller":"traceutil/trace.go:171","msg":"trace[2038057220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1298; }","duration":"1.044391264s","start":"2026-04-17T06:00:41.774251Z","end":"2026-04-17T06:00:42.818642Z","steps":["trace[2038057220] 'agreement among raft nodes before linearized reading' (duration: 1.044168278s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:42.818695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:41.772304Z","time spent":"1.046380779s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T06:00:42.818725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.987200534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T06:00:42.818729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:41.774244Z","time spent":"1.044469876s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T06:00:42.818748Z","caller":"traceutil/trace.go:171","msg":"trace[509565598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1298; }","duration":"1.987730689s","start":"2026-04-17T06:00:40.83101Z","end":"2026-04-17T06:00:42.81874Z","steps":["trace[509565598] 'agreement among raft nodes before linearized reading' (duration: 1.987217114s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:42.818565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045267319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-17T06:00:42.818782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:40.83099Z","time spent":"1.98777132s","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T06:00:42.818802Z","caller":"traceutil/trace.go:171","msg":"trace[1882144397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1298; }","duration":"1.045530796s","start":"2026-04-17T06:00:41.773251Z","end":"2026-04-17T06:00:42.818782Z","steps":["trace[1882144397] 'agreement among raft nodes before linearized reading' (duration: 1.045052162s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:42.818864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:41.773243Z","time spent":"1.045605868s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T06:00:44.734116Z","caller":"traceutil/trace.go:171","msg":"trace[1834912951] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"103.552698ms","start":"2026-04-17T06:00:44.630536Z","end":"2026-04-17T06:00:44.734088Z","steps":["trace[1834912951] 'process raft request' (duration: 103.378843ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:45.061399Z","caller":"traceutil/trace.go:171","msg":"trace[1926563073] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"228.429482ms","start":"2026-04-17T06:00:44.832953Z","end":"2026-04-17T06:00:45.061382Z","steps":["trace[1926563073] 'read index received' (duration: 171.372752ms)","trace[1926563073] 'applied index is now lower than readState.Index' (duration: 57.05602ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:45.061507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.548065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:45.061526Z","caller":"traceutil/trace.go:171","msg":"trace[1358680457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1305; }","duration":"228.615797ms","start":"2026-04-17T06:00:44.832904Z","end":"2026-04-17T06:00:45.06152Z","steps":["trace[1358680457] 'agreement among raft nodes before linearized reading' (duration: 228.571546ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:45.061518Z","caller":"traceutil/trace.go:171","msg":"trace[1933619714] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"244.310032ms","start":"2026-04-17T06:00:44.817166Z","end":"2026-04-17T06:00:45.061477Z","steps":["trace[1933619714] 'process raft request' (duration: 187.078937ms)","trace[1933619714] 'compare' (duration: 57.07056ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:45.061634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.991955ms","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-04-17T06:00:45.06171Z","caller":"traceutil/trace.go:171","msg":"trace[656711446] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1305; }","duration":"225.096488ms","start":"2026-04-17T06:00:44.836597Z","end":"2026-04-17T06:00:45.061694Z","steps":["trace[656711446] 'agreement among raft nodes before linearized reading' (duration: 224.911703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:45.061767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.491034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-17T06:00:45.061639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.374801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-17T06:00:45.061781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.560794ms","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-04-17T06:00:45.061824Z","caller":"traceutil/trace.go:171","msg":"trace[350819614] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1305; }","duration":"191.582917ms","start":"2026-04-17T06:00:44.870225Z","end":"2026-04-17T06:00:45.061808Z","steps":["trace[350819614] 'agreement among raft nodes before linearized reading' (duration: 191.406982ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:45.061844Z","caller":"traceutil/trace.go:171","msg":"trace[1084042946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1305; }","duration":"223.659747ms","start":"2026-04-17T06:00:44.838169Z","end":"2026-04-17T06:00:45.061829Z","steps":["trace[1084042946] 'agreement among raft nodes before linearized reading' (duration: 223.522213ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:45.061853Z","caller":"traceutil/trace.go:171","msg":"trace[324429431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1305; }","duration":"191.601578ms","start":"2026-04-17T06:00:44.870222Z","end":"2026-04-17T06:00:45.061823Z","steps":["trace[324429431] 'agreement among raft nodes before linearized reading' (duration: 191.307819ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:45.262487Z","caller":"traceutil/trace.go:171","msg":"trace[2103585738] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"197.044562ms","start":"2026-04-17T06:00:45.06542Z","end":"2026-04-17T06:00:45.262464Z","steps":["trace[2103585738] 'process raft request' (duration: 196.910997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:45.56706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.760419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:00:45.567307Z","caller":"traceutil/trace.go:171","msg":"trace[1073300253] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"500.47952ms","start":"2026-04-17T06:00:45.066809Z","end":"2026-04-17T06:00:45.567289Z","steps":["trace[1073300253] 'process raft request' (duration: 500.440239ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:45.567326Z","caller":"traceutil/trace.go:171","msg":"trace[1737260870] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"501.231741ms","start":"2026-04-17T06:00:45.066072Z","end":"2026-04-17T06:00:45.567303Z","steps":["trace[1737260870] 'process raft request' (duration: 501.102258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:45.56738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:45.066802Z","time spent":"500.545812ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:45.567426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:45.066058Z","time spent":"501.314004ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:00:45.567387Z","caller":"traceutil/trace.go:171","msg":"trace[1190628209] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"501.722246ms","start":"2026-04-17T06:00:45.065631Z","end":"2026-04-17T06:00:45.567353Z","steps":["trace[1190628209] 'process raft request' (duration: 351.595267ms)","trace[1190628209] 'compare' (duration: 149.392698ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:45.567569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:45.065623Z","time spent":"501.852879ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:00:46.876206Z","caller":"traceutil/trace.go:171","msg":"trace[1371457678] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"133.313113ms","start":"2026-04-17T06:00:46.742865Z","end":"2026-04-17T06:00:46.876178Z","steps":["trace[1371457678] 'process raft request' (duration: 133.128567ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:47.418855Z","caller":"traceutil/trace.go:171","msg":"trace[1252150449] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"147.744331ms","start":"2026-04-17T06:00:47.271081Z","end":"2026-04-17T06:00:47.418825Z","steps":["trace[1252150449] 'process raft request' (duration: 147.485384ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:49.342704Z","caller":"traceutil/trace.go:171","msg":"trace[981329693] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"244.909529ms","start":"2026-04-17T06:00:49.097752Z","end":"2026-04-17T06:00:49.342661Z","steps":["trace[981329693] 'process raft request' (duration: 244.638021ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:49.58706Z","caller":"traceutil/trace.go:171","msg":"trace[343569254] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"158.224399ms","start":"2026-04-17T06:00:49.428808Z","end":"2026-04-17T06:00:49.587033Z","steps":["trace[343569254] 'process raft request' (duration: 79.215408ms)","trace[343569254] 'compare' (duration: 78.828316ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:49.904759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.197782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:00:49.904826Z","caller":"traceutil/trace.go:171","msg":"trace[1348637465] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"301.382141ms","start":"2026-04-17T06:00:49.603433Z","end":"2026-04-17T06:00:49.904815Z","steps":["trace[1348637465] 'read index received' (duration: 138.072397ms)","trace[1348637465] 'applied index is now lower than readState.Index' (duration: 163.309004ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:00:49.904879Z","caller":"traceutil/trace.go:171","msg":"trace[222628254] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"312.361463ms","start":"2026-04-17T06:00:49.592511Z","end":"2026-04-17T06:00:49.904873Z","steps":["trace[222628254] 'process raft request' (duration: 148.972537ms)","trace[222628254] 'compare' (duration: 163.129509ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:49.904905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:49.592492Z","time spent":"312.398785ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:49.90509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.559889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T06:00:49.90516Z","caller":"traceutil/trace.go:171","msg":"trace[1994845646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1320; }","duration":"300.647532ms","start":"2026-04-17T06:00:49.604492Z","end":"2026-04-17T06:00:49.90514Z","steps":["trace[1994845646] 'agreement among raft nodes before linearized reading' (duration: 300.471907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:49.905204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:49.604483Z","time spent":"300.708573ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T06:00:49.905239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.821121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T06:00:49.905256Z","caller":"traceutil/trace.go:171","msg":"trace[31608928] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1320; }","duration":"112.889093ms","start":"2026-04-17T06:00:49.792362Z","end":"2026-04-17T06:00:49.905251Z","steps":["trace[31608928] 'agreement among raft nodes before linearized reading' (duration: 112.845291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:49.905287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.844554ms","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-04-17T06:00:49.905348Z","caller":"traceutil/trace.go:171","msg":"trace[1149574940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1320; }","duration":"301.947578ms","start":"2026-04-17T06:00:49.603384Z","end":"2026-04-17T06:00:49.905331Z","steps":["trace[1149574940] 'agreement among raft nodes before linearized reading' (duration: 301.796654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:49.905389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:49.603366Z","time spent":"302.011059ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T06:00:50.233895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.208901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:00:50.234093Z","caller":"traceutil/trace.go:171","msg":"trace[993931993] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1381; }","duration":"272.681737ms","start":"2026-04-17T06:00:49.961394Z","end":"2026-04-17T06:00:50.234076Z","steps":["trace[993931993] 'read index received' (duration: 127.21812ms)","trace[993931993] 'applied index is now lower than readState.Index' (duration: 145.462437ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:00:50.234189Z","caller":"traceutil/trace.go:171","msg":"trace[905013953] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"323.495598ms","start":"2026-04-17T06:00:49.910683Z","end":"2026-04-17T06:00:50.234178Z","steps":["trace[905013953] 'process raft request' (duration: 323.333964ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:50.234217Z","caller":"traceutil/trace.go:171","msg":"trace[501670472] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"324.518768ms","start":"2026-04-17T06:00:49.90967Z","end":"2026-04-17T06:00:50.234188Z","steps":["trace[501670472] 'process raft request' (duration: 178.932157ms)","trace[501670472] 'compare' (duration: 145.082137ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:50.234255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:49.910677Z","time spent":"323.543269ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:50.234371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:49.909657Z","time spent":"324.644041ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:00:50.234466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.047007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:50.234546Z","caller":"traceutil/trace.go:171","msg":"trace[1666219938] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1322; }","duration":"273.180171ms","start":"2026-04-17T06:00:49.961348Z","end":"2026-04-17T06:00:50.234528Z","steps":["trace[1666219938] 'agreement among raft nodes before linearized reading' (duration: 273.026697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:50.644968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.433286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T06:00:50.645027Z","caller":"traceutil/trace.go:171","msg":"trace[538011888] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1322; }","duration":"237.57092ms","start":"2026-04-17T06:00:50.407441Z","end":"2026-04-17T06:00:50.645012Z","steps":["trace[538011888] 'count revisions from in-memory index tree' (duration: 237.308513ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:51.579539Z","caller":"traceutil/trace.go:171","msg":"trace[1329385963] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"228.605376ms","start":"2026-04-17T06:00:51.350906Z","end":"2026-04-17T06:00:51.579511Z","steps":["trace[1329385963] 'process raft request' (duration: 228.444272ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:51.793146Z","caller":"traceutil/trace.go:171","msg":"trace[713415426] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"196.171025ms","start":"2026-04-17T06:00:51.596948Z","end":"2026-04-17T06:00:51.793119Z","steps":["trace[713415426] 'process raft request' (duration: 187.179221ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:13.755339Z","caller":"traceutil/trace.go:171","msg":"trace[697449524] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"108.884208ms","start":"2026-04-17T06:01:13.64643Z","end":"2026-04-17T06:01:13.755314Z","steps":["trace[697449524] 'process raft request' (duration: 108.679703ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:19.297406Z","caller":"traceutil/trace.go:171","msg":"trace[436460333] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"164.649022ms","start":"2026-04-17T06:01:19.132718Z","end":"2026-04-17T06:01:19.297367Z","steps":["trace[436460333] 'process raft request' (duration: 164.323433ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:20.014014Z","caller":"traceutil/trace.go:171","msg":"trace[1126785016] linearizableReadLoop","detail":"{readStateIndex:1484; appliedIndex:1483; }","duration":"182.501789ms","start":"2026-04-17T06:01:19.83149Z","end":"2026-04-17T06:01:20.013992Z","steps":["trace[1126785016] 'read index received' (duration: 182.332154ms)","trace[1126785016] 'applied index is now lower than readState.Index' (duration: 168.985µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:01:20.014064Z","caller":"traceutil/trace.go:171","msg":"trace[2048921874] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"234.889374ms","start":"2026-04-17T06:01:19.779144Z","end":"2026-04-17T06:01:20.014033Z","steps":["trace[2048921874] 'process raft request' (duration: 234.72857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:20.014296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.721985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:01:20.014477Z","caller":"traceutil/trace.go:171","msg":"trace[1523910941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1417; }","duration":"183.008133ms","start":"2026-04-17T06:01:19.831436Z","end":"2026-04-17T06:01:20.014444Z","steps":["trace[1523910941] 'agreement among raft nodes before linearized reading' (duration: 182.685354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:25.754553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.566214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:01:25.754622Z","caller":"traceutil/trace.go:171","msg":"trace[802858575] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1436; }","duration":"180.685457ms","start":"2026-04-17T06:01:25.573921Z","end":"2026-04-17T06:01:25.754606Z","steps":["trace[802858575] 'count revisions from in-memory index tree' (duration: 180.493142ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:26.149195Z","caller":"traceutil/trace.go:171","msg":"trace[414459308] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"116.052053ms","start":"2026-04-17T06:01:26.033083Z","end":"2026-04-17T06:01:26.149135Z","steps":["trace[414459308] 'process raft request' (duration: 115.533118ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:26.27361Z","caller":"traceutil/trace.go:171","msg":"trace[1730899893] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"120.089257ms","start":"2026-04-17T06:01:26.153464Z","end":"2026-04-17T06:01:26.273554Z","steps":["trace[1730899893] 'process raft request' (duration: 46.18602ms)","trace[1730899893] 'compare' (duration: 73.720832ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:01:26.275314Z","caller":"traceutil/trace.go:171","msg":"trace[1288337309] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"121.704794ms","start":"2026-04-17T06:01:26.15358Z","end":"2026-04-17T06:01:26.275284Z","steps":["trace[1288337309] 'process raft request' (duration: 121.538419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:28.880984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.00553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:01:28.881157Z","caller":"traceutil/trace.go:171","msg":"trace[1817612262] linearizableReadLoop","detail":"{readStateIndex:1652; appliedIndex:1651; }","duration":"396.637144ms","start":"2026-04-17T06:01:28.484502Z","end":"2026-04-17T06:01:28.881139Z","steps":["trace[1817612262] 'read index received' (duration: 162.380867ms)","trace[1817612262] 'applied index is now lower than readState.Index' (duration: 234.254717ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:01:28.881233Z","caller":"traceutil/trace.go:171","msg":"trace[1093130363] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"445.614683ms","start":"2026-04-17T06:01:28.435607Z","end":"2026-04-17T06:01:28.881221Z","steps":["trace[1093130363] 'process raft request' (duration: 211.262704ms)","trace[1093130363] 'compare' (duration: 233.847625ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:01:28.881298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:28.435588Z","time spent":"445.675806ms","remote":"127.0.0.1:51828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:01:28.881355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.819869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephclients.ceph.rook.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:01:28.881457Z","caller":"traceutil/trace.go:171","msg":"trace[690199854] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/cephclients.ceph.rook.io; range_end:; response_count:0; response_revision:1578; }","duration":"396.961693ms","start":"2026-04-17T06:01:28.48447Z","end":"2026-04-17T06:01:28.881432Z","steps":["trace[690199854] 'agreement among raft nodes before linearized reading' (duration: 396.802339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:28.881514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:28.484453Z","time spent":"397.046595ms","remote":"127.0.0.1:51694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephclients.ceph.rook.io\" "} {"level":"info","ts":"2026-04-17T06:01:28.881975Z","caller":"traceutil/trace.go:171","msg":"trace[1422337473] transaction","detail":"{read_only:false; response_revision:1580; number_of_response:1; }","duration":"394.931445ms","start":"2026-04-17T06:01:28.487028Z","end":"2026-04-17T06:01:28.881959Z","steps":["trace[1422337473] 'process raft request' (duration: 394.834462ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:28.882034Z","caller":"traceutil/trace.go:171","msg":"trace[476576091] transaction","detail":"{read_only:false; response_revision:1579; number_of_response:1; }","duration":"395.991505ms","start":"2026-04-17T06:01:28.485996Z","end":"2026-04-17T06:01:28.881987Z","steps":["trace[476576091] 'process raft request' (duration: 395.762678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:28.88222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:28.485978Z","time spent":"396.14559ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:01:28.882066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:28.487019Z","time spent":"395.004117ms","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105279,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:01:28.893168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.15656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:01:28.893248Z","caller":"traceutil/trace.go:171","msg":"trace[248544847] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:1581; }","duration":"150.294794ms","start":"2026-04-17T06:01:28.742935Z","end":"2026-04-17T06:01:28.893229Z","steps":["trace[248544847] 'agreement among raft nodes before linearized reading' (duration: 150.059997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:28.893221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.139644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery-worker\" ","response":"range_response_count:1 size:887"} {"level":"info","ts":"2026-04-17T06:01:28.893311Z","caller":"traceutil/trace.go:171","msg":"trace[705408661] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/node-feature-discovery-worker; range_end:; response_count:1; response_revision:1581; }","duration":"283.271207ms","start":"2026-04-17T06:01:28.610017Z","end":"2026-04-17T06:01:28.893288Z","steps":["trace[705408661] 'agreement among raft nodes before linearized reading' (duration: 282.980709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:28.893639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.360105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:2 size:38012"} {"level":"info","ts":"2026-04-17T06:01:28.893692Z","caller":"traceutil/trace.go:171","msg":"trace[952780842] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:2; response_revision:1581; }","duration":"403.453007ms","start":"2026-04-17T06:01:28.490226Z","end":"2026-04-17T06:01:28.893679Z","steps":["trace[952780842] 'agreement among raft nodes before linearized reading' (duration: 403.296363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:28.893718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:28.490209Z","time spent":"403.502048ms","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":2,"response size":38036,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-04-17T06:01:28.893842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.854968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:01:28.893864Z","caller":"traceutil/trace.go:171","msg":"trace[1318067044] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:0; response_revision:1581; }","duration":"317.91672ms","start":"2026-04-17T06:01:28.575938Z","end":"2026-04-17T06:01:28.893855Z","steps":["trace[1318067044] 'agreement among raft nodes before linearized reading' (duration: 317.883059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:28.893886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:28.575917Z","time spent":"317.965221ms","remote":"127.0.0.1:51848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" "} {"level":"warn","ts":"2026-04-17T06:01:28.894318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.056844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery-gc\" ","response":"range_response_count:1 size:879"} {"level":"info","ts":"2026-04-17T06:01:28.894341Z","caller":"traceutil/trace.go:171","msg":"trace[1434484598] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/node-feature-discovery-gc; range_end:; response_count:1; response_revision:1581; }","duration":"386.118896ms","start":"2026-04-17T06:01:28.508215Z","end":"2026-04-17T06:01:28.894334Z","steps":["trace[1434484598] 'agreement among raft nodes before linearized reading' (duration: 386.053214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:28.894361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:28.508195Z","time spent":"386.159336ms","remote":"127.0.0.1:51848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":903,"request content":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery-gc\" "} {"level":"warn","ts":"2026-04-17T06:01:28.894645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.467875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery\" ","response":"range_response_count:1 size:873"} {"level":"info","ts":"2026-04-17T06:01:28.894692Z","caller":"traceutil/trace.go:171","msg":"trace[58106325] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/node-feature-discovery; range_end:; response_count:1; response_revision:1581; }","duration":"386.543197ms","start":"2026-04-17T06:01:28.508134Z","end":"2026-04-17T06:01:28.894677Z","steps":["trace[58106325] 'agreement among raft nodes before linearized reading' (duration: 385.766515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:28.894724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:28.50812Z","time spent":"386.596298ms","remote":"127.0.0.1:51848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":897,"request content":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery\" "} {"level":"info","ts":"2026-04-17T06:01:29.401767Z","caller":"traceutil/trace.go:171","msg":"trace[1259840111] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"501.380547ms","start":"2026-04-17T06:01:28.900357Z","end":"2026-04-17T06:01:29.401737Z","steps":["trace[1259840111] 'process raft request' (duration: 501.181451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:29.40193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:28.90033Z","time spent":"501.5294ms","remote":"127.0.0.1:51848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1056,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T06:01:29.543653Z","caller":"traceutil/trace.go:171","msg":"trace[1760780836] transaction","detail":"{read_only:false; response_revision:1583; number_of_response:1; }","duration":"643.176039ms","start":"2026-04-17T06:01:28.900435Z","end":"2026-04-17T06:01:29.543611Z","steps":["trace[1760780836] 'process raft request' (duration: 567.592894ms)","trace[1760780836] 'compare' (duration: 75.237475ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:01:29.543739Z","caller":"traceutil/trace.go:171","msg":"trace[739060813] transaction","detail":"{read_only:false; response_revision:1584; number_of_response:1; }","duration":"640.03958ms","start":"2026-04-17T06:01:28.903672Z","end":"2026-04-17T06:01:29.543712Z","steps":["trace[739060813] 'process raft request' (duration: 639.871686ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:29.543781Z","caller":"traceutil/trace.go:171","msg":"trace[1624913013] transaction","detail":"{read_only:false; response_revision:1585; number_of_response:1; }","duration":"134.704542ms","start":"2026-04-17T06:01:29.409057Z","end":"2026-04-17T06:01:29.543762Z","steps":["trace[1624913013] 'process raft request' (duration: 134.63608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:29.543877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:28.900421Z","time spent":"643.332164ms","remote":"127.0.0.1:51828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4764,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:01:29.543902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:28.903661Z","time spent":"640.137333ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":127641,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:01:29.544051Z","caller":"traceutil/trace.go:171","msg":"trace[1101445827] linearizableReadLoop","detail":"{readStateIndex:1657; appliedIndex:1656; }","duration":"640.795652ms","start":"2026-04-17T06:01:28.903237Z","end":"2026-04-17T06:01:29.544033Z","steps":["trace[1101445827] 'read index received' (duration: 498.961248ms)","trace[1101445827] 'applied index is now lower than readState.Index' (duration: 141.832964ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:01:29.544242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.410871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephfilesystemmirrors.ceph.rook.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:01:29.544296Z","caller":"traceutil/trace.go:171","msg":"trace[2059068526] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/cephfilesystemmirrors.ceph.rook.io; range_end:; response_count:0; response_revision:1585; }","duration":"640.476973ms","start":"2026-04-17T06:01:28.903805Z","end":"2026-04-17T06:01:29.544282Z","steps":["trace[2059068526] 'agreement among raft nodes before linearized reading' (duration: 640.379301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:29.544297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.130595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/ingress-nginx-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:01:29.544366Z","caller":"traceutil/trace.go:171","msg":"trace[78911020] range","detail":"{range_begin:/registry/clusterroles/ingress-nginx-admission; range_end:; response_count:0; response_revision:1585; }","duration":"128.232578ms","start":"2026-04-17T06:01:29.416118Z","end":"2026-04-17T06:01:29.544351Z","steps":["trace[78911020] 'agreement among raft nodes before linearized reading' (duration: 128.130585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:29.544378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.128872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T06:01:29.54443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.19962ms","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-04-17T06:01:29.544436Z","caller":"traceutil/trace.go:171","msg":"trace[451349432] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:0; response_revision:1585; }","duration":"641.194073ms","start":"2026-04-17T06:01:28.903218Z","end":"2026-04-17T06:01:29.544412Z","steps":["trace[451349432] 'agreement among raft nodes before linearized reading' (duration: 641.116821ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:29.544465Z","caller":"traceutil/trace.go:171","msg":"trace[1808865456] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1585; }","duration":"157.270572ms","start":"2026-04-17T06:01:29.387187Z","end":"2026-04-17T06:01:29.544457Z","steps":["trace[1808865456] 'agreement among raft nodes before linearized reading' (duration: 157.161359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:29.544479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:28.903197Z","time spent":"641.269886ms","remote":"127.0.0.1:51848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" "} {"level":"warn","ts":"2026-04-17T06:01:29.54433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:28.903797Z","time spent":"640.523844ms","remote":"127.0.0.1:51694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephfilesystemmirrors.ceph.rook.io\" "} {"level":"warn","ts":"2026-04-17T06:01:29.544667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.937423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:2 size:38012"} {"level":"info","ts":"2026-04-17T06:01:29.544695Z","caller":"traceutil/trace.go:171","msg":"trace[1129899846] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:2; response_revision:1585; }","duration":"562.999945ms","start":"2026-04-17T06:01:28.981687Z","end":"2026-04-17T06:01:29.544687Z","steps":["trace[1129899846] 'agreement among raft nodes before linearized reading' (duration: 562.903212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:29.54472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:28.981672Z","time spent":"563.042366ms","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":2,"response size":38036,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-04-17T06:01:30.055713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.846236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:01:30.055798Z","caller":"traceutil/trace.go:171","msg":"trace[64119020] linearizableReadLoop","detail":"{readStateIndex:1660; appliedIndex:1659; }","duration":"506.877882ms","start":"2026-04-17T06:01:29.548908Z","end":"2026-04-17T06:01:30.055786Z","steps":["trace[64119020] 'read index received' (duration: 261.947793ms)","trace[64119020] 'applied index is now lower than readState.Index' (duration: 244.928909ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:01:30.055879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.965735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephfilesystems.ceph.rook.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:01:30.055842Z","caller":"traceutil/trace.go:171","msg":"trace[651556858] transaction","detail":"{read_only:false; response_revision:1586; number_of_response:1; }","duration":"507.725656ms","start":"2026-04-17T06:01:29.548073Z","end":"2026-04-17T06:01:30.055798Z","steps":["trace[651556858] 'process raft request' (duration: 262.736915ms)","trace[651556858] 'compare' (duration: 244.703453ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:01:30.055901Z","caller":"traceutil/trace.go:171","msg":"trace[959730942] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/cephfilesystems.ceph.rook.io; range_end:; response_count:0; response_revision:1586; }","duration":"507.010146ms","start":"2026-04-17T06:01:29.548882Z","end":"2026-04-17T06:01:30.055892Z","steps":["trace[959730942] 'agreement among raft nodes before linearized reading' (duration: 506.954715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:30.056018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:29.548866Z","time spent":"507.14308ms","remote":"127.0.0.1:51694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephfilesystems.ceph.rook.io\" "} {"level":"warn","ts":"2026-04-17T06:01:30.056027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:29.548061Z","time spent":"507.874951ms","remote":"127.0.0.1:51834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T06:01:30.667513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.178988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:01:30.66794Z","caller":"traceutil/trace.go:171","msg":"trace[624903084] transaction","detail":"{read_only:false; response_revision:1587; number_of_response:1; }","duration":"1.118673932s","start":"2026-04-17T06:01:29.549195Z","end":"2026-04-17T06:01:30.667869Z","steps":["trace[624903084] 'process raft request' (duration: 671.062081ms)","trace[624903084] 'compare' (duration: 447.039004ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:01:30.668032Z","caller":"traceutil/trace.go:171","msg":"trace[173416989] transaction","detail":"{read_only:false; response_revision:1588; number_of_response:1; }","duration":"1.064526695s","start":"2026-04-17T06:01:29.60348Z","end":"2026-04-17T06:01:30.668007Z","steps":["trace[173416989] 'process raft request' (duration: 1.06436946s)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:30.668086Z","caller":"traceutil/trace.go:171","msg":"trace[1759233760] linearizableReadLoop","detail":"{readStateIndex:1662; appliedIndex:1660; }","duration":"612.250692ms","start":"2026-04-17T06:01:30.055815Z","end":"2026-04-17T06:01:30.668065Z","steps":["trace[1759233760] 'read index received' (duration: 164.58749ms)","trace[1759233760] 'applied index is now lower than readState.Index' (duration: 447.660472ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:01:30.668153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:29.603456Z","time spent":"1.064633218s","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105323,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:01:30.668258Z","caller":"traceutil/trace.go:171","msg":"trace[487141667] transaction","detail":"{read_only:false; response_revision:1589; number_of_response:1; }","duration":"608.102704ms","start":"2026-04-17T06:01:30.060144Z","end":"2026-04-17T06:01:30.668247Z","steps":["trace[487141667] 'process raft request' (duration: 607.837627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:30.668307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:30.060118Z","time spent":"608.167325ms","remote":"127.0.0.1:51804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":524,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T06:01:30.668355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119005141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-x6xxn\" ","response":"range_response_count:1 size:11624"} {"level":"warn","ts":"2026-04-17T06:01:30.668336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.440969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:01:30.668399Z","caller":"traceutil/trace.go:171","msg":"trace[653585496] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-x6xxn; range_end:; response_count:1; response_revision:1589; }","duration":"1.119070843s","start":"2026-04-17T06:01:29.549318Z","end":"2026-04-17T06:01:30.668389Z","steps":["trace[653585496] 'agreement among raft nodes before linearized reading' (duration: 1.118833497s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:30.66817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:29.549182Z","time spent":"1.118872707s","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:01:30.668655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.483918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-17T06:01:30.668659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.721075ms","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-04-17T06:01:30.668691Z","caller":"traceutil/trace.go:171","msg":"trace[716763566] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1591; }","duration":"379.526009ms","start":"2026-04-17T06:01:30.289154Z","end":"2026-04-17T06:01:30.66868Z","steps":["trace[716763566] 'agreement among raft nodes before linearized reading' (duration: 379.412136ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:30.668697Z","caller":"traceutil/trace.go:171","msg":"trace[1188708243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1591; }","duration":"379.785407ms","start":"2026-04-17T06:01:30.288902Z","end":"2026-04-17T06:01:30.668688Z","steps":["trace[1188708243] 'agreement among raft nodes before linearized reading' (duration: 379.678444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:30.668712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:30.289151Z","time spent":"379.55575ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T06:01:30.668752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:30.288888Z","time spent":"379.831148ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T06:01:30.668787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.080431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T06:01:30.668706Z","caller":"traceutil/trace.go:171","msg":"trace[749390279] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"507.077747ms","start":"2026-04-17T06:01:30.161531Z","end":"2026-04-17T06:01:30.668609Z","steps":["trace[749390279] 'process raft request' (duration: 506.887502ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:30.668871Z","caller":"traceutil/trace.go:171","msg":"trace[683936615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1591; }","duration":"185.167354ms","start":"2026-04-17T06:01:30.483659Z","end":"2026-04-17T06:01:30.668826Z","steps":["trace[683936615] 'agreement among raft nodes before linearized reading' (duration: 185.014489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:30.668447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:29.549312Z","time spent":"1.119126915s","remote":"127.0.0.1:51828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11648,"request content":"key:\"/registry/pods/openstack/openvswitch-x6xxn\" "} {"level":"warn","ts":"2026-04-17T06:01:30.668273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117594211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/cert-manager/cert-manager-webhook\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T06:01:30.669032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:30.161504Z","time spent":"507.369156ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:01:30.668462Z","caller":"traceutil/trace.go:171","msg":"trace[840227003] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"607.802086ms","start":"2026-04-17T06:01:30.060644Z","end":"2026-04-17T06:01:30.668446Z","steps":["trace[840227003] 'process raft request' (duration: 607.545929ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:30.669115Z","caller":"traceutil/trace.go:171","msg":"trace[983737248] range","detail":"{range_begin:/registry/configmaps/cert-manager/cert-manager-webhook; range_end:; response_count:0; response_revision:1589; }","duration":"1.118429144s","start":"2026-04-17T06:01:29.550651Z","end":"2026-04-17T06:01:30.66908Z","steps":["trace[983737248] 'agreement among raft nodes before linearized reading' (duration: 1.117477197s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:30.669162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.728992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephfilesystemsubvolumegroups.ceph.rook.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:01:30.669245Z","caller":"traceutil/trace.go:171","msg":"trace[1414864483] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/cephfilesystemsubvolumegroups.ceph.rook.io; range_end:; response_count:0; response_revision:1591; }","duration":"608.852775ms","start":"2026-04-17T06:01:30.060371Z","end":"2026-04-17T06:01:30.669224Z","steps":["trace[1414864483] 'agreement among raft nodes before linearized reading' (duration: 608.165205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:30.669251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:29.550645Z","time spent":"1.118573138s","remote":"127.0.0.1:51744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/configmaps/cert-manager/cert-manager-webhook\" "} {"level":"warn","ts":"2026-04-17T06:01:30.66929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:30.060361Z","time spent":"608.920407ms","remote":"127.0.0.1:51694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephfilesystemsubvolumegroups.ceph.rook.io\" "} {"level":"warn","ts":"2026-04-17T06:01:30.669174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:30.060635Z","time spent":"608.509085ms","remote":"127.0.0.1:51928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1005,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T06:01:30.668458Z","caller":"traceutil/trace.go:171","msg":"trace[1104918292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1589; }","duration":"838.627384ms","start":"2026-04-17T06:01:29.829802Z","end":"2026-04-17T06:01:30.66843Z","steps":["trace[1104918292] 'agreement among raft nodes before linearized reading' (duration: 838.322586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:30.669392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:29.829781Z","time spent":"839.604112ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T06:01:30.83709Z","caller":"traceutil/trace.go:171","msg":"trace[323174007] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"163.654373ms","start":"2026-04-17T06:01:30.67341Z","end":"2026-04-17T06:01:30.837064Z","steps":["trace[323174007] 'process raft request' (duration: 134.27665ms)","trace[323174007] 'compare' (duration: 29.2632ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:01:30.84264Z","caller":"traceutil/trace.go:171","msg":"trace[703165443] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"168.979934ms","start":"2026-04-17T06:01:30.673624Z","end":"2026-04-17T06:01:30.842604Z","steps":["trace[703165443] 'process raft request' (duration: 168.653525ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:30.842887Z","caller":"traceutil/trace.go:171","msg":"trace[1939767492] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"168.153731ms","start":"2026-04-17T06:01:30.674705Z","end":"2026-04-17T06:01:30.842858Z","steps":["trace[1939767492] 'process raft request' (duration: 167.830662ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:30.843094Z","caller":"traceutil/trace.go:171","msg":"trace[431448708] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"162.632584ms","start":"2026-04-17T06:01:30.680442Z","end":"2026-04-17T06:01:30.843074Z","steps":["trace[431448708] 'process raft request' (duration: 162.333306ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:30.843078Z","caller":"traceutil/trace.go:171","msg":"trace[215392761] linearizableReadLoop","detail":"{readStateIndex:1667; appliedIndex:1665; }","duration":"168.49973ms","start":"2026-04-17T06:01:30.674542Z","end":"2026-04-17T06:01:30.843042Z","steps":["trace[215392761] 'read index received' (duration: 133.160168ms)","trace[215392761] 'applied index is now lower than readState.Index' (duration: 35.337882ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:01:30.843176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.617394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/certificaterequests.cert-manager.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:01:30.843221Z","caller":"traceutil/trace.go:171","msg":"trace[348180769] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/certificaterequests.cert-manager.io; range_end:; response_count:0; response_revision:1596; }","duration":"168.703386ms","start":"2026-04-17T06:01:30.67451Z","end":"2026-04-17T06:01:30.843214Z","steps":["trace[348180769] 'agreement among raft nodes before linearized reading' (duration: 168.619604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:01:30.84334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.373338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephobjectrealms.ceph.rook.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:01:30.843103Z","caller":"traceutil/trace.go:171","msg":"trace[1760337506] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"159.390262ms","start":"2026-04-17T06:01:30.683691Z","end":"2026-04-17T06:01:30.843081Z","steps":["trace[1760337506] 'process raft request' (duration: 159.251689ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:30.843402Z","caller":"traceutil/trace.go:171","msg":"trace[702385945] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/cephobjectrealms.ceph.rook.io; range_end:; response_count:0; response_revision:1596; }","duration":"167.484361ms","start":"2026-04-17T06:01:30.6759Z","end":"2026-04-17T06:01:30.843384Z","steps":["trace[702385945] 'agreement among raft nodes before linearized reading' (duration: 167.353677ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:02:32.340723Z","caller":"traceutil/trace.go:171","msg":"trace[303963622] transaction","detail":"{read_only:false; response_revision:2361; number_of_response:1; }","duration":"354.650922ms","start":"2026-04-17T06:02:31.986048Z","end":"2026-04-17T06:02:32.340699Z","steps":["trace[303963622] 'process raft request' (duration: 354.330593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:32.341048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:31.986029Z","time spent":"354.786626ms","remote":"127.0.0.1:59492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":413298,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:02:33.150129Z","caller":"traceutil/trace.go:171","msg":"trace[1755183882] transaction","detail":"{read_only:false; response_revision:2362; number_of_response:1; }","duration":"114.018852ms","start":"2026-04-17T06:02:33.036083Z","end":"2026-04-17T06:02:33.150102Z","steps":["trace[1755183882] 'process raft request' (duration: 113.808926ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:07:24.081741Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2332} {"level":"info","ts":"2026-04-17T06:07:24.144763Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2332,"took":"61.894765ms","hash":1633030020,"current-db-size-bytes":35708928,"current-db-size":"36 MB","current-db-size-in-use-bytes":13983744,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-04-17T06:07:24.144858Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1633030020,"revision":2332,"compact-revision":-1} {"level":"info","ts":"2026-04-17T06:12:24.089401Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3935} {"level":"info","ts":"2026-04-17T06:12:24.133076Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3935,"took":"42.039672ms","hash":2695696207,"current-db-size-bytes":35708928,"current-db-size":"36 MB","current-db-size-in-use-bytes":19324928,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-04-17T06:12:24.133155Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2695696207,"revision":3935,"compact-revision":2332} {"level":"info","ts":"2026-04-17T06:15:13.09789Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000229c.wal"} {"level":"info","ts":"2026-04-17T06:15:58.811671Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"be773dfc027af3eb","local-member-applied-index":10009,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-17T06:15:58.82456Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10009} {"level":"info","ts":"2026-04-17T06:15:58.824674Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5009} {"level":"info","ts":"2026-04-17T06:17:24.095564Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6429} {"level":"info","ts":"2026-04-17T06:17:24.16616Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6429,"took":"67.81537ms","hash":2866198543,"current-db-size-bytes":51871744,"current-db-size":"52 MB","current-db-size-in-use-bytes":46264320,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-04-17T06:17:24.166218Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2866198543,"revision":6429,"compact-revision":3935} {"level":"info","ts":"2026-04-17T06:21:18.736902Z","caller":"traceutil/trace.go:171","msg":"trace[1799199353] transaction","detail":"{read_only:false; response_revision:13107; number_of_response:1; }","duration":"118.81294ms","start":"2026-04-17T06:21:18.618055Z","end":"2026-04-17T06:21:18.736868Z","steps":["trace[1799199353] 'process raft request' (duration: 118.150631ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:21:19.284783Z","caller":"traceutil/trace.go:171","msg":"trace[405926157] transaction","detail":"{read_only:false; response_revision:13125; number_of_response:1; }","duration":"104.070424ms","start":"2026-04-17T06:21:19.180682Z","end":"2026-04-17T06:21:19.284752Z","steps":["trace[405926157] 'process raft request' (duration: 24.417716ms)","trace[405926157] 'compare' (duration: 78.97238ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:21:20.791623Z","caller":"traceutil/trace.go:171","msg":"trace[639547721] linearizableReadLoop","detail":"{readStateIndex:13605; appliedIndex:13604; }","duration":"144.748897ms","start":"2026-04-17T06:21:20.646847Z","end":"2026-04-17T06:21:20.791596Z","steps":["trace[639547721] 'read index received' (duration: 45.876579ms)","trace[639547721] 'applied index is now lower than readState.Index' (duration: 98.871578ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:21:20.796592Z","caller":"traceutil/trace.go:171","msg":"trace[863357576] transaction","detail":"{read_only:false; response_revision:13170; number_of_response:1; }","duration":"145.757527ms","start":"2026-04-17T06:21:20.646041Z","end":"2026-04-17T06:21:20.791798Z","steps":["trace[863357576] 'process raft request' (duration: 46.989622ms)","trace[863357576] 'compare' (duration: 98.214ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:21:20.796773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.880892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/routers.openstack.k-orc.cloud\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:21:20.796906Z","caller":"traceutil/trace.go:171","msg":"trace[1239930937] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/routers.openstack.k-orc.cloud; range_end:; response_count:0; response_revision:13170; }","duration":"150.076567ms","start":"2026-04-17T06:21:20.646804Z","end":"2026-04-17T06:21:20.796881Z","steps":["trace[1239930937] 'agreement among raft nodes before linearized reading' (duration: 149.879692ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:21:22.115257Z","caller":"traceutil/trace.go:171","msg":"trace[884392463] transaction","detail":"{read_only:false; response_revision:13233; number_of_response:1; }","duration":"119.420236ms","start":"2026-04-17T06:21:21.995802Z","end":"2026-04-17T06:21:22.115222Z","steps":["trace[884392463] 'process raft request' (duration: 109.808166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:21:23.253733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.847444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:21:23.253931Z","caller":"traceutil/trace.go:171","msg":"trace[628024589] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:13440; }","duration":"112.589045ms","start":"2026-04-17T06:21:23.14131Z","end":"2026-04-17T06:21:23.253899Z","steps":["trace[628024589] 'range keys from in-memory index tree' (duration: 111.71544ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:21:23.253901Z","caller":"traceutil/trace.go:171","msg":"trace[1758990769] transaction","detail":"{read_only:false; response_revision:13441; number_of_response:1; }","duration":"106.808192ms","start":"2026-04-17T06:21:23.147037Z","end":"2026-04-17T06:21:23.253846Z","steps":["trace[1758990769] 'process raft request' (duration: 76.903742ms)","trace[1758990769] 'compare' (duration: 29.649733ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:21:23.254664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.879272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:894489"} {"level":"info","ts":"2026-04-17T06:21:23.254783Z","caller":"traceutil/trace.go:171","msg":"trace[240170950] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:13440; }","duration":"113.059409ms","start":"2026-04-17T06:21:23.141699Z","end":"2026-04-17T06:21:23.254759Z","steps":["trace[240170950] 'range keys from in-memory index tree' (duration: 111.570146ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:22:24.102511Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10803} {"level":"info","ts":"2026-04-17T06:22:24.244744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10803,"took":"137.401939ms","hash":4278106102,"current-db-size-bytes":71024640,"current-db-size":"71 MB","current-db-size-in-use-bytes":42643456,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-04-17T06:22:24.24482Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4278106102,"revision":10803,"compact-revision":6429} {"level":"info","ts":"2026-04-17T06:27:24.109007Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14730} {"level":"info","ts":"2026-04-17T06:27:24.268322Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14730,"took":"153.953018ms","hash":1539588375,"current-db-size-bytes":71024640,"current-db-size":"71 MB","current-db-size-in-use-bytes":36753408,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-17T06:27:24.268402Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1539588375,"revision":14730,"compact-revision":10803} {"level":"info","ts":"2026-04-17T06:29:15.477559Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"be773dfc027af3eb","local-member-applied-index":20010,"local-member-snapshot-index":10009,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-17T06:29:15.482173Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20010} {"level":"info","ts":"2026-04-17T06:29:15.482331Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15010} {"level":"warn","ts":"2026-04-17T06:29:16.422917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.509641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T06:29:16.423021Z","caller":"traceutil/trace.go:171","msg":"trace[185856291] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:19439; }","duration":"146.675175ms","start":"2026-04-17T06:29:16.276326Z","end":"2026-04-17T06:29:16.423001Z","steps":["trace[185856291] 'count revisions from in-memory index tree' (duration: 146.418629ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:29:16.423305Z","caller":"traceutil/trace.go:171","msg":"trace[1393212665] transaction","detail":"{read_only:false; response_revision:19440; number_of_response:1; }","duration":"140.37573ms","start":"2026-04-17T06:29:16.282898Z","end":"2026-04-17T06:29:16.423274Z","steps":["trace[1393212665] 'process raft request' (duration: 119.948145ms)","trace[1393212665] 'compare' (duration: 19.742805ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:29:16.518381Z","caller":"traceutil/trace.go:171","msg":"trace[529773480] transaction","detail":"{read_only:false; response_revision:19441; number_of_response:1; }","duration":"158.159059ms","start":"2026-04-17T06:29:16.360187Z","end":"2026-04-17T06:29:16.518346Z","steps":["trace[529773480] 'process raft request' (duration: 157.918432ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:29:16.518336Z","caller":"traceutil/trace.go:171","msg":"trace[1089400151] linearizableReadLoop","detail":"{readStateIndex:20029; appliedIndex:20027; }","duration":"154.937558ms","start":"2026-04-17T06:29:16.363361Z","end":"2026-04-17T06:29:16.518298Z","steps":["trace[1089400151] 'read index received' (duration: 39.505681ms)","trace[1089400151] 'applied index is now lower than readState.Index' (duration: 115.430737ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:29:16.51858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.173216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T06:29:16.518624Z","caller":"traceutil/trace.go:171","msg":"trace[422662678] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:19441; }","duration":"155.30949ms","start":"2026-04-17T06:29:16.363297Z","end":"2026-04-17T06:29:16.518606Z","steps":["trace[422662678] 'agreement among raft nodes before linearized reading' (duration: 155.190897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:29:16.649358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.507094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-04-17T06:29:16.64945Z","caller":"traceutil/trace.go:171","msg":"trace[1146298623] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:19441; }","duration":"107.640037ms","start":"2026-04-17T06:29:16.541782Z","end":"2026-04-17T06:29:16.649423Z","steps":["trace[1146298623] 'range keys from in-memory index tree' (duration: 107.313559ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:29:16.905389Z","caller":"traceutil/trace.go:171","msg":"trace[1142090553] transaction","detail":"{read_only:false; response_revision:19442; number_of_response:1; }","duration":"170.812815ms","start":"2026-04-17T06:29:16.734555Z","end":"2026-04-17T06:29:16.905368Z","steps":["trace[1142090553] 'process raft request' (duration: 170.348601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:29:17.080578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.803197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-gjmxx\" ","response":"range_response_count:1 size:9712"} {"level":"info","ts":"2026-04-17T06:29:17.080657Z","caller":"traceutil/trace.go:171","msg":"trace[891646528] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-gjmxx; range_end:; response_count:1; response_revision:19442; }","duration":"105.896469ms","start":"2026-04-17T06:29:16.97474Z","end":"2026-04-17T06:29:17.080636Z","steps":["trace[891646528] 'range keys from in-memory index tree' (duration: 105.56393ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:29:46.533481Z","caller":"traceutil/trace.go:171","msg":"trace[302121349] transaction","detail":"{read_only:false; response_revision:19852; number_of_response:1; }","duration":"217.824768ms","start":"2026-04-17T06:29:46.315616Z","end":"2026-04-17T06:29:46.533441Z","steps":["trace[302121349] 'process raft request' (duration: 217.660744ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:29:46.728692Z","caller":"traceutil/trace.go:171","msg":"trace[1128747516] linearizableReadLoop","detail":"{readStateIndex:20450; appliedIndex:20449; }","duration":"126.86901ms","start":"2026-04-17T06:29:46.601796Z","end":"2026-04-17T06:29:46.728665Z","steps":["trace[1128747516] 'read index received' (duration: 126.764396ms)","trace[1128747516] 'applied index is now lower than readState.Index' (duration: 103.393µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:29:46.72889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.072296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-17T06:29:46.728961Z","caller":"traceutil/trace.go:171","msg":"trace[1119716901] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:19853; }","duration":"127.170058ms","start":"2026-04-17T06:29:46.601782Z","end":"2026-04-17T06:29:46.728952Z","steps":["trace[1119716901] 'agreement among raft nodes before linearized reading' (duration: 127.028185ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:29:46.72917Z","caller":"traceutil/trace.go:171","msg":"trace[2095512053] transaction","detail":"{read_only:false; response_revision:19853; number_of_response:1; }","duration":"136.450039ms","start":"2026-04-17T06:29:46.592316Z","end":"2026-04-17T06:29:46.728766Z","steps":["trace[2095512053] 'process raft request' (duration: 136.196001ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:29:46.905555Z","caller":"traceutil/trace.go:171","msg":"trace[948616539] linearizableReadLoop","detail":"{readStateIndex:20452; appliedIndex:20451; }","duration":"119.607775ms","start":"2026-04-17T06:29:46.785926Z","end":"2026-04-17T06:29:46.905534Z","steps":["trace[948616539] 'read index received' (duration: 92.675787ms)","trace[948616539] 'applied index is now lower than readState.Index' (duration: 26.930938ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:29:46.905623Z","caller":"traceutil/trace.go:171","msg":"trace[1922248083] transaction","detail":"{read_only:false; response_revision:19855; number_of_response:1; }","duration":"142.291864ms","start":"2026-04-17T06:29:46.7633Z","end":"2026-04-17T06:29:46.905592Z","steps":["trace[1922248083] 'process raft request' (duration: 115.307314ms)","trace[1922248083] 'compare' (duration: 26.725522ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:29:46.905712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.772819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T06:29:46.905747Z","caller":"traceutil/trace.go:171","msg":"trace[276277913] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:19855; }","duration":"119.821661ms","start":"2026-04-17T06:29:46.785919Z","end":"2026-04-17T06:29:46.905741Z","steps":["trace[276277913] 'agreement among raft nodes before linearized reading' (duration: 119.728308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:29:46.905756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.76986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T06:29:46.9058Z","caller":"traceutil/trace.go:171","msg":"trace[542420209] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:19855; }","duration":"119.835471ms","start":"2026-04-17T06:29:46.785955Z","end":"2026-04-17T06:29:46.90579Z","steps":["trace[542420209] 'agreement among raft nodes before linearized reading' (duration: 119.742519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:29:46.905988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.215356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T06:29:46.906013Z","caller":"traceutil/trace.go:171","msg":"trace[1649755864] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:19855; }","duration":"118.275517ms","start":"2026-04-17T06:29:46.787732Z","end":"2026-04-17T06:29:46.906008Z","steps":["trace[1649755864] 'agreement among raft nodes before linearized reading' (duration: 118.241686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:29:46.906069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.651287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T06:29:46.906104Z","caller":"traceutil/trace.go:171","msg":"trace[473166990] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:19855; }","duration":"119.686178ms","start":"2026-04-17T06:29:46.786409Z","end":"2026-04-17T06:29:46.906095Z","steps":["trace[473166990] 'agreement among raft nodes before linearized reading' (duration: 119.620026ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:29:48.460168Z","caller":"traceutil/trace.go:171","msg":"trace[819546779] linearizableReadLoop","detail":"{readStateIndex:20466; appliedIndex:20465; }","duration":"184.673285ms","start":"2026-04-17T06:29:48.275473Z","end":"2026-04-17T06:29:48.460147Z","steps":["trace[819546779] 'read index received' (duration: 129.547134ms)","trace[819546779] 'applied index is now lower than readState.Index' (duration: 55.121491ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:29:48.460291Z","caller":"traceutil/trace.go:171","msg":"trace[2092286627] transaction","detail":"{read_only:false; response_revision:19868; number_of_response:1; }","duration":"189.299956ms","start":"2026-04-17T06:29:48.270976Z","end":"2026-04-17T06:29:48.460276Z","steps":["trace[2092286627] 'process raft request' (duration: 133.973699ms)","trace[2092286627] 'compare' (duration: 55.06839ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:29:48.460645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.141198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T06:29:48.460807Z","caller":"traceutil/trace.go:171","msg":"trace[1895551947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19868; }","duration":"185.331504ms","start":"2026-04-17T06:29:48.275434Z","end":"2026-04-17T06:29:48.460765Z","steps":["trace[1895551947] 'agreement among raft nodes before linearized reading' (duration: 185.005425ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:29:48.630462Z","caller":"traceutil/trace.go:171","msg":"trace[1186815760] linearizableReadLoop","detail":"{readStateIndex:20467; appliedIndex:20466; }","duration":"170.197437ms","start":"2026-04-17T06:29:48.460243Z","end":"2026-04-17T06:29:48.63044Z","steps":["trace[1186815760] 'read index received' (duration: 113.828442ms)","trace[1186815760] 'applied index is now lower than readState.Index' (duration: 56.367705ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:29:48.630548Z","caller":"traceutil/trace.go:171","msg":"trace[36197176] transaction","detail":"{read_only:false; response_revision:19869; number_of_response:1; }","duration":"330.293161ms","start":"2026-04-17T06:29:48.300206Z","end":"2026-04-17T06:29:48.630499Z","steps":["trace[36197176] 'process raft request' (duration: 273.847783ms)","trace[36197176] 'compare' (duration: 56.272843ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:29:48.630659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.883392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-17T06:29:48.630703Z","caller":"traceutil/trace.go:171","msg":"trace[524783049] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19869; }","duration":"349.966174ms","start":"2026-04-17T06:29:48.280723Z","end":"2026-04-17T06:29:48.630689Z","steps":["trace[524783049] 'agreement among raft nodes before linearized reading' (duration: 349.81255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:29:48.630741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:29:48.280718Z","time spent":"350.014975ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T06:29:48.630755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:29:48.300196Z","time spent":"330.448405ms","remote":"127.0.0.1:51880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5416,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:29:48.633874Z","caller":"traceutil/trace.go:171","msg":"trace[1505378308] transaction","detail":"{read_only:false; response_revision:19870; number_of_response:1; }","duration":"168.259113ms","start":"2026-04-17T06:29:48.465565Z","end":"2026-04-17T06:29:48.633824Z","steps":["trace[1505378308] 'process raft request' (duration: 168.104869ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:31:07.793731Z","caller":"traceutil/trace.go:171","msg":"trace[1014250239] transaction","detail":"{read_only:false; response_revision:20829; number_of_response:1; }","duration":"118.168444ms","start":"2026-04-17T06:31:07.675533Z","end":"2026-04-17T06:31:07.793702Z","steps":["trace[1014250239] 'process raft request' (duration: 90.059653ms)","trace[1014250239] 'compare' (duration: 27.974087ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:32:24.11528Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18058} {"level":"info","ts":"2026-04-17T06:32:24.250186Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18058,"took":"130.009227ms","hash":2214322592,"current-db-size-bytes":71024640,"current-db-size":"71 MB","current-db-size-in-use-bytes":40394752,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-04-17T06:32:24.250263Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2214322592,"revision":18058,"compact-revision":14730} {"level":"info","ts":"2026-04-17T06:37:24.125726Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21643} {"level":"info","ts":"2026-04-17T06:37:24.272184Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21643,"took":"140.997011ms","hash":1994791820,"current-db-size-bytes":71024640,"current-db-size":"71 MB","current-db-size-in-use-bytes":33992704,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-17T06:37:24.27227Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1994791820,"revision":21643,"compact-revision":18058} {"level":"info","ts":"2026-04-17T06:42:01.576298Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068fb.wal"} {"level":"info","ts":"2026-04-17T06:42:24.133967Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24048} {"level":"info","ts":"2026-04-17T06:42:24.279997Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24048,"took":"138.906835ms","hash":831130428,"current-db-size-bytes":71024640,"current-db-size":"71 MB","current-db-size-in-use-bytes":32034816,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-17T06:42:24.280087Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":831130428,"revision":24048,"compact-revision":21643} {"level":"info","ts":"2026-04-17T06:47:24.140279Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26276} {"level":"info","ts":"2026-04-17T06:47:24.250949Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26276,"took":"106.051071ms","hash":1193567819,"current-db-size-bytes":71024640,"current-db-size":"71 MB","current-db-size-in-use-bytes":33005568,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-17T06:47:24.251006Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1193567819,"revision":26276,"compact-revision":24048}