{"level":"warn","ts":"2026-07-30T20:55:52.291541Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T20:55:52.291718Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.61: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.61:2380","--initial-cluster=instance=https://199.204.45.61:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.61:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.61: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-07-30T20:55:52.292368Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T20:55:52.292398Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.61:2380"]} {"level":"info","ts":"2026-07-30T20:55:52.292475Z","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-07-30T20:55:52.294205Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"]} {"level":"info","ts":"2026-07-30T20:55:52.29436Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.61:2380"],"listen-peer-urls":["https://199.204.45.61:2380"],"advertise-client-urls":["https://199.204.45.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.61: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-07-30T20:55:52.297794Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.048925ms"} {"level":"info","ts":"2026-07-30T20:55:52.305515Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca256638ea38eb43","cluster-id":"dfd7ac400ae20102"} {"level":"info","ts":"2026-07-30T20:55:52.305613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T20:55:52.305648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became follower at term 0"} {"level":"info","ts":"2026-07-30T20:55:52.305658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca256638ea38eb43 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T20:55:52.305668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became follower at term 1"} {"level":"info","ts":"2026-07-30T20:55:52.305719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=(14566160964433537859)"} {"level":"warn","ts":"2026-07-30T20:55:52.312887Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T20:55:52.314249Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T20:55:52.315402Z","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-07-30T20:55:52.318173Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca256638ea38eb43","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T20:55:52.318431Z","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-07-30T20:55:52.31851Z","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-07-30T20:55:52.318523Z","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-07-30T20:55:52.318495Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca256638ea38eb43","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T20:55:52.319518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=(14566160964433537859)"} {"level":"info","ts":"2026-07-30T20:55:52.319656Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfd7ac400ae20102","local-member-id":"ca256638ea38eb43","added-peer-id":"ca256638ea38eb43","added-peer-peer-urls":["https://199.204.45.61:2380"]} {"level":"info","ts":"2026-07-30T20:55:52.324321Z","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-07-30T20:55:52.324496Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.61:2380"} {"level":"info","ts":"2026-07-30T20:55:52.324666Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.61:2380"} {"level":"info","ts":"2026-07-30T20:55:52.324529Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca256638ea38eb43","initial-advertise-peer-urls":["https://199.204.45.61:2380"],"listen-peer-urls":["https://199.204.45.61:2380"],"advertise-client-urls":["https://199.204.45.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T20:55:52.324556Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T20:55:52.706532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T20:55:52.706591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T20:55:52.706676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 received MsgPreVoteResp from ca256638ea38eb43 at term 1"} {"level":"info","ts":"2026-07-30T20:55:52.706705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became candidate at term 2"} {"level":"info","ts":"2026-07-30T20:55:52.70675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 received MsgVoteResp from ca256638ea38eb43 at term 2"} {"level":"info","ts":"2026-07-30T20:55:52.706795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became leader at term 2"} {"level":"info","ts":"2026-07-30T20:55:52.706815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca256638ea38eb43 elected leader ca256638ea38eb43 at term 2"} {"level":"info","ts":"2026-07-30T20:55:52.707961Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca256638ea38eb43","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.61:2379]}","request-path":"/0/members/ca256638ea38eb43/attributes","cluster-id":"dfd7ac400ae20102","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T20:55:52.708048Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:55:52.708281Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T20:55:52.708815Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T20:55:52.709056Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T20:55:52.709079Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T20:55:52.709258Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfd7ac400ae20102","local-member-id":"ca256638ea38eb43","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:55:52.709436Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:55:52.709479Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:55:52.711862Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.61:2379"} {"level":"info","ts":"2026-07-30T20:55:52.713016Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T20:55:55.33166Z","caller":"traceutil/trace.go:171","msg":"trace[1873850026] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"131.771942ms","start":"2026-07-30T20:55:55.19985Z","end":"2026-07-30T20:55:55.331622Z","steps":["trace[1873850026] 'process raft request' (duration: 98.964774ms)","trace[1873850026] 'compare' (duration: 32.563552ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:55:55.535651Z","caller":"traceutil/trace.go:171","msg":"trace[911702229] linearizableReadLoop","detail":"{readStateIndex:87; appliedIndex:86; }","duration":"128.317906ms","start":"2026-07-30T20:55:55.407309Z","end":"2026-07-30T20:55:55.535627Z","steps":["trace[911702229] 'read index received' (duration: 63.711686ms)","trace[911702229] 'applied index is now lower than readState.Index' (duration: 64.60554ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:55:55.535802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.453711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-30T20:55:55.535785Z","caller":"traceutil/trace.go:171","msg":"trace[1423658674] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"132.366666ms","start":"2026-07-30T20:55:55.403345Z","end":"2026-07-30T20:55:55.535712Z","steps":["trace[1423658674] 'process raft request' (duration: 67.634263ms)","trace[1423658674] 'compare' (duration: 64.545238ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:55:55.535857Z","caller":"traceutil/trace.go:171","msg":"trace[1489173407] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:82; }","duration":"128.583573ms","start":"2026-07-30T20:55:55.407261Z","end":"2026-07-30T20:55:55.535844Z","steps":["trace[1489173407] 'agreement among raft nodes before linearized reading' (duration: 128.444129ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:55:55.683451Z","caller":"traceutil/trace.go:171","msg":"trace[966314713] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"143.882719ms","start":"2026-07-30T20:55:55.539547Z","end":"2026-07-30T20:55:55.68343Z","steps":["trace[966314713] 'process raft request' (duration: 143.837528ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:55:55.683623Z","caller":"traceutil/trace.go:171","msg":"trace[757594761] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"145.106611ms","start":"2026-07-30T20:55:55.538344Z","end":"2026-07-30T20:55:55.68345Z","steps":["trace[757594761] 'process raft request' (duration: 139.088663ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:55:56.987604Z","caller":"traceutil/trace.go:171","msg":"trace[619498525] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"105.167728ms","start":"2026-07-30T20:55:56.882408Z","end":"2026-07-30T20:55:56.987576Z","steps":["trace[619498525] 'process raft request' (duration: 105.009144ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:55:56.987665Z","caller":"traceutil/trace.go:171","msg":"trace[263529052] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"105.779083ms","start":"2026-07-30T20:55:56.881836Z","end":"2026-07-30T20:55:56.987615Z","steps":["trace[263529052] 'process raft request' (duration: 77.221771ms)","trace[263529052] 'compare' (duration: 28.161793ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:55:57.440793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.225708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:55:57.440912Z","caller":"traceutil/trace.go:171","msg":"trace[701963103] linearizableReadLoop","detail":"{readStateIndex:222; appliedIndex:221; }","duration":"349.011577ms","start":"2026-07-30T20:55:57.091885Z","end":"2026-07-30T20:55:57.440896Z","steps":["trace[701963103] 'read index received' (duration: 204.05775ms)","trace[701963103] 'applied index is now lower than readState.Index' (duration: 144.952727ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:55:57.440991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.101549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:55:57.44102Z","caller":"traceutil/trace.go:171","msg":"trace[1351330841] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:215; }","duration":"349.191131ms","start":"2026-07-30T20:55:57.091821Z","end":"2026-07-30T20:55:57.441012Z","steps":["trace[1351330841] 'agreement among raft nodes before linearized reading' (duration: 349.118979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:55:57.441062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:55:57.091795Z","time spent":"349.258693ms","remote":"127.0.0.1:46478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" "} {"level":"info","ts":"2026-07-30T20:55:57.441243Z","caller":"traceutil/trace.go:171","msg":"trace[151766473] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"350.100703ms","start":"2026-07-30T20:55:57.091079Z","end":"2026-07-30T20:55:57.441179Z","steps":["trace[151766473] 'process raft request' (duration: 204.953032ms)","trace[151766473] 'compare' (duration: 144.086704ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:55:57.441599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:55:57.091052Z","time spent":"350.40187ms","remote":"127.0.0.1:46590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":330,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T20:55:57.830068Z","caller":"traceutil/trace.go:171","msg":"trace[910200159] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"382.662453ms","start":"2026-07-30T20:55:57.447381Z","end":"2026-07-30T20:55:57.830044Z","steps":["trace[910200159] 'process raft request' (duration: 382.615892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:55:57.830257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:55:57.447356Z","time spent":"382.834879ms","remote":"127.0.0.1:46478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":184,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T20:55:57.830098Z","caller":"traceutil/trace.go:171","msg":"trace[1817327333] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"384.615093ms","start":"2026-07-30T20:55:57.445455Z","end":"2026-07-30T20:55:57.83007Z","steps":["trace[1817327333] 'process raft request' (duration: 301.880087ms)","trace[1817327333] 'compare' (duration: 82.51768ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:55:57.830431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:55:57.445445Z","time spent":"384.91659ms","remote":"127.0.0.1:46602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T20:56:02.059713Z","caller":"traceutil/trace.go:171","msg":"trace[555359394] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"112.709583ms","start":"2026-07-30T20:56:01.946963Z","end":"2026-07-30T20:56:02.059672Z","steps":["trace[555359394] 'process raft request' (duration: 107.086584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:19.200643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.20252ms","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-07-30T20:56:19.200727Z","caller":"traceutil/trace.go:171","msg":"trace[1585670690] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:467; }","duration":"138.344213ms","start":"2026-07-30T20:56:19.062365Z","end":"2026-07-30T20:56:19.200709Z","steps":["trace[1585670690] 'range keys from in-memory index tree' (duration: 138.072367ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:19.458747Z","caller":"traceutil/trace.go:171","msg":"trace[1506584119] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"254.505571ms","start":"2026-07-30T20:56:19.204219Z","end":"2026-07-30T20:56:19.458724Z","steps":["trace[1506584119] 'process raft request' (duration: 254.383298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:19.9717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.461021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:56:19.971821Z","caller":"traceutil/trace.go:171","msg":"trace[1826833413] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:489; }","duration":"215.609124ms","start":"2026-07-30T20:56:19.756202Z","end":"2026-07-30T20:56:19.971811Z","steps":["trace[1826833413] 'read index received' (duration: 51.801µs)","trace[1826833413] 'applied index is now lower than readState.Index' (duration: 215.556423ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:56:19.97189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.694986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:56:19.971912Z","caller":"traceutil/trace.go:171","msg":"trace[2017395292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"215.746658ms","start":"2026-07-30T20:56:19.756158Z","end":"2026-07-30T20:56:19.971905Z","steps":["trace[2017395292] 'agreement among raft nodes before linearized reading' (duration: 215.694346ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:19.972034Z","caller":"traceutil/trace.go:171","msg":"trace[1601135322] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"767.587603ms","start":"2026-07-30T20:56:19.204439Z","end":"2026-07-30T20:56:19.972026Z","steps":["trace[1601135322] 'process raft request' (duration: 314.575009ms)","trace[1601135322] 'compare' (duration: 452.393779ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:56:19.972072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:19.204425Z","time spent":"767.627974ms","remote":"127.0.0.1:46554","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-07-30T20:56:19.972207Z","caller":"traceutil/trace.go:171","msg":"trace[226028711] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"767.238025ms","start":"2026-07-30T20:56:19.204963Z","end":"2026-07-30T20:56:19.972201Z","steps":["trace[226028711] 'process raft request' (duration: 766.809473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:19.972315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:19.204935Z","time spent":"767.358258ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:56:20.882036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.249552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:56:20.882149Z","caller":"traceutil/trace.go:171","msg":"trace[164015854] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"123.687602ms","start":"2026-07-30T20:56:20.758448Z","end":"2026-07-30T20:56:20.882135Z","steps":["trace[164015854] 'read index received' (duration: 60.691µs)","trace[164015854] 'applied index is now lower than readState.Index' (duration: 123.625291ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:56:20.88224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.793846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:56:20.882269Z","caller":"traceutil/trace.go:171","msg":"trace[1124471477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"123.855906ms","start":"2026-07-30T20:56:20.758406Z","end":"2026-07-30T20:56:20.882262Z","steps":["trace[1124471477] 'agreement among raft nodes before linearized reading' (duration: 123.796765ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:20.88227Z","caller":"traceutil/trace.go:171","msg":"trace[1169248960] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"872.429253ms","start":"2026-07-30T20:56:20.009804Z","end":"2026-07-30T20:56:20.882233Z","steps":["trace[1169248960] 'process raft request' (duration: 70.905635ms)","trace[1169248960] 'compare' (duration: 801.091237ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:56:20.882438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:20.009775Z","time spent":"872.572076ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:56:32.654963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569021283759559,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T20:56:32.672526Z","caller":"traceutil/trace.go:171","msg":"trace[275869769] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"539.515562ms","start":"2026-07-30T20:56:32.132963Z","end":"2026-07-30T20:56:32.672479Z","steps":["trace[275869769] 'process raft request' (duration: 529.097436ms)","trace[275869769] 'compare' (duration: 10.223142ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:56:32.672735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.13288Z","time spent":"539.758599ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:56:32.676243Z","caller":"traceutil/trace.go:171","msg":"trace[1088569420] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:595; }","duration":"522.319509ms","start":"2026-07-30T20:56:32.15391Z","end":"2026-07-30T20:56:32.676229Z","steps":["trace[1088569420] 'read index received' (duration: 508.170231ms)","trace[1088569420] 'applied index is now lower than readState.Index' (duration: 14.148448ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:56:32.676366Z","caller":"traceutil/trace.go:171","msg":"trace[1530297301] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"542.042904ms","start":"2026-07-30T20:56:32.134314Z","end":"2026-07-30T20:56:32.676357Z","steps":["trace[1530297301] 'process raft request' (duration: 541.611674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:32.676473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.134304Z","time spent":"542.115177ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T20:56:32.676592Z","caller":"traceutil/trace.go:171","msg":"trace[1750420362] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"534.53078ms","start":"2026-07-30T20:56:32.142052Z","end":"2026-07-30T20:56:32.676583Z","steps":["trace[1750420362] 'process raft request' (duration: 534.050307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:32.676685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.142028Z","time spent":"534.624483ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:56:32.676839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.922875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:56:32.676883Z","caller":"traceutil/trace.go:171","msg":"trace[657161753] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:571; }","duration":"522.969756ms","start":"2026-07-30T20:56:32.153905Z","end":"2026-07-30T20:56:32.676875Z","steps":["trace[657161753] 'agreement among raft nodes before linearized reading' (duration: 522.904094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:32.676911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.153875Z","time spent":"523.029868ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-30T20:56:32.676944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.390765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:56:32.677023Z","caller":"traceutil/trace.go:171","msg":"trace[375350233] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:571; }","duration":"451.516768ms","start":"2026-07-30T20:56:32.225488Z","end":"2026-07-30T20:56:32.677005Z","steps":["trace[375350233] 'agreement among raft nodes before linearized reading' (duration: 451.415166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:32.677098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.225465Z","time spent":"451.6213ms","remote":"127.0.0.1:54082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-07-30T20:56:32.677085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.872543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:56:32.677112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.255423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:56:32.677204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.459151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:56:32.677232Z","caller":"traceutil/trace.go:171","msg":"trace[1226577777] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:571; }","duration":"478.502472ms","start":"2026-07-30T20:56:32.198722Z","end":"2026-07-30T20:56:32.677225Z","steps":["trace[1226577777] 'agreement among raft nodes before linearized reading' (duration: 478.459151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:32.677261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.198712Z","time spent":"478.542503ms","remote":"127.0.0.1:54080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-07-30T20:56:32.677473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.184689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:56:32.677489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.306194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:56:32.677519Z","caller":"traceutil/trace.go:171","msg":"trace[858753060] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:571; }","duration":"455.273381ms","start":"2026-07-30T20:56:32.222233Z","end":"2026-07-30T20:56:32.677506Z","steps":["trace[858753060] 'agreement among raft nodes before linearized reading' (duration: 455.21228ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:32.677542Z","caller":"traceutil/trace.go:171","msg":"trace[1773910367] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:571; }","duration":"423.378986ms","start":"2026-07-30T20:56:32.254141Z","end":"2026-07-30T20:56:32.67752Z","steps":["trace[1773910367] 'agreement among raft nodes before linearized reading' (duration: 423.308384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:32.677555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.22221Z","time spent":"455.335712ms","remote":"127.0.0.1:54082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-30T20:56:32.677629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.945045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:56:32.677639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.728095ms","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-07-30T20:56:32.677664Z","caller":"traceutil/trace.go:171","msg":"trace[1735714460] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:571; }","duration":"496.996506ms","start":"2026-07-30T20:56:32.180657Z","end":"2026-07-30T20:56:32.677654Z","steps":["trace[1735714460] 'agreement among raft nodes before linearized reading' (duration: 496.927434ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:32.677678Z","caller":"traceutil/trace.go:171","msg":"trace[1923785633] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:571; }","duration":"497.780416ms","start":"2026-07-30T20:56:32.179886Z","end":"2026-07-30T20:56:32.677666Z","steps":["trace[1923785633] 'agreement among raft nodes before linearized reading' (duration: 497.712725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:32.677692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.18065Z","time spent":"497.036878ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"warn","ts":"2026-07-30T20:56:32.677643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.420468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:56:32.677714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.17988Z","time spent":"497.826567ms","remote":"127.0.0.1:54068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-07-30T20:56:32.677766Z","caller":"traceutil/trace.go:171","msg":"trace[661271828] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:571; }","duration":"395.574012ms","start":"2026-07-30T20:56:32.282164Z","end":"2026-07-30T20:56:32.677738Z","steps":["trace[661271828] 'agreement among raft nodes before linearized reading' (duration: 395.447659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:32.677749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:56:32.677715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.545628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:56:32.677414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.51513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:56:32.677902Z","caller":"traceutil/trace.go:171","msg":"trace[165388144] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:571; }","duration":"498.216027ms","start":"2026-07-30T20:56:32.179662Z","end":"2026-07-30T20:56:32.677878Z","steps":["trace[165388144] 'agreement among raft nodes before linearized reading' (duration: 497.202843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:32.677927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.17964Z","time spent":"498.279188ms","remote":"127.0.0.1:54068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-30T20:56:32.677934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.014132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:56:32.677963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.728474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:56:32.677986Z","caller":"traceutil/trace.go:171","msg":"trace[1982702174] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:571; }","duration":"396.112395ms","start":"2026-07-30T20:56:32.281867Z","end":"2026-07-30T20:56:32.677979Z","steps":["trace[1982702174] 'agreement among raft nodes before linearized reading' (duration: 396.022363ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:32.678032Z","caller":"traceutil/trace.go:171","msg":"trace[2090034047] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:571; }","duration":"423.804616ms","start":"2026-07-30T20:56:32.254193Z","end":"2026-07-30T20:56:32.677997Z","steps":["trace[2090034047] 'agreement among raft nodes before linearized reading' (duration: 422.967756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:32.678051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.281849Z","time spent":"396.193276ms","remote":"127.0.0.1:54096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-30T20:56:32.678126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.254169Z","time spent":"423.891019ms","remote":"127.0.0.1:54086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"info","ts":"2026-07-30T20:56:32.677202Z","caller":"traceutil/trace.go:171","msg":"trace[459054059] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:571; }","duration":"395.455098ms","start":"2026-07-30T20:56:32.281727Z","end":"2026-07-30T20:56:32.677183Z","steps":["trace[459054059] 'agreement among raft nodes before linearized reading' (duration: 395.297075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:32.678178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.281698Z","time spent":"396.464203ms","remote":"127.0.0.1:54096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"info","ts":"2026-07-30T20:56:32.677154Z","caller":"traceutil/trace.go:171","msg":"trace[473250144] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:571; }","duration":"422.960425ms","start":"2026-07-30T20:56:32.254179Z","end":"2026-07-30T20:56:32.677139Z","steps":["trace[473250144] 'agreement among raft nodes before linearized reading' (duration: 422.856073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:32.678297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.254176Z","time spent":"424.108073ms","remote":"127.0.0.1:54086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-07-30T20:56:32.678278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.67487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:56:32.677821Z","caller":"traceutil/trace.go:171","msg":"trace[556427685] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:571; }","duration":"479.015535ms","start":"2026-07-30T20:56:32.198798Z","end":"2026-07-30T20:56:32.677813Z","steps":["trace[556427685] 'agreement among raft nodes before linearized reading' (duration: 478.935853ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:32.677854Z","caller":"traceutil/trace.go:171","msg":"trace[522196439] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:571; }","duration":"455.707311ms","start":"2026-07-30T20:56:32.222137Z","end":"2026-07-30T20:56:32.677844Z","steps":["trace[522196439] 'agreement among raft nodes before linearized reading' (duration: 455.547938ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:32.678539Z","caller":"traceutil/trace.go:171","msg":"trace[1503948609] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:571; }","duration":"479.908046ms","start":"2026-07-30T20:56:32.198506Z","end":"2026-07-30T20:56:32.678414Z","steps":["trace[1503948609] 'agreement among raft nodes before linearized reading' (duration: 479.000414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:32.678599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.222128Z","time spent":"456.452029ms","remote":"127.0.0.1:54082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-30T20:56:32.678709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.198488Z","time spent":"480.112341ms","remote":"127.0.0.1:54080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-07-30T20:56:32.677829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.282155Z","time spent":"395.646903ms","remote":"127.0.0.1:54096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-07-30T20:56:32.678443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.198764Z","time spent":"479.667961ms","remote":"127.0.0.1:54080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-07-30T20:56:32.679899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.254124Z","time spent":"423.449448ms","remote":"127.0.0.1:54086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-07-30T20:56:32.68062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.921075ms","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-07-30T20:56:32.680692Z","caller":"traceutil/trace.go:171","msg":"trace[1623851893] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:571; }","duration":"526.80108ms","start":"2026-07-30T20:56:32.153872Z","end":"2026-07-30T20:56:32.680673Z","steps":["trace[1623851893] 'agreement among raft nodes before linearized reading' (duration: 522.914895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:32.686278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:32.15385Z","time spent":"532.401607ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-30T20:56:39.009052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.081815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:56:39.009144Z","caller":"traceutil/trace.go:171","msg":"trace[838737486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"253.213869ms","start":"2026-07-30T20:56:38.755911Z","end":"2026-07-30T20:56:39.009125Z","steps":["trace[838737486] 'range keys from in-memory index tree' (duration: 253.003214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:39.625792Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569021283759713,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T20:56:39.871859Z","caller":"traceutil/trace.go:171","msg":"trace[2049386880] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"746.384242ms","start":"2026-07-30T20:56:39.125369Z","end":"2026-07-30T20:56:39.871754Z","steps":["trace[2049386880] 'read index received' (duration: 739.81429ms)","trace[2049386880] 'applied index is now lower than readState.Index' (duration: 6.566822ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:56:39.872292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.588369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:56:39.872276Z","caller":"traceutil/trace.go:171","msg":"trace[1607698411] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"781.257469ms","start":"2026-07-30T20:56:39.090976Z","end":"2026-07-30T20:56:39.872233Z","steps":["trace[1607698411] 'process raft request' (duration: 774.950724ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:39.872381Z","caller":"traceutil/trace.go:171","msg":"trace[36008374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"114.719672ms","start":"2026-07-30T20:56:39.757638Z","end":"2026-07-30T20:56:39.872358Z","steps":["trace[36008374] 'agreement among raft nodes before linearized reading' (duration: 114.576199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:39.872376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.981226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-30T20:56:39.872433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.078754ms","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-07-30T20:56:39.872477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:39.090937Z","time spent":"781.451164ms","remote":"127.0.0.1:46366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:56:39.872482Z","caller":"traceutil/trace.go:171","msg":"trace[2119956224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"747.115949ms","start":"2026-07-30T20:56:39.125326Z","end":"2026-07-30T20:56:39.872442Z","steps":["trace[2119956224] 'agreement among raft nodes before linearized reading' (duration: 746.859903ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:39.872531Z","caller":"traceutil/trace.go:171","msg":"trace[270620034] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"285.160096ms","start":"2026-07-30T20:56:39.587328Z","end":"2026-07-30T20:56:39.872488Z","steps":["trace[270620034] 'agreement among raft nodes before linearized reading' (duration: 284.894129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:39.872536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:39.125301Z","time spent":"747.223551ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T20:56:40.38478Z","caller":"traceutil/trace.go:171","msg":"trace[851779347] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"505.541296ms","start":"2026-07-30T20:56:39.879214Z","end":"2026-07-30T20:56:40.384756Z","steps":["trace[851779347] 'process raft request' (duration: 438.254091ms)","trace[851779347] 'compare' (duration: 66.911116ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:56:40.384928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:39.879186Z","time spent":"505.661569ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:56:40.416024Z","caller":"traceutil/trace.go:171","msg":"trace[1354847658] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"535.123755ms","start":"2026-07-30T20:56:39.880875Z","end":"2026-07-30T20:56:40.415999Z","steps":["trace[1354847658] 'process raft request' (duration: 534.93663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:40.416136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:39.88086Z","time spent":"535.214277ms","remote":"127.0.0.1:46554","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-07-30T20:56:40.416157Z","caller":"traceutil/trace.go:171","msg":"trace[332329793] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:671; }","duration":"312.265032ms","start":"2026-07-30T20:56:40.103878Z","end":"2026-07-30T20:56:40.416143Z","steps":["trace[332329793] 'read index received' (duration: 213.541804ms)","trace[332329793] 'applied index is now lower than readState.Index' (duration: 98.721978ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:56:40.416183Z","caller":"traceutil/trace.go:171","msg":"trace[1728004596] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"490.811025ms","start":"2026-07-30T20:56:39.925345Z","end":"2026-07-30T20:56:40.416156Z","steps":["trace[1728004596] 'process raft request' (duration: 490.596999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:40.416309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:39.925325Z","time spent":"490.925857ms","remote":"127.0.0.1:46528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T20:56:40.416345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.367844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-30T20:56:40.416386Z","caller":"traceutil/trace.go:171","msg":"trace[1890268046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"312.438246ms","start":"2026-07-30T20:56:40.103936Z","end":"2026-07-30T20:56:40.416374Z","steps":["trace[1890268046] 'agreement among raft nodes before linearized reading' (duration: 312.293612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:40.416415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:40.103914Z","time spent":"312.492388ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T20:56:40.416542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.652061ms","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-07-30T20:56:40.416729Z","caller":"traceutil/trace.go:171","msg":"trace[1271608055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"312.802076ms","start":"2026-07-30T20:56:40.103829Z","end":"2026-07-30T20:56:40.416632Z","steps":["trace[1271608055] 'agreement among raft nodes before linearized reading' (duration: 312.380895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:40.416857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:40.103804Z","time spent":"313.034951ms","remote":"127.0.0.1:46554","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-07-30T20:56:41.25889Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569021283759727,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:56:41.759074Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569021283759727,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:56:42.259833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569021283759727,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:56:42.357532Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.909003424s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T20:56:42.358042Z","caller":"traceutil/trace.go:171","msg":"trace[1324045527] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.90962933s","start":"2026-07-30T20:56:40.448389Z","end":"2026-07-30T20:56:42.358019Z","steps":["trace[1324045527] 'process raft request' (duration: 1.909495715s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:42.358184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:40.448362Z","time spent":"1.909735663s","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T20:56:42.378174Z","caller":"traceutil/trace.go:171","msg":"trace[772752892] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"928.567094ms","start":"2026-07-30T20:56:41.449571Z","end":"2026-07-30T20:56:42.378138Z","steps":["trace[772752892] 'process raft request' (duration: 928.281518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:42.378392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:41.449543Z","time spent":"928.708778ms","remote":"127.0.0.1:46554","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-07-30T20:56:42.620828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.801532ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-30T20:56:42.620995Z","caller":"traceutil/trace.go:171","msg":"trace[1299873117] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:676; }","duration":"1.863125646s","start":"2026-07-30T20:56:40.75785Z","end":"2026-07-30T20:56:42.620975Z","steps":["trace[1299873117] 'read index received' (duration: 1.600030803s)","trace[1299873117] 'applied index is now lower than readState.Index' (duration: 263.092513ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:56:42.621144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.863292189s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:56:42.621161Z","caller":"traceutil/trace.go:171","msg":"trace[266720218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"1.863367561s","start":"2026-07-30T20:56:40.757787Z","end":"2026-07-30T20:56:42.621154Z","steps":["trace[266720218] 'agreement among raft nodes before linearized reading' (duration: 1.863293029s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:42.62119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:40.757763Z","time spent":"1.863420913s","remote":"127.0.0.1:46284","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-07-30T20:56:42.668093Z","caller":"traceutil/trace.go:171","msg":"trace[1849573778] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"305.184018ms","start":"2026-07-30T20:56:42.362874Z","end":"2026-07-30T20:56:42.668058Z","steps":["trace[1849573778] 'process raft request' (duration: 304.961683ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:42.668125Z","caller":"traceutil/trace.go:171","msg":"trace[820985154] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"304.929332ms","start":"2026-07-30T20:56:42.363171Z","end":"2026-07-30T20:56:42.6681Z","steps":["trace[820985154] 'process raft request' (duration: 304.803719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:42.66825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:42.362843Z","time spent":"305.326982ms","remote":"127.0.0.1:46352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T20:56:42.66826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:42.363153Z","time spent":"305.038055ms","remote":"127.0.0.1:46352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T20:56:42.668322Z","caller":"traceutil/trace.go:171","msg":"trace[95921737] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"304.241694ms","start":"2026-07-30T20:56:42.364065Z","end":"2026-07-30T20:56:42.668306Z","steps":["trace[95921737] 'process raft request' (duration: 303.948597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:42.668473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:42.364036Z","time spent":"304.324387ms","remote":"127.0.0.1:46528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:56:42.668616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.271172ms","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-07-30T20:56:42.66866Z","caller":"traceutil/trace.go:171","msg":"trace[1643522589] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:653; }","duration":"249.374845ms","start":"2026-07-30T20:56:42.419273Z","end":"2026-07-30T20:56:42.668648Z","steps":["trace[1643522589] 'agreement among raft nodes before linearized reading' (duration: 249.18274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:42.66889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.761299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:56:42.668943Z","caller":"traceutil/trace.go:171","msg":"trace[1936207543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"800.853543ms","start":"2026-07-30T20:56:41.868078Z","end":"2026-07-30T20:56:42.668931Z","steps":["trace[1936207543] 'agreement among raft nodes before linearized reading' (duration: 800.7814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:42.668979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:41.868057Z","time spent":"800.915894ms","remote":"127.0.0.1:46284","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-07-30T20:56:42.668972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.728542ms","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-07-30T20:56:42.668994Z","caller":"traceutil/trace.go:171","msg":"trace[1597644152] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"303.310132ms","start":"2026-07-30T20:56:42.365643Z","end":"2026-07-30T20:56:42.668953Z","steps":["trace[1597644152] 'process raft request' (duration: 302.4235ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:42.669026Z","caller":"traceutil/trace.go:171","msg":"trace[4558835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:653; }","duration":"279.813773ms","start":"2026-07-30T20:56:42.389196Z","end":"2026-07-30T20:56:42.66901Z","steps":["trace[4558835] 'agreement among raft nodes before linearized reading' (duration: 279.658199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:42.669077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:42.365633Z","time spent":"303.387695ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:56:42.669283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.289186ms","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-07-30T20:56:42.66932Z","caller":"traceutil/trace.go:171","msg":"trace[726756692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:653; }","duration":"219.339327ms","start":"2026-07-30T20:56:42.449971Z","end":"2026-07-30T20:56:42.66931Z","steps":["trace[726756692] 'agreement among raft nodes before linearized reading' (duration: 219.234444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:42.669351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.07513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-30T20:56:42.669384Z","caller":"traceutil/trace.go:171","msg":"trace[777290073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:653; }","duration":"232.147921ms","start":"2026-07-30T20:56:42.437227Z","end":"2026-07-30T20:56:42.669374Z","steps":["trace[777290073] 'agreement among raft nodes before linearized reading' (duration: 232.052149ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:42.776855Z","caller":"traceutil/trace.go:171","msg":"trace[1565126030] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"102.909132ms","start":"2026-07-30T20:56:42.673922Z","end":"2026-07-30T20:56:42.776831Z","steps":["trace[1565126030] 'process raft request' (duration: 100.662117ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:42.777596Z","caller":"traceutil/trace.go:171","msg":"trace[209141345] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"101.158548ms","start":"2026-07-30T20:56:42.676411Z","end":"2026-07-30T20:56:42.777569Z","steps":["trace[209141345] 'process raft request' (duration: 101.109867ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:42.777628Z","caller":"traceutil/trace.go:171","msg":"trace[143668457] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"101.893826ms","start":"2026-07-30T20:56:42.675707Z","end":"2026-07-30T20:56:42.7776Z","steps":["trace[143668457] 'process raft request' (duration: 101.341893ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:42.777721Z","caller":"traceutil/trace.go:171","msg":"trace[1296419200] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"101.938777ms","start":"2026-07-30T20:56:42.675757Z","end":"2026-07-30T20:56:42.777696Z","steps":["trace[1296419200] 'process raft request' (duration: 101.704712ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:43.769907Z","caller":"traceutil/trace.go:171","msg":"trace[197394474] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"227.821495ms","start":"2026-07-30T20:56:43.542061Z","end":"2026-07-30T20:56:43.769882Z","steps":["trace[197394474] 'process raft request' (duration: 227.64979ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:46.638803Z","caller":"traceutil/trace.go:171","msg":"trace[235242051] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:731; }","duration":"307.022924ms","start":"2026-07-30T20:56:46.331685Z","end":"2026-07-30T20:56:46.638708Z","steps":["trace[235242051] 'read index received' (duration: 262.261453ms)","trace[235242051] 'applied index is now lower than readState.Index' (duration: 44.759871ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:56:46.638831Z","caller":"traceutil/trace.go:171","msg":"trace[746295152] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"388.092898ms","start":"2026-07-30T20:56:46.250692Z","end":"2026-07-30T20:56:46.638785Z","steps":["trace[746295152] 'process raft request' (duration: 343.240844ms)","trace[746295152] 'compare' (duration: 44.637349ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:56:46.638978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.28242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:56:46.639015Z","caller":"traceutil/trace.go:171","msg":"trace[1128220723] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:702; }","duration":"307.394652ms","start":"2026-07-30T20:56:46.33161Z","end":"2026-07-30T20:56:46.639004Z","steps":["trace[1128220723] 'agreement among raft nodes before linearized reading' (duration: 307.209428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:46.639038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:46.331577Z","time spent":"307.454904ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-07-30T20:56:46.639079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:46.250624Z","time spent":"388.385905ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:56:46.641408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.382452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:56:46.641436Z","caller":"traceutil/trace.go:171","msg":"trace[439763556] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:703; }","duration":"109.437613ms","start":"2026-07-30T20:56:46.53199Z","end":"2026-07-30T20:56:46.641428Z","steps":["trace[439763556] 'agreement among raft nodes before linearized reading' (duration: 109.371831ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:46.802953Z","caller":"traceutil/trace.go:171","msg":"trace[18416502] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"144.426573ms","start":"2026-07-30T20:56:46.65848Z","end":"2026-07-30T20:56:46.802907Z","steps":["trace[18416502] 'process raft request' (duration: 81.348581ms)","trace[18416502] 'compare' (duration: 62.828346ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:56:47.106511Z","caller":"traceutil/trace.go:171","msg":"trace[1690176442] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"168.174167ms","start":"2026-07-30T20:56:46.938314Z","end":"2026-07-30T20:56:47.106488Z","steps":["trace[1690176442] 'process raft request' (duration: 167.988741ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:47.248853Z","caller":"traceutil/trace.go:171","msg":"trace[1550969422] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:741; }","duration":"117.10324ms","start":"2026-07-30T20:56:47.131736Z","end":"2026-07-30T20:56:47.248839Z","steps":["trace[1550969422] 'read index received' (duration: 27.010874ms)","trace[1550969422] 'applied index is now lower than readState.Index' (duration: 90.091436ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:56:47.248891Z","caller":"traceutil/trace.go:171","msg":"trace[1961746708] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"129.464765ms","start":"2026-07-30T20:56:47.119413Z","end":"2026-07-30T20:56:47.248878Z","steps":["trace[1961746708] 'process raft request' (duration: 129.294801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:47.24918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.434648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-07-30T20:56:47.24922Z","caller":"traceutil/trace.go:171","msg":"trace[454929007] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:712; }","duration":"117.516581ms","start":"2026-07-30T20:56:47.131687Z","end":"2026-07-30T20:56:47.249204Z","steps":["trace[454929007] 'agreement among raft nodes before linearized reading' (duration: 117.226303ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:47.572629Z","caller":"traceutil/trace.go:171","msg":"trace[450404681] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"325.639762ms","start":"2026-07-30T20:56:47.246962Z","end":"2026-07-30T20:56:47.572602Z","steps":["trace[450404681] 'process raft request' (duration: 297.283544ms)","trace[450404681] 'compare' (duration: 28.217954ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:56:47.572805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:47.246943Z","time spent":"325.807495ms","remote":"127.0.0.1:46352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T20:56:47.60732Z","caller":"traceutil/trace.go:171","msg":"trace[2073236262] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:743; }","duration":"275.640211ms","start":"2026-07-30T20:56:47.331657Z","end":"2026-07-30T20:56:47.607298Z","steps":["trace[2073236262] 'read index received' (duration: 212.624831ms)","trace[2073236262] 'applied index is now lower than readState.Index' (duration: 63.0145ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:56:47.607582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.899527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-07-30T20:56:47.607628Z","caller":"traceutil/trace.go:171","msg":"trace[1029390342] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:714; }","duration":"275.963739ms","start":"2026-07-30T20:56:47.331652Z","end":"2026-07-30T20:56:47.607616Z","steps":["trace[1029390342] 'agreement among raft nodes before linearized reading' (duration: 275.767284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:47.607719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.292335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T20:56:47.607823Z","caller":"traceutil/trace.go:171","msg":"trace[877914103] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:714; }","duration":"256.4759ms","start":"2026-07-30T20:56:47.351325Z","end":"2026-07-30T20:56:47.607801Z","steps":["trace[877914103] 'agreement among raft nodes before linearized reading' (duration: 256.159901ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:47.608087Z","caller":"traceutil/trace.go:171","msg":"trace[43967197] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"344.367962ms","start":"2026-07-30T20:56:47.263023Z","end":"2026-07-30T20:56:47.607391Z","steps":["trace[43967197] 'process raft request' (duration: 344.122336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:47.608215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:47.26299Z","time spent":"345.150732ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:56:48.774666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.95833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:56:48.774883Z","caller":"traceutil/trace.go:171","msg":"trace[1972469211] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"169.847038ms","start":"2026-07-30T20:56:48.605008Z","end":"2026-07-30T20:56:48.774855Z","steps":["trace[1972469211] 'process raft request' (duration: 58.55423ms)","trace[1972469211] 'compare' (duration: 110.788196ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:56:48.789261Z","caller":"traceutil/trace.go:171","msg":"trace[859404482] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"145.09357ms","start":"2026-07-30T20:56:48.644147Z","end":"2026-07-30T20:56:48.78924Z","steps":["trace[859404482] 'process raft request' (duration: 144.876325ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:48.926476Z","caller":"traceutil/trace.go:171","msg":"trace[1535139936] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"128.920862ms","start":"2026-07-30T20:56:48.797531Z","end":"2026-07-30T20:56:48.926452Z","steps":["trace[1535139936] 'process raft request' (duration: 123.079888ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:52.017947Z","caller":"traceutil/trace.go:171","msg":"trace[1059731672] transaction","detail":"{read_only:false; number_of_response:0; response_revision:753; }","duration":"205.509044ms","start":"2026-07-30T20:56:51.812413Z","end":"2026-07-30T20:56:52.017922Z","steps":["trace[1059731672] 'process raft request' (duration: 175.959817ms)","trace[1059731672] 'compare' (duration: 29.445265ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:56:52.018425Z","caller":"traceutil/trace.go:171","msg":"trace[1610423039] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"150.901841ms","start":"2026-07-30T20:56:51.867493Z","end":"2026-07-30T20:56:52.018395Z","steps":["trace[1610423039] 'process raft request' (duration: 150.487331ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:52.241509Z","caller":"traceutil/trace.go:171","msg":"trace[1536696465] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"132.419587ms","start":"2026-07-30T20:56:52.109061Z","end":"2026-07-30T20:56:52.241481Z","steps":["trace[1536696465] 'process raft request' (duration: 132.245943ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:54.91112Z","caller":"traceutil/trace.go:171","msg":"trace[251438094] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"119.027558ms","start":"2026-07-30T20:56:54.792048Z","end":"2026-07-30T20:56:54.911076Z","steps":["trace[251438094] 'process raft request' (duration: 118.807372ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:54.91118Z","caller":"traceutil/trace.go:171","msg":"trace[1587057895] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"116.70715ms","start":"2026-07-30T20:56:54.794447Z","end":"2026-07-30T20:56:54.911154Z","steps":["trace[1587057895] 'process raft request' (duration: 116.586387ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:54.91125Z","caller":"traceutil/trace.go:171","msg":"trace[835634552] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"119.059448ms","start":"2026-07-30T20:56:54.792138Z","end":"2026-07-30T20:56:54.911197Z","steps":["trace[835634552] 'process raft request' (duration: 118.850433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:56:55.133432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.635876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:56:55.13364Z","caller":"traceutil/trace.go:171","msg":"trace[1362222651] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"142.669188ms","start":"2026-07-30T20:56:54.990946Z","end":"2026-07-30T20:56:55.133615Z","steps":["trace[1362222651] 'process raft request' (duration: 32.723145ms)","trace[1362222651] 'compare' (duration: 109.459042ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:56:55.153318Z","caller":"traceutil/trace.go:171","msg":"trace[1660444076] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"159.732568ms","start":"2026-07-30T20:56:54.99356Z","end":"2026-07-30T20:56:55.153293Z","steps":["trace[1660444076] 'process raft request' (duration: 159.635036ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:55.153642Z","caller":"traceutil/trace.go:171","msg":"trace[1010309607] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"160.64622ms","start":"2026-07-30T20:56:54.992931Z","end":"2026-07-30T20:56:55.153578Z","steps":["trace[1010309607] 'process raft request' (duration: 160.116137ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:55.153928Z","caller":"traceutil/trace.go:171","msg":"trace[2085446686] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"159.696507ms","start":"2026-07-30T20:56:54.994218Z","end":"2026-07-30T20:56:55.153914Z","steps":["trace[2085446686] 'process raft request' (duration: 159.028321ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:55.154279Z","caller":"traceutil/trace.go:171","msg":"trace[1508942342] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"159.38026ms","start":"2026-07-30T20:56:54.994882Z","end":"2026-07-30T20:56:55.154262Z","steps":["trace[1508942342] 'process raft request' (duration: 159.150104ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:55.154471Z","caller":"traceutil/trace.go:171","msg":"trace[504848539] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"158.815645ms","start":"2026-07-30T20:56:54.995638Z","end":"2026-07-30T20:56:55.154454Z","steps":["trace[504848539] 'process raft request' (duration: 158.58469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:00.182535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569021283760043,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:57:00.683549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569021283760043,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:57:00.779881Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.142550657s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T20:57:00.780224Z","caller":"traceutil/trace.go:171","msg":"trace[521093682] linearizableReadLoop","detail":"{readStateIndex:850; appliedIndex:849; }","duration":"1.098636728s","start":"2026-07-30T20:56:59.681565Z","end":"2026-07-30T20:57:00.780202Z","steps":["trace[521093682] 'read index received' (duration: 1.098486264s)","trace[521093682] 'applied index is now lower than readState.Index' (duration: 149.554µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:57:00.780346Z","caller":"traceutil/trace.go:171","msg":"trace[191863192] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"1.14309698s","start":"2026-07-30T20:56:59.63724Z","end":"2026-07-30T20:57:00.780337Z","steps":["trace[191863192] 'process raft request' (duration: 1.142837284s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:00.780453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:59.637219Z","time spent":"1.143158832s","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:57:00.780747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023332655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:57:00.780878Z","caller":"traceutil/trace.go:171","msg":"trace[64885635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:815; }","duration":"1.023452848s","start":"2026-07-30T20:56:59.757364Z","end":"2026-07-30T20:57:00.780817Z","steps":["trace[64885635] 'agreement among raft nodes before linearized reading' (duration: 1.023301104s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:00.780963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:59.757306Z","time spent":"1.023611892s","remote":"127.0.0.1:46284","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-07-30T20:57:00.781002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.154554ms","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-07-30T20:57:00.781044Z","caller":"traceutil/trace.go:171","msg":"trace[1804751992] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:815; }","duration":"980.286006ms","start":"2026-07-30T20:56:59.800747Z","end":"2026-07-30T20:57:00.781033Z","steps":["trace[1804751992] 'agreement among raft nodes before linearized reading' (duration: 980.155193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:00.781027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099434507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-30T20:57:00.781079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:59.800734Z","time spent":"980.337348ms","remote":"127.0.0.1:46382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-07-30T20:57:00.781126Z","caller":"traceutil/trace.go:171","msg":"trace[1103714272] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:815; }","duration":"1.099580931s","start":"2026-07-30T20:56:59.681518Z","end":"2026-07-30T20:57:00.781099Z","steps":["trace[1103714272] 'agreement among raft nodes before linearized reading' (duration: 1.099356125s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:00.781175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:56:59.681466Z","time spent":"1.099700254s","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T20:57:35.967608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.293197ms","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-07-30T20:57:35.967745Z","caller":"traceutil/trace.go:171","msg":"trace[1751796991] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1029; }","duration":"318.459461ms","start":"2026-07-30T20:57:35.649268Z","end":"2026-07-30T20:57:35.967727Z","steps":["trace[1751796991] 'range keys from in-memory index tree' (duration: 318.268936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:35.9677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.829835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:57:35.967825Z","caller":"traceutil/trace.go:171","msg":"trace[1644826358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"209.016439ms","start":"2026-07-30T20:57:35.758785Z","end":"2026-07-30T20:57:35.967801Z","steps":["trace[1644826358] 'range keys from in-memory index tree' (duration: 208.733062ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:36.193439Z","caller":"traceutil/trace.go:171","msg":"trace[1289840534] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1073; }","duration":"224.323476ms","start":"2026-07-30T20:57:35.969078Z","end":"2026-07-30T20:57:36.193402Z","steps":["trace[1289840534] 'read index received' (duration: 224.042249ms)","trace[1289840534] 'applied index is now lower than readState.Index' (duration: 280.347µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:57:36.193493Z","caller":"traceutil/trace.go:171","msg":"trace[1928946099] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"272.754077ms","start":"2026-07-30T20:57:35.920691Z","end":"2026-07-30T20:57:36.193445Z","steps":["trace[1928946099] 'process raft request' (duration: 272.544492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:36.193613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.514281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:57:36.193656Z","caller":"traceutil/trace.go:171","msg":"trace[2090564233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1030; }","duration":"224.619153ms","start":"2026-07-30T20:57:35.969024Z","end":"2026-07-30T20:57:36.193644Z","steps":["trace[2090564233] 'agreement among raft nodes before linearized reading' (duration: 224.49147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:37.242081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569021283760560,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T20:57:37.276774Z","caller":"traceutil/trace.go:171","msg":"trace[66592135] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"535.059098ms","start":"2026-07-30T20:57:36.741689Z","end":"2026-07-30T20:57:37.276748Z","steps":["trace[66592135] 'read index received' (duration: 534.869263ms)","trace[66592135] 'applied index is now lower than readState.Index' (duration: 189.145µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:57:37.276903Z","caller":"traceutil/trace.go:171","msg":"trace[1136136274] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"736.182094ms","start":"2026-07-30T20:57:36.540672Z","end":"2026-07-30T20:57:37.276854Z","steps":["trace[1136136274] 'process raft request' (duration: 735.937838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:37.277009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.650125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-30T20:57:37.27701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.309154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.61\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-30T20:57:37.277051Z","caller":"traceutil/trace.go:171","msg":"trace[1419185459] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1031; }","duration":"237.756937ms","start":"2026-07-30T20:57:37.039282Z","end":"2026-07-30T20:57:37.277039Z","steps":["trace[1419185459] 'agreement among raft nodes before linearized reading' (duration: 237.591063ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:37.277061Z","caller":"traceutil/trace.go:171","msg":"trace[678574448] range","detail":"{range_begin:/registry/masterleases/199.204.45.61; range_end:; response_count:1; response_revision:1031; }","duration":"535.414756ms","start":"2026-07-30T20:57:36.741631Z","end":"2026-07-30T20:57:37.277046Z","steps":["trace[678574448] 'agreement among raft nodes before linearized reading' (duration: 535.238342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:37.277089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:57:36.741607Z","time spent":"535.472588ms","remote":"127.0.0.1:46300","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.61\" "} {"level":"warn","ts":"2026-07-30T20:57:37.277106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:57:36.540644Z","time spent":"736.352728ms","remote":"127.0.0.1:46554","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-07-30T20:57:37.615924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:57:37.278435Z","time spent":"337.484249ms","remote":"127.0.0.1:46300","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-30T20:57:37.620501Z","caller":"traceutil/trace.go:171","msg":"trace[432795964] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1075; }","duration":"168.150845ms","start":"2026-07-30T20:57:37.452309Z","end":"2026-07-30T20:57:37.62046Z","steps":["trace[432795964] 'read index received' (duration: 161.642325ms)","trace[432795964] 'applied index is now lower than readState.Index' (duration: 6.5071ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:57:37.620591Z","caller":"traceutil/trace.go:171","msg":"trace[818315651] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"339.010007ms","start":"2026-07-30T20:57:37.281544Z","end":"2026-07-30T20:57:37.620554Z","steps":["trace[818315651] 'process raft request' (duration: 338.765891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:37.620744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.414632ms","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-07-30T20:57:37.62092Z","caller":"traceutil/trace.go:171","msg":"trace[439192723] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1032; }","duration":"168.654709ms","start":"2026-07-30T20:57:37.452254Z","end":"2026-07-30T20:57:37.620909Z","steps":["trace[439192723] 'agreement among raft nodes before linearized reading' (duration: 168.35958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:37.621019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:57:37.281525Z","time spent":"339.313975ms","remote":"127.0.0.1:46554","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-07-30T20:57:37.621872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.879979ms","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-07-30T20:57:37.622036Z","caller":"traceutil/trace.go:171","msg":"trace[310954877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1033; }","duration":"168.088044ms","start":"2026-07-30T20:57:37.453934Z","end":"2026-07-30T20:57:37.622022Z","steps":["trace[310954877] 'agreement among raft nodes before linearized reading' (duration: 167.794837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:37.622487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.137842ms","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-07-30T20:57:37.623074Z","caller":"traceutil/trace.go:171","msg":"trace[959488130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1033; }","duration":"165.732196ms","start":"2026-07-30T20:57:37.457319Z","end":"2026-07-30T20:57:37.623051Z","steps":["trace[959488130] 'agreement among raft nodes before linearized reading' (duration: 165.08383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:37.623067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.609807ms","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-07-30T20:57:37.623174Z","caller":"traceutil/trace.go:171","msg":"trace[1583207137] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1033; }","duration":"166.764681ms","start":"2026-07-30T20:57:37.456382Z","end":"2026-07-30T20:57:37.623147Z","steps":["trace[1583207137] 'agreement among raft nodes before linearized reading' (duration: 166.487414ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:02.527574Z","caller":"traceutil/trace.go:171","msg":"trace[1377113472] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"110.734071ms","start":"2026-07-30T20:58:02.416802Z","end":"2026-07-30T20:58:02.527536Z","steps":["trace[1377113472] 'process raft request' (duration: 110.504215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:03.074033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.970874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:03.074161Z","caller":"traceutil/trace.go:171","msg":"trace[2057511846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1116; }","duration":"316.155179ms","start":"2026-07-30T20:58:02.757985Z","end":"2026-07-30T20:58:03.07414Z","steps":["trace[2057511846] 'range keys from in-memory index tree' (duration: 315.825631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:03.074209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:02.757959Z","time spent":"316.238412ms","remote":"127.0.0.1:46284","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-07-30T20:58:03.922092Z","caller":"traceutil/trace.go:171","msg":"trace[1923653009] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"109.308956ms","start":"2026-07-30T20:58:03.812762Z","end":"2026-07-30T20:58:03.922071Z","steps":["trace[1923653009] 'read index received' (duration: 109.085311ms)","trace[1923653009] 'applied index is now lower than readState.Index' (duration: 222.725µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:03.922232Z","caller":"traceutil/trace.go:171","msg":"trace[1105222117] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"145.686581ms","start":"2026-07-30T20:58:03.776533Z","end":"2026-07-30T20:58:03.92222Z","steps":["trace[1105222117] 'process raft request' (duration: 145.374453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:03.922565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.797328ms","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-07-30T20:58:03.922613Z","caller":"traceutil/trace.go:171","msg":"trace[1102505061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1117; }","duration":"109.909791ms","start":"2026-07-30T20:58:03.812691Z","end":"2026-07-30T20:58:03.922601Z","steps":["trace[1102505061] 'agreement among raft nodes before linearized reading' (duration: 109.789118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:05.655598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.087212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:05.655779Z","caller":"traceutil/trace.go:171","msg":"trace[1091914741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1123; }","duration":"898.316057ms","start":"2026-07-30T20:58:04.757439Z","end":"2026-07-30T20:58:05.655755Z","steps":["trace[1091914741] 'range keys from in-memory index tree' (duration: 897.976459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:05.656068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:04.757415Z","time spent":"898.635554ms","remote":"127.0.0.1:46282","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-07-30T20:58:06.067452Z","caller":"traceutil/trace.go:171","msg":"trace[1344389927] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"135.784846ms","start":"2026-07-30T20:58:05.931633Z","end":"2026-07-30T20:58:06.067418Z","steps":["trace[1344389927] 'process raft request' (duration: 135.437438ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:06.318441Z","caller":"traceutil/trace.go:171","msg":"trace[180479791] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"245.456452ms","start":"2026-07-30T20:58:06.072905Z","end":"2026-07-30T20:58:06.318362Z","steps":["trace[180479791] 'process raft request' (duration: 150.500829ms)","trace[180479791] 'compare' (duration: 94.653505ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:06.318733Z","caller":"traceutil/trace.go:171","msg":"trace[2051885373] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"245.547235ms","start":"2026-07-30T20:58:06.073151Z","end":"2026-07-30T20:58:06.318698Z","steps":["trace[2051885373] 'process raft request' (duration: 245.161165ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:06.319102Z","caller":"traceutil/trace.go:171","msg":"trace[1222511788] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"245.891983ms","start":"2026-07-30T20:58:06.073195Z","end":"2026-07-30T20:58:06.319087Z","steps":["trace[1222511788] 'process raft request' (duration: 245.462403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:07.192855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.699662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:58:07.193029Z","caller":"traceutil/trace.go:171","msg":"trace[2096634671] linearizableReadLoop","detail":"{readStateIndex:1181; appliedIndex:1180; }","duration":"271.81725ms","start":"2026-07-30T20:58:06.921188Z","end":"2026-07-30T20:58:07.193005Z","steps":["trace[2096634671] 'read index received' (duration: 140.718098ms)","trace[2096634671] 'applied index is now lower than readState.Index' (duration: 131.097382ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:07.193069Z","caller":"traceutil/trace.go:171","msg":"trace[2077782557] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"312.48766ms","start":"2026-07-30T20:58:06.880547Z","end":"2026-07-30T20:58:07.193035Z","steps":["trace[2077782557] 'process raft request' (duration: 181.341487ms)","trace[2077782557] 'compare' (duration: 130.544068ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:07.193184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.992184ms","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:7"} {"level":"warn","ts":"2026-07-30T20:58:07.193321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:06.880519Z","time spent":"312.638324ms","remote":"127.0.0.1:46300","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-07-30T20:58:07.193327Z","caller":"traceutil/trace.go:171","msg":"trace[1127600375] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1130; }","duration":"272.171978ms","start":"2026-07-30T20:58:06.921135Z","end":"2026-07-30T20:58:07.193307Z","steps":["trace[1127600375] 'agreement among raft nodes before linearized reading' (duration: 271.935093ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:08.256657Z","caller":"traceutil/trace.go:171","msg":"trace[2006825160] linearizableReadLoop","detail":"{readStateIndex:1182; appliedIndex:1181; }","duration":"183.400927ms","start":"2026-07-30T20:58:08.073229Z","end":"2026-07-30T20:58:08.25663Z","steps":["trace[2006825160] 'read index received' (duration: 183.231852ms)","trace[2006825160] 'applied index is now lower than readState.Index' (duration: 167.485µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:08.256832Z","caller":"traceutil/trace.go:171","msg":"trace[1792222908] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"301.961962ms","start":"2026-07-30T20:58:07.954803Z","end":"2026-07-30T20:58:08.256765Z","steps":["trace[1792222908] 'process raft request' (duration: 301.611452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:08.257067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.831971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-30T20:58:08.257182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:07.95477Z","time spent":"302.243848ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:08.257221Z","caller":"traceutil/trace.go:171","msg":"trace[153644552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1131; }","duration":"128.083568ms","start":"2026-07-30T20:58:08.129123Z","end":"2026-07-30T20:58:08.257207Z","steps":["trace[153644552] 'agreement among raft nodes before linearized reading' (duration: 127.700099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:08.257033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.770807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T20:58:08.257419Z","caller":"traceutil/trace.go:171","msg":"trace[556189375] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1131; }","duration":"184.241828ms","start":"2026-07-30T20:58:08.073165Z","end":"2026-07-30T20:58:08.257407Z","steps":["trace[556189375] 'agreement among raft nodes before linearized reading' (duration: 183.608803ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:08.462836Z","caller":"traceutil/trace.go:171","msg":"trace[1799235122] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"200.332983ms","start":"2026-07-30T20:58:08.262473Z","end":"2026-07-30T20:58:08.462806Z","steps":["trace[1799235122] 'process raft request' (duration: 191.589658ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:08.745839Z","caller":"traceutil/trace.go:171","msg":"trace[1943418847] linearizableReadLoop","detail":"{readStateIndex:1184; appliedIndex:1182; }","duration":"421.614481ms","start":"2026-07-30T20:58:08.324194Z","end":"2026-07-30T20:58:08.745808Z","steps":["trace[1943418847] 'read index received' (duration: 129.903082ms)","trace[1943418847] 'applied index is now lower than readState.Index' (duration: 291.709459ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:08.745978Z","caller":"traceutil/trace.go:171","msg":"trace[188033065] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"482.644651ms","start":"2026-07-30T20:58:08.263303Z","end":"2026-07-30T20:58:08.745948Z","steps":["trace[188033065] 'process raft request' (duration: 426.504891ms)","trace[188033065] 'compare' (duration: 55.784361ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:08.746288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.066941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-30T20:58:08.746294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:08.263271Z","time spent":"482.864516ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:08.746412Z","caller":"traceutil/trace.go:171","msg":"trace[389762963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1133; }","duration":"422.214085ms","start":"2026-07-30T20:58:08.324149Z","end":"2026-07-30T20:58:08.746363Z","steps":["trace[389762963] 'agreement among raft nodes before linearized reading' (duration: 421.893027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:08.746484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:08.324129Z","time spent":"422.342489ms","remote":"127.0.0.1:46554","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":"info","ts":"2026-07-30T20:58:08.757409Z","caller":"traceutil/trace.go:171","msg":"trace[1957224606] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"128.180019ms","start":"2026-07-30T20:58:08.629208Z","end":"2026-07-30T20:58:08.757388Z","steps":["trace[1957224606] 'process raft request' (duration: 128.058606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:08.757652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.312918ms","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-07-30T20:58:08.757858Z","caller":"traceutil/trace.go:171","msg":"trace[1948070409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1134; }","duration":"433.548174ms","start":"2026-07-30T20:58:08.324293Z","end":"2026-07-30T20:58:08.757841Z","steps":["trace[1948070409] 'agreement among raft nodes before linearized reading' (duration: 433.262767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:08.757979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:08.324268Z","time spent":"433.697697ms","remote":"127.0.0.1:46554","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-07-30T20:58:08.758282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.962655ms","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-07-30T20:58:08.758329Z","caller":"traceutil/trace.go:171","msg":"trace[1019344304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1134; }","duration":"434.062537ms","start":"2026-07-30T20:58:08.324256Z","end":"2026-07-30T20:58:08.758318Z","steps":["trace[1019344304] 'agreement among raft nodes before linearized reading' (duration: 433.188935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:08.75836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:08.324226Z","time spent":"434.128048ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-30T20:58:08.895707Z","caller":"traceutil/trace.go:171","msg":"trace[1161883353] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"144.169094ms","start":"2026-07-30T20:58:08.7515Z","end":"2026-07-30T20:58:08.895669Z","steps":["trace[1161883353] 'process raft request' (duration: 107.263286ms)","trace[1161883353] 'compare' (duration: 36.759104ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:08.895802Z","caller":"traceutil/trace.go:171","msg":"trace[588079877] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"132.452596ms","start":"2026-07-30T20:58:08.763334Z","end":"2026-07-30T20:58:08.895786Z","steps":["trace[588079877] 'process raft request' (duration: 132.377754ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:08.895763Z","caller":"traceutil/trace.go:171","msg":"trace[2108128150] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"133.615044ms","start":"2026-07-30T20:58:08.762133Z","end":"2026-07-30T20:58:08.895748Z","steps":["trace[2108128150] 'process raft request' (duration: 133.534862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:08.895995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.009463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:08.898263Z","caller":"traceutil/trace.go:171","msg":"trace[910509790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1137; }","duration":"140.33631ms","start":"2026-07-30T20:58:08.757895Z","end":"2026-07-30T20:58:08.898231Z","steps":["trace[910509790] 'agreement among raft nodes before linearized reading' (duration: 137.961021ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:08.895985Z","caller":"traceutil/trace.go:171","msg":"trace[695540263] linearizableReadLoop","detail":"{readStateIndex:1186; appliedIndex:1185; }","duration":"137.732686ms","start":"2026-07-30T20:58:08.757976Z","end":"2026-07-30T20:58:08.895709Z","steps":["trace[695540263] 'read index received' (duration: 100.803457ms)","trace[695540263] 'applied index is now lower than readState.Index' (duration: 36.925459ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:25.043963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569021283761059,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:58:25.544165Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569021283761059,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:58:25.815336Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.328309924s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T20:58:25.815962Z","caller":"traceutil/trace.go:171","msg":"trace[995399510] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"1.329018782s","start":"2026-07-30T20:58:24.486908Z","end":"2026-07-30T20:58:25.815927Z","steps":["trace[995399510] 'process raft request' (duration: 1.328822418s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:25.816151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:24.486879Z","time spent":"1.329165766s","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:26.61536Z","caller":"traceutil/trace.go:171","msg":"trace[326443842] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1292; }","duration":"2.07155143s","start":"2026-07-30T20:58:24.543773Z","end":"2026-07-30T20:58:26.615325Z","steps":["trace[326443842] 'read index received' (duration: 1.271860406s)","trace[326443842] 'applied index is now lower than readState.Index' (duration: 799.689494ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:26.615506Z","caller":"traceutil/trace.go:171","msg":"trace[1676360348] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"1.800180691s","start":"2026-07-30T20:58:24.81531Z","end":"2026-07-30T20:58:26.615491Z","steps":["trace[1676360348] 'process raft request' (duration: 1.777983575s)","trace[1676360348] 'compare' (duration: 21.855787ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:26.61564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:24.815287Z","time spent":"1.800251253s","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:58:26.615736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.712483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:58:26.615816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.424037ms","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-07-30T20:58:26.615923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.037001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:58:26.615906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635502924s","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-07-30T20:58:26.615973Z","caller":"traceutil/trace.go:171","msg":"trace[1162589105] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1238; }","duration":"837.110703ms","start":"2026-07-30T20:58:25.778851Z","end":"2026-07-30T20:58:26.615961Z","steps":["trace[1162589105] 'agreement among raft nodes before linearized reading' (duration: 837.036931ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:26.615999Z","caller":"traceutil/trace.go:171","msg":"trace[1099263556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1238; }","duration":"1.635625436s","start":"2026-07-30T20:58:24.980351Z","end":"2026-07-30T20:58:26.615977Z","steps":["trace[1099263556] 'agreement among raft nodes before linearized reading' (duration: 1.635333329s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:26.616021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:25.778839Z","time spent":"837.172564ms","remote":"127.0.0.1:56490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-30T20:58:26.616054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:24.980341Z","time spent":"1.635704728s","remote":"127.0.0.1:46554","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-07-30T20:58:26.616094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.072316099s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-30T20:58:26.616111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.64516254s","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-07-30T20:58:26.616136Z","caller":"traceutil/trace.go:171","msg":"trace[2023181981] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1238; }","duration":"2.072411651s","start":"2026-07-30T20:58:24.543715Z","end":"2026-07-30T20:58:26.616127Z","steps":["trace[2023181981] 'agreement among raft nodes before linearized reading' (duration: 2.072255497s)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:26.616155Z","caller":"traceutil/trace.go:171","msg":"trace[436186099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1238; }","duration":"1.645247272s","start":"2026-07-30T20:58:24.970896Z","end":"2026-07-30T20:58:26.616143Z","steps":["trace[436186099] 'agreement among raft nodes before linearized reading' (duration: 1.645079758s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:26.616182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:24.543694Z","time spent":"2.072478952s","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T20:58:26.616189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:24.970868Z","time spent":"1.645315494s","remote":"127.0.0.1:46554","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-07-30T20:58:26.616331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635792291s","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-07-30T20:58:26.615935Z","caller":"traceutil/trace.go:171","msg":"trace[109836915] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1238; }","duration":"185.619182ms","start":"2026-07-30T20:58:26.430289Z","end":"2026-07-30T20:58:26.615908Z","steps":["trace[109836915] 'agreement among raft nodes before linearized reading' (duration: 185.399746ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:26.616376Z","caller":"traceutil/trace.go:171","msg":"trace[522930361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"1.635881853s","start":"2026-07-30T20:58:24.980483Z","end":"2026-07-30T20:58:26.616365Z","steps":["trace[522930361] 'agreement among raft nodes before linearized reading' (duration: 1.63577606s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:26.616413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:24.980457Z","time spent":"1.635948915s","remote":"127.0.0.1:46554","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":"info","ts":"2026-07-30T20:58:26.615853Z","caller":"traceutil/trace.go:171","msg":"trace[1785511858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1238; }","duration":"858.886327ms","start":"2026-07-30T20:58:25.75694Z","end":"2026-07-30T20:58:26.615826Z","steps":["trace[1785511858] 'agreement among raft nodes before linearized reading' (duration: 858.683332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:26.616521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:25.756917Z","time spent":"859.592934ms","remote":"127.0.0.1:46284","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-07-30T20:58:26.619486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.801213396s","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-07-30T20:58:26.619548Z","caller":"traceutil/trace.go:171","msg":"trace[1374684884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1238; }","duration":"1.801303358s","start":"2026-07-30T20:58:24.818228Z","end":"2026-07-30T20:58:26.619532Z","steps":["trace[1374684884] 'agreement among raft nodes before linearized reading' (duration: 1.797347472s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:26.619582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:24.818221Z","time spent":"1.801354229s","remote":"127.0.0.1:46554","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-07-30T20:58:47.608624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.489537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:58:47.608883Z","caller":"traceutil/trace.go:171","msg":"trace[1891960730] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1400; }","duration":"807.123315ms","start":"2026-07-30T20:58:46.801743Z","end":"2026-07-30T20:58:47.608866Z","steps":["trace[1891960730] 'read index received' (duration: 359.204567ms)","trace[1891960730] 'applied index is now lower than readState.Index' (duration: 447.917908ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:47.609111Z","caller":"traceutil/trace.go:171","msg":"trace[453007520] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"825.944688ms","start":"2026-07-30T20:58:46.783152Z","end":"2026-07-30T20:58:47.609097Z","steps":["trace[453007520] 'process raft request' (duration: 377.893317ms)","trace[453007520] 'compare' (duration: 447.087348ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:47.609181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:46.78314Z","time spent":"826.00839ms","remote":"127.0.0.1:46554","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-07-30T20:58:47.609394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.396272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-30T20:58:47.609436Z","caller":"traceutil/trace.go:171","msg":"trace[283059159] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1342; }","duration":"805.476884ms","start":"2026-07-30T20:58:46.803948Z","end":"2026-07-30T20:58:47.609425Z","steps":["trace[283059159] 'agreement among raft nodes before linearized reading' (duration: 805.406013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:47.609465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:46.80393Z","time spent":"805.528805ms","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-07-30T20:58:47.609523Z","caller":"traceutil/trace.go:171","msg":"trace[1268187498] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"825.858016ms","start":"2026-07-30T20:58:46.783644Z","end":"2026-07-30T20:58:47.609502Z","steps":["trace[1268187498] 'process raft request' (duration: 825.104478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:47.609648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:46.783635Z","time spent":"825.942158ms","remote":"127.0.0.1:46554","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-07-30T20:58:47.609704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.820837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:47.60974Z","caller":"traceutil/trace.go:171","msg":"trace[1222912371] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1342; }","duration":"561.895788ms","start":"2026-07-30T20:58:47.047836Z","end":"2026-07-30T20:58:47.609732Z","steps":["trace[1222912371] 'agreement among raft nodes before linearized reading' (duration: 561.839176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:47.609769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:47.047816Z","time spent":"561.945779ms","remote":"127.0.0.1:46586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-07-30T20:58:47.609853Z","caller":"traceutil/trace.go:171","msg":"trace[516117434] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"825.828314ms","start":"2026-07-30T20:58:46.784009Z","end":"2026-07-30T20:58:47.609837Z","steps":["trace[516117434] 'process raft request' (duration: 824.8191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:47.609936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:46.783986Z","time spent":"825.908157ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:58:47.609432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.460634ms","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-07-30T20:58:47.610163Z","caller":"traceutil/trace.go:171","msg":"trace[8654888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1342; }","duration":"819.242973ms","start":"2026-07-30T20:58:46.790909Z","end":"2026-07-30T20:58:47.610152Z","steps":["trace[8654888] 'agreement among raft nodes before linearized reading' (duration: 818.345531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:47.610165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.339639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-30T20:58:47.6102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:46.790898Z","time spent":"819.291214ms","remote":"127.0.0.1:46554","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-07-30T20:58:47.610215Z","caller":"traceutil/trace.go:171","msg":"trace[1081765115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1342; }","duration":"811.40717ms","start":"2026-07-30T20:58:46.79879Z","end":"2026-07-30T20:58:47.610197Z","steps":["trace[1081765115] 'agreement among raft nodes before linearized reading' (duration: 811.249116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:47.611301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:46.798779Z","time spent":"812.509398ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T20:58:48.300768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569021283761290,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T20:58:48.498792Z","caller":"traceutil/trace.go:171","msg":"trace[109648977] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1404; }","duration":"698.516806ms","start":"2026-07-30T20:58:47.800246Z","end":"2026-07-30T20:58:48.498762Z","steps":["trace[109648977] 'read index received' (duration: 698.23012ms)","trace[109648977] 'applied index is now lower than readState.Index' (duration: 285.696µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:48.498885Z","caller":"traceutil/trace.go:171","msg":"trace[1971039732] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"882.95714ms","start":"2026-07-30T20:58:47.615894Z","end":"2026-07-30T20:58:48.498851Z","steps":["trace[1971039732] 'process raft request' (duration: 882.693233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:48.499021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.726782ms","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:7"} {"level":"warn","ts":"2026-07-30T20:58:48.499037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:47.615875Z","time spent":"883.081243ms","remote":"127.0.0.1:46554","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-07-30T20:58:48.499078Z","caller":"traceutil/trace.go:171","msg":"trace[1763779984] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1344; }","duration":"698.862646ms","start":"2026-07-30T20:58:47.800198Z","end":"2026-07-30T20:58:48.499061Z","steps":["trace[1763779984] 'agreement among raft nodes before linearized reading' (duration: 698.712602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:48.499127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:47.800176Z","time spent":"698.939227ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-30T20:58:48.499169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.527558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-07-30T20:58:48.499246Z","caller":"traceutil/trace.go:171","msg":"trace[252387100] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1344; }","duration":"224.664681ms","start":"2026-07-30T20:58:48.274564Z","end":"2026-07-30T20:58:48.499229Z","steps":["trace[252387100] 'agreement among raft nodes before linearized reading' (duration: 224.442335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:49.124764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.889827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:58:49.124873Z","caller":"traceutil/trace.go:171","msg":"trace[1323094886] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"621.762761ms","start":"2026-07-30T20:58:48.503092Z","end":"2026-07-30T20:58:49.124855Z","steps":["trace[1323094886] 'process raft request' (duration: 420.693279ms)","trace[1323094886] 'compare' (duration: 200.677552ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:49.124918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:48.503032Z","time spent":"621.865953ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:58:50.35873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.985483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:58:50.358996Z","caller":"traceutil/trace.go:171","msg":"trace[2147334553] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"1.324551102s","start":"2026-07-30T20:58:49.034425Z","end":"2026-07-30T20:58:50.358976Z","steps":["trace[2147334553] 'process raft request' (duration: 1.324434999s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:50.359076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:49.034395Z","time spent":"1.324647713s","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:50.359302Z","caller":"traceutil/trace.go:171","msg":"trace[1232200696] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"1.606141112s","start":"2026-07-30T20:58:48.753145Z","end":"2026-07-30T20:58:50.359286Z","steps":["trace[1232200696] 'process raft request' (duration: 704.477303ms)","trace[1232200696] 'compare' (duration: 900.778497ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:50.35937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:48.753123Z","time spent":"1.606216364s","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:50.359504Z","caller":"traceutil/trace.go:171","msg":"trace[579571945] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1405; }","duration":"1.602301487s","start":"2026-07-30T20:58:48.75714Z","end":"2026-07-30T20:58:50.359442Z","steps":["trace[579571945] 'read index received' (duration: 166.584213ms)","trace[579571945] 'applied index is now lower than readState.Index' (duration: 1.435710044s)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:50.359797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.602612165s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:50.359869Z","caller":"traceutil/trace.go:171","msg":"trace[1594319416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"1.602732827s","start":"2026-07-30T20:58:48.757114Z","end":"2026-07-30T20:58:50.359847Z","steps":["trace[1594319416] 'agreement among raft nodes before linearized reading' (duration: 1.602571903s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:50.359927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:48.757107Z","time spent":"1.60280619s","remote":"127.0.0.1:46284","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-07-30T20:58:50.359942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.180864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-30T20:58:50.359955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.049689ms","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-07-30T20:58:50.359987Z","caller":"traceutil/trace.go:171","msg":"trace[1339456395] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1347; }","duration":"424.285566ms","start":"2026-07-30T20:58:49.935688Z","end":"2026-07-30T20:58:50.359973Z","steps":["trace[1339456395] 'agreement among raft nodes before linearized reading' (duration: 424.085661ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:50.360006Z","caller":"traceutil/trace.go:171","msg":"trace[1699495907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1347; }","duration":"745.132891ms","start":"2026-07-30T20:58:49.614858Z","end":"2026-07-30T20:58:50.359991Z","steps":["trace[1699495907] 'agreement among raft nodes before linearized reading' (duration: 744.949257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:50.359975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025718777s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-30T20:58:50.360032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:49.935659Z","time spent":"424.363828ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T20:58:50.360042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:49.614852Z","time spent":"745.181612ms","remote":"127.0.0.1:46554","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-07-30T20:58:50.360064Z","caller":"traceutil/trace.go:171","msg":"trace[297112450] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1347; }","duration":"1.025866721s","start":"2026-07-30T20:58:49.334176Z","end":"2026-07-30T20:58:50.360042Z","steps":["trace[297112450] 'agreement among raft nodes before linearized reading' (duration: 1.025602274s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:50.360071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.166248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-30T20:58:50.360125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:49.334145Z","time spent":"1.025968443s","remote":"127.0.0.1:46352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":145,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-30T20:58:50.360076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.074041ms","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-07-30T20:58:50.360177Z","caller":"traceutil/trace.go:171","msg":"trace[915211874] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1347; }","duration":"718.287641ms","start":"2026-07-30T20:58:49.641863Z","end":"2026-07-30T20:58:50.360151Z","steps":["trace[915211874] 'agreement among raft nodes before linearized reading' (duration: 717.929163ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:50.360249Z","caller":"traceutil/trace.go:171","msg":"trace[998028257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1347; }","duration":"745.216734ms","start":"2026-07-30T20:58:49.614962Z","end":"2026-07-30T20:58:50.360179Z","steps":["trace[998028257] 'agreement among raft nodes before linearized reading' (duration: 744.841925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:50.360311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:49.641793Z","time spent":"718.503397ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T20:58:50.360315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:49.614873Z","time spent":"745.428719ms","remote":"127.0.0.1:46554","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-07-30T20:58:50.36051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.546652ms","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-07-30T20:58:50.360646Z","caller":"traceutil/trace.go:171","msg":"trace[1503192321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1347; }","duration":"745.906991ms","start":"2026-07-30T20:58:49.614721Z","end":"2026-07-30T20:58:50.360628Z","steps":["trace[1503192321] 'agreement among raft nodes before linearized reading' (duration: 745.080091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:50.360797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:49.614679Z","time spent":"746.101196ms","remote":"127.0.0.1:46554","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":"info","ts":"2026-07-30T20:58:50.678212Z","caller":"traceutil/trace.go:171","msg":"trace[1924235434] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"312.598242ms","start":"2026-07-30T20:58:50.365578Z","end":"2026-07-30T20:58:50.678176Z","steps":["trace[1924235434] 'process raft request' (duration: 312.184231ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:50.678334Z","caller":"traceutil/trace.go:171","msg":"trace[2137498585] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"312.329876ms","start":"2026-07-30T20:58:50.365963Z","end":"2026-07-30T20:58:50.678293Z","steps":["trace[2137498585] 'process raft request' (duration: 311.992008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:50.678433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:50.365554Z","time spent":"312.761427ms","remote":"127.0.0.1:46554","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-07-30T20:58:50.678539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:50.365948Z","time spent":"312.469149ms","remote":"127.0.0.1:46554","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-07-30T20:58:50.678615Z","caller":"traceutil/trace.go:171","msg":"trace[1667941472] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"311.727422ms","start":"2026-07-30T20:58:50.366873Z","end":"2026-07-30T20:58:50.678601Z","steps":["trace[1667941472] 'process raft request' (duration: 311.219139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:50.678697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:50.366866Z","time spent":"311.785993ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:50.678208Z","caller":"traceutil/trace.go:171","msg":"trace[1490526916] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1408; }","duration":"309.289431ms","start":"2026-07-30T20:58:50.368888Z","end":"2026-07-30T20:58:50.678178Z","steps":["trace[1490526916] 'read index received' (duration: 64.678089ms)","trace[1490526916] 'applied index is now lower than readState.Index' (duration: 244.609632ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:50.678961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.05926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:50.679444Z","caller":"traceutil/trace.go:171","msg":"trace[1065259320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1352; }","duration":"310.561892ms","start":"2026-07-30T20:58:50.368862Z","end":"2026-07-30T20:58:50.679423Z","steps":["trace[1065259320] 'agreement among raft nodes before linearized reading' (duration: 310.047039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:50.679506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:50.368854Z","time spent":"310.636604ms","remote":"127.0.0.1:46284","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-07-30T20:58:50.679829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.945684ms","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-07-30T20:58:50.679885Z","caller":"traceutil/trace.go:171","msg":"trace[185375573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1352; }","duration":"176.041847ms","start":"2026-07-30T20:58:50.50383Z","end":"2026-07-30T20:58:50.679872Z","steps":["trace[185375573] 'agreement among raft nodes before linearized reading' (duration: 175.865282ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:50.679062Z","caller":"traceutil/trace.go:171","msg":"trace[1716937014] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"312.568321ms","start":"2026-07-30T20:58:50.366473Z","end":"2026-07-30T20:58:50.679042Z","steps":["trace[1716937014] 'process raft request' (duration: 311.572497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:50.680051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:50.366466Z","time spent":"313.524674ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:58:50.993669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.562116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:58:50.993763Z","caller":"traceutil/trace.go:171","msg":"trace[194003212] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"268.582431ms","start":"2026-07-30T20:58:50.725168Z","end":"2026-07-30T20:58:50.99375Z","steps":["trace[194003212] 'read index received' (duration: 50.78841ms)","trace[194003212] 'applied index is now lower than readState.Index' (duration: 217.792511ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:50.993812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.651502ms","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-07-30T20:58:50.993829Z","caller":"traceutil/trace.go:171","msg":"trace[2054994770] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1353; }","duration":"268.707884ms","start":"2026-07-30T20:58:50.725116Z","end":"2026-07-30T20:58:50.993824Z","steps":["trace[2054994770] 'agreement among raft nodes before linearized reading' (duration: 268.664813ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:50.993904Z","caller":"traceutil/trace.go:171","msg":"trace[1871403699] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"308.992523ms","start":"2026-07-30T20:58:50.684857Z","end":"2026-07-30T20:58:50.99385Z","steps":["trace[1871403699] 'process raft request' (duration: 91.166621ms)","trace[1871403699] 'compare' (duration: 217.437793ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:50.994092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:50.684825Z","time spent":"309.172167ms","remote":"127.0.0.1:46554","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-07-30T20:58:52.193178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.841006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T20:58:52.193267Z","caller":"traceutil/trace.go:171","msg":"trace[1322140419] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1353; }","duration":"742.994859ms","start":"2026-07-30T20:58:51.450253Z","end":"2026-07-30T20:58:52.193248Z","steps":["trace[1322140419] 'count revisions from in-memory index tree' (duration: 742.735413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:52.193337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:51.450229Z","time spent":"743.073292ms","remote":"127.0.0.1:46678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-30T20:58:52.193341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.254731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:52.193432Z","caller":"traceutil/trace.go:171","msg":"trace[2011733179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1353; }","duration":"436.392164ms","start":"2026-07-30T20:58:51.757012Z","end":"2026-07-30T20:58:52.193404Z","steps":["trace[2011733179] 'range keys from in-memory index tree' (duration: 436.159528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:52.193487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:51.756986Z","time spent":"436.490167ms","remote":"127.0.0.1:46284","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-07-30T20:58:52.508324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.447551ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-30T20:58:52.508562Z","caller":"traceutil/trace.go:171","msg":"trace[197642771] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1414; }","duration":"449.794274ms","start":"2026-07-30T20:58:52.058705Z","end":"2026-07-30T20:58:52.508499Z","steps":["trace[197642771] 'read index received' (duration: 213.021535ms)","trace[197642771] 'applied index is now lower than readState.Index' (duration: 236.770259ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:52.508692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.978929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:52.508752Z","caller":"traceutil/trace.go:171","msg":"trace[835786192] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1353; }","duration":"450.096472ms","start":"2026-07-30T20:58:52.058639Z","end":"2026-07-30T20:58:52.508735Z","steps":["trace[835786192] 'agreement among raft nodes before linearized reading' (duration: 449.968129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:52.508794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:52.058617Z","time spent":"450.166373ms","remote":"127.0.0.1:33930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-30T20:58:52.637309Z","caller":"traceutil/trace.go:171","msg":"trace[862664195] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"271.322638ms","start":"2026-07-30T20:58:52.365949Z","end":"2026-07-30T20:58:52.637272Z","steps":["trace[862664195] 'process raft request' (duration: 271.142704ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:52.637592Z","caller":"traceutil/trace.go:171","msg":"trace[579211504] linearizableReadLoop","detail":"{readStateIndex:1416; appliedIndex:1416; }","duration":"128.934158ms","start":"2026-07-30T20:58:52.508645Z","end":"2026-07-30T20:58:52.63758Z","steps":["trace[579211504] 'read index received' (duration: 128.928008ms)","trace[579211504] 'applied index is now lower than readState.Index' (duration: 4.63µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:52.637801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.018677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:52.637859Z","caller":"traceutil/trace.go:171","msg":"trace[331504755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1354; }","duration":"443.158301ms","start":"2026-07-30T20:58:52.194682Z","end":"2026-07-30T20:58:52.637841Z","steps":["trace[331504755] 'agreement among raft nodes before linearized reading' (duration: 442.978736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:52.637904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:52.194658Z","time spent":"443.231992ms","remote":"127.0.0.1:46282","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-07-30T20:58:53.21633Z","caller":"traceutil/trace.go:171","msg":"trace[444350856] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"528.000246ms","start":"2026-07-30T20:58:52.688303Z","end":"2026-07-30T20:58:53.216303Z","steps":["trace[444350856] 'process raft request' (duration: 527.633026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:53.216505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:52.688287Z","time spent":"528.11664ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:58:53.386386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.059838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:58:53.386651Z","caller":"traceutil/trace.go:171","msg":"trace[960656519] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"697.924172ms","start":"2026-07-30T20:58:52.688702Z","end":"2026-07-30T20:58:53.386626Z","steps":["trace[960656519] 'process raft request' (duration: 569.159637ms)","trace[960656519] 'compare' (duration: 127.780841ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:53.386731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:52.688678Z","time spent":"698.017764ms","remote":"127.0.0.1:46554","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-07-30T20:58:53.386999Z","caller":"traceutil/trace.go:171","msg":"trace[2101017846] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"696.824465ms","start":"2026-07-30T20:58:52.690159Z","end":"2026-07-30T20:58:53.386983Z","steps":["trace[2101017846] 'process raft request' (duration: 696.754333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:53.387062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:52.690145Z","time spent":"696.881757ms","remote":"127.0.0.1:46554","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-07-30T20:58:53.387138Z","caller":"traceutil/trace.go:171","msg":"trace[1566218625] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1416; }","duration":"628.349663ms","start":"2026-07-30T20:58:52.758764Z","end":"2026-07-30T20:58:53.387114Z","steps":["trace[1566218625] 'read index received' (duration: 457.270859ms)","trace[1566218625] 'applied index is now lower than readState.Index' (duration: 171.077504ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:53.387273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.507717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:53.387312Z","caller":"traceutil/trace.go:171","msg":"trace[908756604] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"697.280707ms","start":"2026-07-30T20:58:52.690021Z","end":"2026-07-30T20:58:53.387302Z","steps":["trace[908756604] 'process raft request' (duration: 696.553238ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:53.387352Z","caller":"traceutil/trace.go:171","msg":"trace[426381466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1358; }","duration":"628.629209ms","start":"2026-07-30T20:58:52.758709Z","end":"2026-07-30T20:58:53.387338Z","steps":["trace[426381466] 'agreement among raft nodes before linearized reading' (duration: 628.518407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:53.387363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.439516ms","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-07-30T20:58:53.38739Z","caller":"traceutil/trace.go:171","msg":"trace[2125240325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1358; }","duration":"388.56039ms","start":"2026-07-30T20:58:52.998822Z","end":"2026-07-30T20:58:53.387383Z","steps":["trace[2125240325] 'agreement among raft nodes before linearized reading' (duration: 388.416696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:53.387378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:52.690006Z","time spent":"697.339538ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:58:53.387392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:52.758689Z","time spent":"628.696252ms","remote":"127.0.0.1:46284","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-07-30T20:58:53.387414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:52.998797Z","time spent":"388.60865ms","remote":"127.0.0.1:46554","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-07-30T20:58:54.297838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.638008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:5 size:6183"} {"level":"info","ts":"2026-07-30T20:58:54.297929Z","caller":"traceutil/trace.go:171","msg":"trace[688496353] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:5; response_revision:1360; }","duration":"101.796732ms","start":"2026-07-30T20:58:54.196116Z","end":"2026-07-30T20:58:54.297912Z","steps":["trace[688496353] 'range keys from in-memory index tree' (duration: 101.433273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:54.298278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.61393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-30T20:58:54.298441Z","caller":"traceutil/trace.go:171","msg":"trace[1752562606] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1360; }","duration":"106.836525ms","start":"2026-07-30T20:58:54.191583Z","end":"2026-07-30T20:58:54.29842Z","steps":["trace[1752562606] 'range keys from in-memory index tree' (duration: 106.295382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:55.256844Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569021283761333,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:58:55.75824Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569021283761333,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:58:55.986417Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.343076136s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T20:58:55.986844Z","caller":"traceutil/trace.go:171","msg":"trace[1862833725] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1422; }","duration":"1.230885059s","start":"2026-07-30T20:58:54.755929Z","end":"2026-07-30T20:58:55.986814Z","steps":["trace[1862833725] 'read index received' (duration: 1.230629693s)","trace[1862833725] 'applied index is now lower than readState.Index' (duration: 254.206µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:55.986953Z","caller":"traceutil/trace.go:171","msg":"trace[1589348819] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"1.343694932s","start":"2026-07-30T20:58:54.643224Z","end":"2026-07-30T20:58:55.986919Z","steps":["trace[1589348819] 'process raft request' (duration: 1.343433436s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:55.987026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231074525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:55.987062Z","caller":"traceutil/trace.go:171","msg":"trace[877211295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"1.231186337s","start":"2026-07-30T20:58:54.755866Z","end":"2026-07-30T20:58:55.987052Z","steps":["trace[877211295] 'agreement among raft nodes before linearized reading' (duration: 1.231090205s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:55.9871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:54.755844Z","time spent":"1.231248498s","remote":"127.0.0.1:46284","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-07-30T20:58:55.98713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.496802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:58:55.987158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:54.643198Z","time spent":"1.343829236s","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:55.987199Z","caller":"traceutil/trace.go:171","msg":"trace[213251633] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1361; }","duration":"896.656096ms","start":"2026-07-30T20:58:55.090517Z","end":"2026-07-30T20:58:55.987173Z","steps":["trace[213251633] 'agreement among raft nodes before linearized reading' (duration: 896.500252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:55.987241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.090488Z","time spent":"896.742928ms","remote":"127.0.0.1:56440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-30T20:58:55.987552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.54325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-30T20:58:55.987568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.756068ms","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-07-30T20:58:55.987599Z","caller":"traceutil/trace.go:171","msg":"trace[551043233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1361; }","duration":"596.591911ms","start":"2026-07-30T20:58:55.390995Z","end":"2026-07-30T20:58:55.987587Z","steps":["trace[551043233] 'agreement among raft nodes before linearized reading' (duration: 596.425087ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:55.9876Z","caller":"traceutil/trace.go:171","msg":"trace[2053403185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1361; }","duration":"765.84814ms","start":"2026-07-30T20:58:55.221742Z","end":"2026-07-30T20:58:55.98759Z","steps":["trace[2053403185] 'agreement among raft nodes before linearized reading' (duration: 765.693757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:55.987629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.390924Z","time spent":"596.699244ms","remote":"127.0.0.1:46554","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-07-30T20:58:55.987634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.221688Z","time spent":"765.940124ms","remote":"127.0.0.1:46554","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-07-30T20:58:55.987645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.084844ms","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-07-30T20:58:55.987648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.722834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-30T20:58:55.987526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.367657ms","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-07-30T20:58:55.9877Z","caller":"traceutil/trace.go:171","msg":"trace[401480093] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1361; }","duration":"550.794777ms","start":"2026-07-30T20:58:55.436891Z","end":"2026-07-30T20:58:55.987685Z","steps":["trace[401480093] 'agreement among raft nodes before linearized reading' (duration: 550.674084ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:55.9877Z","caller":"traceutil/trace.go:171","msg":"trace[120956570] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1361; }","duration":"573.148575ms","start":"2026-07-30T20:58:55.414532Z","end":"2026-07-30T20:58:55.987681Z","steps":["trace[120956570] 'agreement among raft nodes before linearized reading' (duration: 573.049953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:55.98773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.436879Z","time spent":"550.844507ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-30T20:58:55.987721Z","caller":"traceutil/trace.go:171","msg":"trace[773078689] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1361; }","duration":"594.602422ms","start":"2026-07-30T20:58:55.3931Z","end":"2026-07-30T20:58:55.987702Z","steps":["trace[773078689] 'agreement among raft nodes before linearized reading' (duration: 594.12606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:55.987757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.414521Z","time spent":"573.228197ms","remote":"127.0.0.1:46554","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-07-30T20:58:55.987796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.393088Z","time spent":"594.691894ms","remote":"127.0.0.1:46554","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-07-30T20:58:55.987838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.985541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T20:58:55.987927Z","caller":"traceutil/trace.go:171","msg":"trace[279549430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1361; }","duration":"595.104084ms","start":"2026-07-30T20:58:55.392801Z","end":"2026-07-30T20:58:55.987905Z","steps":["trace[279549430] 'agreement among raft nodes before linearized reading' (duration: 594.800467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:55.987977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.392735Z","time spent":"595.233217ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-30T20:58:56.31193Z","caller":"traceutil/trace.go:171","msg":"trace[1725919639] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"319.565103ms","start":"2026-07-30T20:58:55.992236Z","end":"2026-07-30T20:58:56.311801Z","steps":["trace[1725919639] 'process raft request' (duration: 258.711777ms)","trace[1725919639] 'compare' (duration: 60.701593ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:56.312159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.992224Z","time spent":"319.79921ms","remote":"127.0.0.1:46554","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-07-30T20:58:56.314678Z","caller":"traceutil/trace.go:171","msg":"trace[1138650606] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"322.28327ms","start":"2026-07-30T20:58:55.992366Z","end":"2026-07-30T20:58:56.314649Z","steps":["trace[1138650606] 'process raft request' (duration: 322.182478ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:56.314678Z","caller":"traceutil/trace.go:171","msg":"trace[1142164495] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"322.283651ms","start":"2026-07-30T20:58:55.992345Z","end":"2026-07-30T20:58:56.314629Z","steps":["trace[1142164495] 'process raft request' (duration: 322.080506ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:56.314775Z","caller":"traceutil/trace.go:171","msg":"trace[321485292] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"320.703423ms","start":"2026-07-30T20:58:55.994038Z","end":"2026-07-30T20:58:56.314742Z","steps":["trace[321485292] 'process raft request' (duration: 320.550419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:56.314853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.992331Z","time spent":"322.430554ms","remote":"127.0.0.1:46554","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-07-30T20:58:56.314855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.992346Z","time spent":"322.415254ms","remote":"127.0.0.1:46554","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-07-30T20:58:56.314883Z","caller":"traceutil/trace.go:171","msg":"trace[1308712401] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"320.748053ms","start":"2026-07-30T20:58:55.994098Z","end":"2026-07-30T20:58:56.314847Z","steps":["trace[1308712401] 'process raft request' (duration: 320.524228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:56.314979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.993954Z","time spent":"320.913268ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:58:56.315053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.994083Z","time spent":"320.877327ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:56.315395Z","caller":"traceutil/trace.go:171","msg":"trace[79657928] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1423; }","duration":"316.761514ms","start":"2026-07-30T20:58:55.998619Z","end":"2026-07-30T20:58:56.315381Z","steps":["trace[79657928] 'read index received' (duration: 252.344321ms)","trace[79657928] 'applied index is now lower than readState.Index' (duration: 64.416403ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:56.315488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.882547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:56.315523Z","caller":"traceutil/trace.go:171","msg":"trace[960800033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1367; }","duration":"316.956929ms","start":"2026-07-30T20:58:55.998553Z","end":"2026-07-30T20:58:56.31551Z","steps":["trace[960800033] 'agreement among raft nodes before linearized reading' (duration: 316.878007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:56.315558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.998534Z","time spent":"317.01709ms","remote":"127.0.0.1:46284","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-07-30T20:58:56.315686Z","caller":"traceutil/trace.go:171","msg":"trace[68721520] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"321.310517ms","start":"2026-07-30T20:58:55.994358Z","end":"2026-07-30T20:58:56.315668Z","steps":["trace[68721520] 'process raft request' (duration: 320.833336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:56.315765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:55.994322Z","time spent":"321.399499ms","remote":"127.0.0.1:46554","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-07-30T20:58:58.119975Z","caller":"traceutil/trace.go:171","msg":"trace[1942362268] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"126.343856ms","start":"2026-07-30T20:58:57.993587Z","end":"2026-07-30T20:58:58.119931Z","steps":["trace[1942362268] 'process raft request' (duration: 126.199182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:04.367609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.323034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-07-30T20:59:04.367731Z","caller":"traceutil/trace.go:171","msg":"trace[1140187061] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1420; }","duration":"126.500109ms","start":"2026-07-30T20:59:04.241196Z","end":"2026-07-30T20:59:04.367696Z","steps":["trace[1140187061] 'range keys from in-memory index tree' (duration: 126.043258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:04.367971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.505635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-07-30T20:59:04.368054Z","caller":"traceutil/trace.go:171","msg":"trace[263023782] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1420; }","duration":"164.640728ms","start":"2026-07-30T20:59:04.203396Z","end":"2026-07-30T20:59:04.368037Z","steps":["trace[263023782] 'range keys from in-memory index tree' (duration: 163.951891ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:18.059102Z","caller":"traceutil/trace.go:171","msg":"trace[1222860827] transaction","detail":"{read_only:false; response_revision:1760; number_of_response:1; }","duration":"239.488246ms","start":"2026-07-30T21:00:17.819587Z","end":"2026-07-30T21:00:18.059075Z","steps":["trace[1222860827] 'process raft request' (duration: 239.340672ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:44.646759Z","caller":"traceutil/trace.go:171","msg":"trace[1296414130] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"103.633203ms","start":"2026-07-30T21:00:44.543092Z","end":"2026-07-30T21:00:44.646725Z","steps":["trace[1296414130] 'process raft request' (duration: 103.402847ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:27.43237Z","caller":"traceutil/trace.go:171","msg":"trace[2090464227] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"142.228422ms","start":"2026-07-30T21:01:27.290109Z","end":"2026-07-30T21:01:27.432337Z","steps":["trace[2090464227] 'process raft request' (duration: 113.175109ms)","trace[2090464227] 'compare' (duration: 28.879729ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:27.432432Z","caller":"traceutil/trace.go:171","msg":"trace[877717898] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"141.838142ms","start":"2026-07-30T21:01:27.290577Z","end":"2026-07-30T21:01:27.432415Z","steps":["trace[877717898] 'process raft request' (duration: 141.707059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:27.668848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.922805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-30T21:01:27.668937Z","caller":"traceutil/trace.go:171","msg":"trace[1634098012] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2081; }","duration":"118.082639ms","start":"2026-07-30T21:01:27.550831Z","end":"2026-07-30T21:01:27.668914Z","steps":["trace[1634098012] 'range keys from in-memory index tree' (duration: 117.675749ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:43.62648Z","caller":"traceutil/trace.go:171","msg":"trace[26671827] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"106.078933ms","start":"2026-07-30T21:01:43.520377Z","end":"2026-07-30T21:01:43.626456Z","steps":["trace[26671827] 'process raft request' (duration: 106.004752ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:43.626517Z","caller":"traceutil/trace.go:171","msg":"trace[141109249] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"106.106264ms","start":"2026-07-30T21:01:43.520374Z","end":"2026-07-30T21:01:43.62648Z","steps":["trace[141109249] 'process raft request' (duration: 97.285768ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:49.791104Z","caller":"traceutil/trace.go:171","msg":"trace[303720026] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"136.484611ms","start":"2026-07-30T21:01:49.654598Z","end":"2026-07-30T21:01:49.791083Z","steps":["trace[303720026] 'process raft request' (duration: 136.206015ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:02.747112Z","caller":"traceutil/trace.go:171","msg":"trace[448815477] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"107.188831ms","start":"2026-07-30T21:02:02.639896Z","end":"2026-07-30T21:02:02.747085Z","steps":["trace[448815477] 'process raft request' (duration: 106.791642ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:05.664655Z","caller":"traceutil/trace.go:171","msg":"trace[1235223814] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"424.480401ms","start":"2026-07-30T21:02:05.240148Z","end":"2026-07-30T21:02:05.664628Z","steps":["trace[1235223814] 'process raft request' (duration: 424.326957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:05.664844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:05.240129Z","time spent":"424.618674ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:02:05.66555Z","caller":"traceutil/trace.go:171","msg":"trace[1965747764] linearizableReadLoop","detail":"{readStateIndex:2351; appliedIndex:2351; }","duration":"348.64679ms","start":"2026-07-30T21:02:05.316885Z","end":"2026-07-30T21:02:05.665531Z","steps":["trace[1965747764] 'read index received' (duration: 348.63729ms)","trace[1965747764] 'applied index is now lower than readState.Index' (duration: 7.5µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:02:05.665863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.966487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-30T21:02:05.66593Z","caller":"traceutil/trace.go:171","msg":"trace[1450174450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2251; }","duration":"349.036349ms","start":"2026-07-30T21:02:05.316878Z","end":"2026-07-30T21:02:05.665914Z","steps":["trace[1450174450] 'agreement among raft nodes before linearized reading' (duration: 348.839674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:05.665953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.277366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-30T21:02:05.665974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:05.316826Z","time spent":"349.136202ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T21:02:05.666006Z","caller":"traceutil/trace.go:171","msg":"trace[1942212373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2251; }","duration":"224.378189ms","start":"2026-07-30T21:02:05.441615Z","end":"2026-07-30T21:02:05.665994Z","steps":["trace[1942212373] 'agreement among raft nodes before linearized reading' (duration: 224.218195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:05.66617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.896093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:02:05.666189Z","caller":"traceutil/trace.go:171","msg":"trace[1858205412] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2251; }","duration":"132.944105ms","start":"2026-07-30T21:02:05.53324Z","end":"2026-07-30T21:02:05.666184Z","steps":["trace[1858205412] 'agreement among raft nodes before linearized reading' (duration: 132.911344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:06.25645Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569021283763500,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:02:08.144477Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.770030774s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-30T21:02:08.147744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.773183672s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:02:08.147975Z","caller":"traceutil/trace.go:171","msg":"trace[1405207601] linearizableReadLoop","detail":"{readStateIndex:2354; appliedIndex:2351; }","duration":"2.392058915s","start":"2026-07-30T21:02:05.7559Z","end":"2026-07-30T21:02:08.147959Z","steps":["trace[1405207601] 'read index received' (duration: 618.574846ms)","trace[1405207601] 'applied index is now lower than readState.Index' (duration: 1.773483129s)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:08.147987Z","caller":"traceutil/trace.go:171","msg":"trace[222266854] transaction","detail":"{read_only:false; response_revision:2252; number_of_response:1; }","duration":"2.478466465s","start":"2026-07-30T21:02:05.669487Z","end":"2026-07-30T21:02:08.147953Z","steps":["trace[222266854] 'process raft request' (duration: 705.004218ms)","trace[222266854] 'compare' (duration: 1.773046637s)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:02:08.148134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:05.66947Z","time spent":"2.478590079s","remote":"127.0.0.1:46554","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-07-30T21:02:08.148213Z","caller":"traceutil/trace.go:171","msg":"trace[2076146288] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"2.478006885s","start":"2026-07-30T21:02:05.670175Z","end":"2026-07-30T21:02:08.148182Z","steps":["trace[2076146288] 'process raft request' (duration: 2.477749299s)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:08.148312Z","caller":"traceutil/trace.go:171","msg":"trace[2111184711] transaction","detail":"{read_only:false; response_revision:2253; number_of_response:1; }","duration":"2.478480706s","start":"2026-07-30T21:02:05.669818Z","end":"2026-07-30T21:02:08.148299Z","steps":["trace[2111184711] 'process raft request' (duration: 2.478021155s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:08.148365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:05.670151Z","time spent":"2.478130837s","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:02:08.148376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:05.669808Z","time spent":"2.478537608s","remote":"127.0.0.1:46554","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-07-30T21:02:08.148249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.392350452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-30T21:02:08.148474Z","caller":"traceutil/trace.go:171","msg":"trace[1322371013] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2254; }","duration":"2.392606878s","start":"2026-07-30T21:02:05.755853Z","end":"2026-07-30T21:02:08.14846Z","steps":["trace[1322371013] 'agreement among raft nodes before linearized reading' (duration: 2.39230644s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:08.148521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:05.755834Z","time spent":"2.39267515s","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T21:02:08.156521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.265199731s","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-07-30T21:02:08.156565Z","caller":"traceutil/trace.go:171","msg":"trace[545286789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2255; }","duration":"2.265268623s","start":"2026-07-30T21:02:05.891285Z","end":"2026-07-30T21:02:08.156554Z","steps":["trace[545286789] 'agreement among raft nodes before linearized reading' (duration: 2.26514705s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:08.156624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:05.89127Z","time spent":"2.265346834s","remote":"127.0.0.1:46554","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":"info","ts":"2026-07-30T21:02:08.156808Z","caller":"traceutil/trace.go:171","msg":"trace[1001446739] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"1.168662601s","start":"2026-07-30T21:02:06.988137Z","end":"2026-07-30T21:02:08.156799Z","steps":["trace[1001446739] 'process raft request' (duration: 1.16822393s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:08.156892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:06.988113Z","time spent":"1.168723761s","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:02:08.157042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.160463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-30T21:02:08.157096Z","caller":"traceutil/trace.go:171","msg":"trace[1831113541] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2255; }","duration":"345.221305ms","start":"2026-07-30T21:02:07.811849Z","end":"2026-07-30T21:02:08.15707Z","steps":["trace[1831113541] 'agreement among raft nodes before linearized reading' (duration: 345.143833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:08.157121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:07.811834Z","time spent":"345.280836ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T21:02:08.157257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380902141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-30T21:02:08.157296Z","caller":"traceutil/trace.go:171","msg":"trace[910373715] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2255; }","duration":"1.380949452s","start":"2026-07-30T21:02:06.776324Z","end":"2026-07-30T21:02:08.157274Z","steps":["trace[910373715] 'agreement among raft nodes before linearized reading' (duration: 1.380893801s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:08.15732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:06.776315Z","time spent":"1.380999444s","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-30T21:02:08.157446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39879879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.61\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-30T21:02:08.157483Z","caller":"traceutil/trace.go:171","msg":"trace[1482261773] range","detail":"{range_begin:/registry/masterleases/199.204.45.61; range_end:; response_count:1; response_revision:2255; }","duration":"1.398861752s","start":"2026-07-30T21:02:06.758601Z","end":"2026-07-30T21:02:08.157463Z","steps":["trace[1482261773] 'agreement among raft nodes before linearized reading' (duration: 1.39880386s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:08.157507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:06.75858Z","time spent":"1.398921653s","remote":"127.0.0.1:46300","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.61\" "} {"level":"warn","ts":"2026-07-30T21:02:08.157664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400211515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:02:08.157687Z","caller":"traceutil/trace.go:171","msg":"trace[205091556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2255; }","duration":"1.400271597s","start":"2026-07-30T21:02:06.75741Z","end":"2026-07-30T21:02:08.157681Z","steps":["trace[205091556] 'agreement among raft nodes before linearized reading' (duration: 1.400236116s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:08.157735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:06.757388Z","time spent":"1.400341798s","remote":"127.0.0.1:46284","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-07-30T21:02:08.157877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438088244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-30T21:02:08.157916Z","caller":"traceutil/trace.go:171","msg":"trace[2079642198] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2255; }","duration":"1.438147726s","start":"2026-07-30T21:02:06.719746Z","end":"2026-07-30T21:02:08.157894Z","steps":["trace[2079642198] 'agreement among raft nodes before linearized reading' (duration: 1.438089955s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:08.157939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:06.719724Z","time spent":"1.438209318s","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-30T21:02:13.646219Z","caller":"traceutil/trace.go:171","msg":"trace[1915553355] linearizableReadLoop","detail":"{readStateIndex:2381; appliedIndex:2380; }","duration":"464.630258ms","start":"2026-07-30T21:02:13.181567Z","end":"2026-07-30T21:02:13.646197Z","steps":["trace[1915553355] 'read index received' (duration: 464.442262ms)","trace[1915553355] 'applied index is now lower than readState.Index' (duration: 186.646µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:13.646326Z","caller":"traceutil/trace.go:171","msg":"trace[699665335] transaction","detail":"{read_only:false; response_revision:2279; number_of_response:1; }","duration":"464.938234ms","start":"2026-07-30T21:02:13.181366Z","end":"2026-07-30T21:02:13.646304Z","steps":["trace[699665335] 'process raft request' (duration: 464.666857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:13.646431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.849853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-30T21:02:13.646451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:13.181344Z","time spent":"465.033547ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:02:13.646464Z","caller":"traceutil/trace.go:171","msg":"trace[535548919] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2279; }","duration":"464.919284ms","start":"2026-07-30T21:02:13.181534Z","end":"2026-07-30T21:02:13.646453Z","steps":["trace[535548919] 'agreement among raft nodes before linearized reading' (duration: 464.788061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:13.646496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:13.181518Z","time spent":"464.970446ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T21:02:13.646616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.948312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:02:13.646682Z","caller":"traceutil/trace.go:171","msg":"trace[1187056547] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2279; }","duration":"370.026784ms","start":"2026-07-30T21:02:13.276638Z","end":"2026-07-30T21:02:13.646664Z","steps":["trace[1187056547] 'agreement among raft nodes before linearized reading' (duration: 369.911811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:13.646721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:13.276603Z","time spent":"370.109006ms","remote":"127.0.0.1:46564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-07-30T21:02:13.925899Z","caller":"traceutil/trace.go:171","msg":"trace[1070452584] linearizableReadLoop","detail":"{readStateIndex:2382; appliedIndex:2381; }","duration":"168.567048ms","start":"2026-07-30T21:02:13.757303Z","end":"2026-07-30T21:02:13.925871Z","steps":["trace[1070452584] 'read index received' (duration: 166.249432ms)","trace[1070452584] 'applied index is now lower than readState.Index' (duration: 2.316186ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:13.925948Z","caller":"traceutil/trace.go:171","msg":"trace[942578182] transaction","detail":"{read_only:false; response_revision:2280; number_of_response:1; }","duration":"274.570491ms","start":"2026-07-30T21:02:13.651355Z","end":"2026-07-30T21:02:13.925925Z","steps":["trace[942578182] 'process raft request' (duration: 272.292346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:13.926034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.738383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:02:13.92609Z","caller":"traceutil/trace.go:171","msg":"trace[2083808878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2280; }","duration":"168.845625ms","start":"2026-07-30T21:02:13.757228Z","end":"2026-07-30T21:02:13.926074Z","steps":["trace[2083808878] 'agreement among raft nodes before linearized reading' (duration: 168.742683ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:18.774047Z","caller":"traceutil/trace.go:171","msg":"trace[1405213489] transaction","detail":"{read_only:false; response_revision:2306; number_of_response:1; }","duration":"118.571577ms","start":"2026-07-30T21:02:18.655448Z","end":"2026-07-30T21:02:18.77402Z","steps":["trace[1405213489] 'process raft request' (duration: 118.165127ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:21.176978Z","caller":"traceutil/trace.go:171","msg":"trace[2083510961] transaction","detail":"{read_only:false; response_revision:2317; number_of_response:1; }","duration":"141.925753ms","start":"2026-07-30T21:02:21.035027Z","end":"2026-07-30T21:02:21.176953Z","steps":["trace[2083510961] 'process raft request' (duration: 141.202714ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:39.10725Z","caller":"traceutil/trace.go:171","msg":"trace[1606037884] linearizableReadLoop","detail":"{readStateIndex:2505; appliedIndex:2504; }","duration":"138.703292ms","start":"2026-07-30T21:02:38.968521Z","end":"2026-07-30T21:02:39.107224Z","steps":["trace[1606037884] 'read index received' (duration: 138.518558ms)","trace[1606037884] 'applied index is now lower than readState.Index' (duration: 183.604µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:39.107313Z","caller":"traceutil/trace.go:171","msg":"trace[887675844] transaction","detail":"{read_only:false; response_revision:2398; number_of_response:1; }","duration":"299.192362ms","start":"2026-07-30T21:02:38.808091Z","end":"2026-07-30T21:02:39.107283Z","steps":["trace[887675844] 'process raft request' (duration: 298.977617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:39.107446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.914418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-30T21:02:39.107491Z","caller":"traceutil/trace.go:171","msg":"trace[1347415114] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2398; }","duration":"139.01839ms","start":"2026-07-30T21:02:38.96846Z","end":"2026-07-30T21:02:39.107478Z","steps":["trace[1347415114] 'agreement among raft nodes before linearized reading' (duration: 138.863956ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:39.318057Z","caller":"traceutil/trace.go:171","msg":"trace[665822606] transaction","detail":"{read_only:false; response_revision:2399; number_of_response:1; }","duration":"205.164499ms","start":"2026-07-30T21:02:39.112866Z","end":"2026-07-30T21:02:39.318031Z","steps":["trace[665822606] 'process raft request' (duration: 183.737661ms)","trace[665822606] 'compare' (duration: 21.324324ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:16.579291Z","caller":"traceutil/trace.go:171","msg":"trace[1296703493] transaction","detail":"{read_only:false; response_revision:2594; number_of_response:1; }","duration":"113.831111ms","start":"2026-07-30T21:03:16.465436Z","end":"2026-07-30T21:03:16.579267Z","steps":["trace[1296703493] 'process raft request' (duration: 113.652996ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:31.096237Z","caller":"traceutil/trace.go:171","msg":"trace[1427658722] linearizableReadLoop","detail":"{readStateIndex:2789; appliedIndex:2788; }","duration":"329.787479ms","start":"2026-07-30T21:03:30.76642Z","end":"2026-07-30T21:03:31.096208Z","steps":["trace[1427658722] 'read index received' (duration: 329.605214ms)","trace[1427658722] 'applied index is now lower than readState.Index' (duration: 180.795µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:31.096347Z","caller":"traceutil/trace.go:171","msg":"trace[1757045924] transaction","detail":"{read_only:false; response_revision:2670; number_of_response:1; }","duration":"348.549972ms","start":"2026-07-30T21:03:30.747759Z","end":"2026-07-30T21:03:31.096309Z","steps":["trace[1757045924] 'process raft request' (duration: 348.287156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:31.096493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:30.747738Z","time spent":"348.685766ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:03:31.096509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.751453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-30T21:03:31.096552Z","caller":"traceutil/trace.go:171","msg":"trace[700513510] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2670; }","duration":"328.818055ms","start":"2026-07-30T21:03:30.767719Z","end":"2026-07-30T21:03:31.096538Z","steps":["trace[700513510] 'agreement among raft nodes before linearized reading' (duration: 328.652721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:31.096535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.077606ms","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":"warn","ts":"2026-07-30T21:03:31.096589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:30.767703Z","time spent":"328.877086ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T21:03:31.0966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.762394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:03:31.096621Z","caller":"traceutil/trace.go:171","msg":"trace[2060201920] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2670; }","duration":"330.196728ms","start":"2026-07-30T21:03:30.766402Z","end":"2026-07-30T21:03:31.096599Z","steps":["trace[2060201920] 'agreement among raft nodes before linearized reading' (duration: 329.918501ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:31.096675Z","caller":"traceutil/trace.go:171","msg":"trace[634535590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2670; }","duration":"328.852455ms","start":"2026-07-30T21:03:30.767798Z","end":"2026-07-30T21:03:31.096651Z","steps":["trace[634535590] 'agreement among raft nodes before linearized reading' (duration: 328.689531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:31.096683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:30.766392Z","time spent":"330.27724ms","remote":"127.0.0.1:46554","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-07-30T21:03:31.096725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:30.767782Z","time spent":"328.931707ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-30T21:03:31.769237Z","caller":"traceutil/trace.go:171","msg":"trace[713416015] transaction","detail":"{read_only:false; response_revision:2671; number_of_response:1; }","duration":"668.515178ms","start":"2026-07-30T21:03:31.100703Z","end":"2026-07-30T21:03:31.769218Z","steps":["trace[713416015] 'process raft request' (duration: 667.468322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:31.769342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:31.100652Z","time spent":"668.634791ms","remote":"127.0.0.1:46554","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-07-30T21:03:31.769814Z","caller":"traceutil/trace.go:171","msg":"trace[1549284976] transaction","detail":"{read_only:false; response_revision:2673; number_of_response:1; }","duration":"668.817665ms","start":"2026-07-30T21:03:31.100984Z","end":"2026-07-30T21:03:31.769801Z","steps":["trace[1549284976] 'process raft request' (duration: 668.61929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:31.769868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:31.100962Z","time spent":"668.879946ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:03:31.769943Z","caller":"traceutil/trace.go:171","msg":"trace[1857300842] transaction","detail":"{read_only:false; response_revision:2672; number_of_response:1; }","duration":"668.995259ms","start":"2026-07-30T21:03:31.100932Z","end":"2026-07-30T21:03:31.769927Z","steps":["trace[1857300842] 'process raft request' (duration: 668.593759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:31.770431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:31.100915Z","time spent":"669.063811ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:03:42.006269Z","caller":"traceutil/trace.go:171","msg":"trace[485931832] linearizableReadLoop","detail":"{readStateIndex:2841; appliedIndex:2839; }","duration":"184.884468ms","start":"2026-07-30T21:03:41.821358Z","end":"2026-07-30T21:03:42.006242Z","steps":["trace[485931832] 'read index received' (duration: 162.539586ms)","trace[485931832] 'applied index is now lower than readState.Index' (duration: 22.343952ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:42.006303Z","caller":"traceutil/trace.go:171","msg":"trace[882149499] transaction","detail":"{read_only:false; response_revision:2719; number_of_response:1; }","duration":"191.114991ms","start":"2026-07-30T21:03:41.815146Z","end":"2026-07-30T21:03:42.006261Z","steps":["trace[882149499] 'process raft request' (duration: 168.814791ms)","trace[882149499] 'compare' (duration: 22.070365ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:42.006406Z","caller":"traceutil/trace.go:171","msg":"trace[2078990324] transaction","detail":"{read_only:false; response_revision:2720; number_of_response:1; }","duration":"191.052601ms","start":"2026-07-30T21:03:41.81534Z","end":"2026-07-30T21:03:42.006393Z","steps":["trace[2078990324] 'process raft request' (duration: 190.852696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:42.006899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.524303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:monitoring\" ","response":"range_response_count:1 size:634"} {"level":"info","ts":"2026-07-30T21:03:42.006946Z","caller":"traceutil/trace.go:171","msg":"trace[603452428] range","detail":"{range_begin:/registry/clusterroles/system:monitoring; range_end:; response_count:1; response_revision:2720; }","duration":"185.616126ms","start":"2026-07-30T21:03:41.821318Z","end":"2026-07-30T21:03:42.006934Z","steps":["trace[603452428] 'agreement among raft nodes before linearized reading' (duration: 185.521734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:42.006924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.743003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:03:42.006931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.422442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:03:42.006962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.348845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-07-30T21:03:42.007022Z","caller":"traceutil/trace.go:171","msg":"trace[725642982] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap; range_end:; response_count:1; response_revision:2720; }","duration":"129.421448ms","start":"2026-07-30T21:03:41.877585Z","end":"2026-07-30T21:03:42.007006Z","steps":["trace[725642982] 'agreement among raft nodes before linearized reading' (duration: 129.312385ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:42.007019Z","caller":"traceutil/trace.go:171","msg":"trace[160010529] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2720; }","duration":"115.538074ms","start":"2026-07-30T21:03:41.891461Z","end":"2026-07-30T21:03:42.006999Z","steps":["trace[160010529] 'agreement among raft nodes before linearized reading' (duration: 115.401141ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:42.007017Z","caller":"traceutil/trace.go:171","msg":"trace[1835801682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2720; }","duration":"137.875796ms","start":"2026-07-30T21:03:41.869118Z","end":"2026-07-30T21:03:42.006994Z","steps":["trace[1835801682] 'agreement among raft nodes before linearized reading' (duration: 137.730703ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:43.217638Z","caller":"traceutil/trace.go:171","msg":"trace[1065525178] linearizableReadLoop","detail":"{readStateIndex:2846; appliedIndex:2844; }","duration":"435.256305ms","start":"2026-07-30T21:03:42.782355Z","end":"2026-07-30T21:03:43.217611Z","steps":["trace[1065525178] 'read index received' (duration: 37.308102ms)","trace[1065525178] 'applied index is now lower than readState.Index' (duration: 397.947253ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:43.217783Z","caller":"traceutil/trace.go:171","msg":"trace[1292857435] transaction","detail":"{read_only:false; response_revision:2724; number_of_response:1; }","duration":"436.671179ms","start":"2026-07-30T21:03:42.781078Z","end":"2026-07-30T21:03:43.217749Z","steps":["trace[1292857435] 'process raft request' (duration: 436.385252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:43.217815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.441129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:767"} {"level":"warn","ts":"2026-07-30T21:03:43.217934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.129247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-proxier\" ","response":"range_response_count:1 size:712"} {"level":"warn","ts":"2026-07-30T21:03:43.218017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:42.781062Z","time spent":"436.816703ms","remote":"127.0.0.1:46554","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-07-30T21:03:43.218033Z","caller":"traceutil/trace.go:171","msg":"trace[1663386299] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:2724; }","duration":"435.683516ms","start":"2026-07-30T21:03:42.782331Z","end":"2026-07-30T21:03:43.218015Z","steps":["trace[1663386299] 'agreement among raft nodes before linearized reading' (duration: 435.417159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:43.218394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:42.782321Z","time spent":"436.048004ms","remote":"127.0.0.1:46620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":791,"request content":"key:\"/registry/clusterrolebindings/system:controller:attachdetach-controller\" "} {"level":"info","ts":"2026-07-30T21:03:43.218203Z","caller":"traceutil/trace.go:171","msg":"trace[331315168] range","detail":"{range_begin:/registry/clusterroles/system:node-proxier; range_end:; response_count:1; response_revision:2724; }","duration":"434.377812ms","start":"2026-07-30T21:03:42.783758Z","end":"2026-07-30T21:03:43.218136Z","steps":["trace[331315168] 'agreement among raft nodes before linearized reading' (duration: 434.036774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:43.218547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:42.783752Z","time spent":"434.778682ms","remote":"127.0.0.1:46616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":736,"request content":"key:\"/registry/clusterroles/system:node-proxier\" "} {"level":"info","ts":"2026-07-30T21:03:43.638877Z","caller":"traceutil/trace.go:171","msg":"trace[1618218046] transaction","detail":"{read_only:false; response_revision:2725; number_of_response:1; }","duration":"415.868275ms","start":"2026-07-30T21:03:43.22298Z","end":"2026-07-30T21:03:43.638848Z","steps":["trace[1618218046] 'process raft request' (duration: 370.527545ms)","trace[1618218046] 'compare' (duration: 44.92334ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:03:43.639028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:43.222966Z","time spent":"415.986009ms","remote":"127.0.0.1:46554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:03:57.935322Z","caller":"traceutil/trace.go:171","msg":"trace[1354421149] linearizableReadLoop","detail":"{readStateIndex:2916; appliedIndex:2915; }","duration":"127.071069ms","start":"2026-07-30T21:03:57.80823Z","end":"2026-07-30T21:03:57.935301Z","steps":["trace[1354421149] 'read index received' (duration: 126.910726ms)","trace[1354421149] 'applied index is now lower than readState.Index' (duration: 159.263µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:57.935379Z","caller":"traceutil/trace.go:171","msg":"trace[536614880] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"142.84201ms","start":"2026-07-30T21:03:57.792501Z","end":"2026-07-30T21:03:57.935343Z","steps":["trace[536614880] 'process raft request' (duration: 142.658155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:57.935547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.144543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-07-30T21:03:57.935623Z","caller":"traceutil/trace.go:171","msg":"trace[2005375363] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:2791; }","duration":"104.270186ms","start":"2026-07-30T21:03:57.831332Z","end":"2026-07-30T21:03:57.935602Z","steps":["trace[2005375363] 'agreement among raft nodes before linearized reading' (duration: 104.124372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:57.93583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.587142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1\" ","response":"range_response_count:1 size:317290"} {"level":"info","ts":"2026-07-30T21:03:57.935875Z","caller":"traceutil/trace.go:171","msg":"trace[453584508] range","detail":"{range_begin:/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1; range_end:; response_count:1; response_revision:2791; }","duration":"127.662924ms","start":"2026-07-30T21:03:57.808199Z","end":"2026-07-30T21:03:57.935862Z","steps":["trace[453584508] 'agreement among raft nodes before linearized reading' (duration: 127.233523ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:58.102576Z","caller":"traceutil/trace.go:171","msg":"trace[1745943325] linearizableReadLoop","detail":"{readStateIndex:2917; appliedIndex:2916; }","duration":"120.997499ms","start":"2026-07-30T21:03:57.981554Z","end":"2026-07-30T21:03:58.102551Z","steps":["trace[1745943325] 'read index received' (duration: 120.813275ms)","trace[1745943325] 'applied index is now lower than readState.Index' (duration: 182.964µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:58.102867Z","caller":"traceutil/trace.go:171","msg":"trace[447314264] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"163.146861ms","start":"2026-07-30T21:03:57.939698Z","end":"2026-07-30T21:03:58.102845Z","steps":["trace[447314264] 'process raft request' (duration: 162.72374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:58.102862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.286847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:03:58.103003Z","caller":"traceutil/trace.go:171","msg":"trace[482965263] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2792; }","duration":"121.469071ms","start":"2026-07-30T21:03:57.981514Z","end":"2026-07-30T21:03:58.102983Z","steps":["trace[482965263] 'agreement among raft nodes before linearized reading' (duration: 121.274506ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:05.347507Z","caller":"traceutil/trace.go:171","msg":"trace[1504907413] linearizableReadLoop","detail":"{readStateIndex:2952; appliedIndex:2951; }","duration":"105.779554ms","start":"2026-07-30T21:04:05.241703Z","end":"2026-07-30T21:04:05.347482Z","steps":["trace[1504907413] 'read index received' (duration: 25.141761ms)","trace[1504907413] 'applied index is now lower than readState.Index' (duration: 80.636383ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:04:05.347574Z","caller":"traceutil/trace.go:171","msg":"trace[1795590219] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"174.87052ms","start":"2026-07-30T21:04:05.172675Z","end":"2026-07-30T21:04:05.347545Z","steps":["trace[1795590219] 'process raft request' (duration: 94.273119ms)","trace[1795590219] 'compare' (duration: 80.418147ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:04:05.347719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.998989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-b9gt7\" ","response":"range_response_count:1 size:11386"} {"level":"info","ts":"2026-07-30T21:04:05.347776Z","caller":"traceutil/trace.go:171","msg":"trace[472790115] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-b9gt7; range_end:; response_count:1; response_revision:2826; }","duration":"106.087842ms","start":"2026-07-30T21:04:05.241673Z","end":"2026-07-30T21:04:05.34776Z","steps":["trace[472790115] 'agreement among raft nodes before linearized reading' (duration: 105.935348ms)"],"step_count":1}