{"level":"warn","ts":"2026-07-17T10:53:05.802047Z","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-17T10:53:05.802198Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70: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.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-17T10:53:05.802347Z","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-17T10:53:05.802367Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-07-17T10:53:05.802411Z","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-17T10:53:05.803453Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-07-17T10:53:05.803598Z","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.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-17T10:53:05.809233Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.262029ms"} {"level":"info","ts":"2026-07-17T10:53:05.82558Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-07-17T10:53:05.825912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T10:53:05.825993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-07-17T10:53:05.826026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T10:53:05.82608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-07-17T10:53:05.826194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-07-17T10:53:05.841338Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T10:53:05.843319Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T10:53:05.844854Z","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-17T10:53:05.84812Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T10:53:05.848286Z","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-17T10:53:05.848348Z","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-17T10:53:05.848384Z","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-17T10:53:05.848267Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T10:53:05.849615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-07-17T10:53:05.849782Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-07-17T10:53:05.855769Z","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-17T10:53:05.855842Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-07-17T10:53:05.85601Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-07-17T10:53:05.85699Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T10:53:05.856934Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T10:53:06.127262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T10:53:06.127338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T10:53:06.127382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-07-17T10:53:06.127401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-07-17T10:53:06.127413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-07-17T10:53:06.127638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-07-17T10:53:06.127664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-07-17T10:53:06.128672Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T10:53:06.129986Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T10:53:06.129989Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T10:53:06.130063Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T10:53:06.130559Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T10:53:06.130663Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T10:53:06.131795Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T10:53:06.131958Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T10:53:06.132026Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T10:53:06.132837Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-07-17T10:53:06.134819Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-17T10:53:13.161953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.218916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:53:13.162133Z","caller":"traceutil/trace.go:171","msg":"trace[139225894] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:328; }","duration":"113.436561ms","start":"2026-07-17T10:53:13.048676Z","end":"2026-07-17T10:53:13.162113Z","steps":["trace[139225894] 'range keys from in-memory index tree' (duration: 113.066471ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:53:16.947579Z","caller":"traceutil/trace.go:171","msg":"trace[536405758] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"208.104185ms","start":"2026-07-17T10:53:16.739405Z","end":"2026-07-17T10:53:16.947509Z","steps":["trace[536405758] 'process raft request' (duration: 207.690665ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:53:17.071511Z","caller":"traceutil/trace.go:171","msg":"trace[1143806342] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"114.327254ms","start":"2026-07-17T10:53:16.957152Z","end":"2026-07-17T10:53:17.07148Z","steps":["trace[1143806342] 'process raft request' (duration: 108.696345ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:53:17.072114Z","caller":"traceutil/trace.go:171","msg":"trace[973295766] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"108.005356ms","start":"2026-07-17T10:53:16.964095Z","end":"2026-07-17T10:53:17.0721Z","steps":["trace[973295766] 'process raft request' (duration: 107.290638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:18.927839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.887665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:53:18.927944Z","caller":"traceutil/trace.go:171","msg":"trace[1584028376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"139.059799ms","start":"2026-07-17T10:53:18.788859Z","end":"2026-07-17T10:53:18.927919Z","steps":["trace[1584028376] 'range keys from in-memory index tree' (duration: 138.795951ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:53:19.795465Z","caller":"traceutil/trace.go:171","msg":"trace[1738569139] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"164.952524ms","start":"2026-07-17T10:53:19.630156Z","end":"2026-07-17T10:53:19.795109Z","steps":["trace[1738569139] 'read index received' (duration: 157.865697ms)","trace[1738569139] 'applied index is now lower than readState.Index' (duration: 7.084377ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:53:19.796071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.025306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-07-17T10:53:19.796143Z","caller":"traceutil/trace.go:171","msg":"trace[837792650] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:403; }","duration":"148.190941ms","start":"2026-07-17T10:53:19.64794Z","end":"2026-07-17T10:53:19.796131Z","steps":["trace[837792650] 'agreement among raft nodes before linearized reading' (duration: 148.037357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:19.796312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.114215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-17T10:53:19.796369Z","caller":"traceutil/trace.go:171","msg":"trace[78312857] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"218.739146ms","start":"2026-07-17T10:53:19.577615Z","end":"2026-07-17T10:53:19.796354Z","steps":["trace[78312857] 'process raft request' (duration: 210.436947ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:53:19.796455Z","caller":"traceutil/trace.go:171","msg":"trace[1755232072] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:403; }","duration":"166.2941ms","start":"2026-07-17T10:53:19.630104Z","end":"2026-07-17T10:53:19.796398Z","steps":["trace[1755232072] 'agreement among raft nodes before linearized reading' (duration: 165.996122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:21.140316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.772667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:53:21.140471Z","caller":"traceutil/trace.go:171","msg":"trace[1693957443] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"224.065258ms","start":"2026-07-17T10:53:20.916378Z","end":"2026-07-17T10:53:21.140443Z","steps":["trace[1693957443] 'process raft request' (duration: 114.440299ms)","trace[1693957443] 'compare' (duration: 108.645553ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:53:24.198085Z","caller":"traceutil/trace.go:171","msg":"trace[689222160] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"335.774818ms","start":"2026-07-17T10:53:23.862282Z","end":"2026-07-17T10:53:24.198057Z","steps":["trace[689222160] 'process raft request' (duration: 335.610764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:24.198295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:23.862265Z","time spent":"335.943512ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:53:30.008957Z","caller":"traceutil/trace.go:171","msg":"trace[626399460] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"376.175135ms","start":"2026-07-17T10:53:29.63276Z","end":"2026-07-17T10:53:30.008935Z","steps":["trace[626399460] 'read index received' (duration: 375.98183ms)","trace[626399460] 'applied index is now lower than readState.Index' (duration: 192.265µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:53:30.009129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.35323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-17T10:53:30.009166Z","caller":"traceutil/trace.go:171","msg":"trace[953144989] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:430; }","duration":"376.443082ms","start":"2026-07-17T10:53:29.63271Z","end":"2026-07-17T10:53:30.009153Z","steps":["trace[953144989] 'agreement among raft nodes before linearized reading' (duration: 376.330379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:30.009203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:29.632689Z","time spent":"376.506324ms","remote":"127.0.0.1:43740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"info","ts":"2026-07-17T10:53:30.009242Z","caller":"traceutil/trace.go:171","msg":"trace[2039012724] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"403.884048ms","start":"2026-07-17T10:53:29.605301Z","end":"2026-07-17T10:53:30.009185Z","steps":["trace[2039012724] 'process raft request' (duration: 403.509038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:30.009554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:29.605281Z","time spent":"404.098605ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:53:30.613386Z","caller":"traceutil/trace.go:171","msg":"trace[992029006] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"384.631848ms","start":"2026-07-17T10:53:30.22872Z","end":"2026-07-17T10:53:30.613352Z","steps":["trace[992029006] 'read index received' (duration: 368.0373ms)","trace[992029006] 'applied index is now lower than readState.Index' (duration: 16.592628ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:53:30.613417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:30.010745Z","time spent":"602.660263ms","remote":"127.0.0.1:43740","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-17T10:53:30.613588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.830885ms","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-17T10:53:30.613637Z","caller":"traceutil/trace.go:171","msg":"trace[740254534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:430; }","duration":"384.939317ms","start":"2026-07-17T10:53:30.228683Z","end":"2026-07-17T10:53:30.613622Z","steps":["trace[740254534] 'agreement among raft nodes before linearized reading' (duration: 384.799952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:30.613681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:30.228655Z","time spent":"385.015739ms","remote":"127.0.0.1:44004","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-17T10:53:49.440855Z","caller":"traceutil/trace.go:171","msg":"trace[568655569] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"379.710338ms","start":"2026-07-17T10:53:49.061122Z","end":"2026-07-17T10:53:49.440832Z","steps":["trace[568655569] 'process raft request' (duration: 379.587745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:49.441044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:49.061109Z","time spent":"379.862592ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:53:49.532272Z","caller":"traceutil/trace.go:171","msg":"trace[837646815] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"400.984872ms","start":"2026-07-17T10:53:49.131265Z","end":"2026-07-17T10:53:49.53225Z","steps":["trace[837646815] 'process raft request' (duration: 400.867118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:49.532463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:49.131242Z","time spent":"401.125865ms","remote":"127.0.0.1:43756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:53:49.532449Z","caller":"traceutil/trace.go:171","msg":"trace[1571534368] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"388.964304ms","start":"2026-07-17T10:53:49.14344Z","end":"2026-07-17T10:53:49.532404Z","steps":["trace[1571534368] 'process raft request' (duration: 388.830041ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:53:49.532475Z","caller":"traceutil/trace.go:171","msg":"trace[2023606543] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"400.960361ms","start":"2026-07-17T10:53:49.131346Z","end":"2026-07-17T10:53:49.532306Z","steps":["trace[2023606543] 'process raft request' (duration: 400.830847ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:53:49.532584Z","caller":"traceutil/trace.go:171","msg":"trace[567746297] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"388.977375ms","start":"2026-07-17T10:53:49.143575Z","end":"2026-07-17T10:53:49.532552Z","steps":["trace[567746297] 'process raft request' (duration: 388.79896ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:53:49.532645Z","caller":"traceutil/trace.go:171","msg":"trace[2019626980] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"389.079677ms","start":"2026-07-17T10:53:49.143527Z","end":"2026-07-17T10:53:49.532607Z","steps":["trace[2019626980] 'process raft request' (duration: 388.79928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:49.532711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:49.143571Z","time spent":"389.082827ms","remote":"127.0.0.1:43756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T10:53:49.532699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:49.143409Z","time spent":"389.136808ms","remote":"127.0.0.1:43756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T10:53:49.532721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:49.131338Z","time spent":"401.336061ms","remote":"127.0.0.1:43756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:53:49.53226Z","caller":"traceutil/trace.go:171","msg":"trace[138681262] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"411.324006ms","start":"2026-07-17T10:53:49.120879Z","end":"2026-07-17T10:53:49.532203Z","steps":["trace[138681262] 'process raft request' (duration: 411.11338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:49.532902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:49.120861Z","time spent":"412.015903ms","remote":"127.0.0.1:43756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:53:49.532931Z","caller":"traceutil/trace.go:171","msg":"trace[1761365979] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"395.931719ms","start":"2026-07-17T10:53:49.136416Z","end":"2026-07-17T10:53:49.532348Z","steps":["trace[1761365979] 'process raft request' (duration: 395.801635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:49.533049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:49.136396Z","time spent":"396.608527ms","remote":"127.0.0.1:43756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T10:53:49.533389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:49.143523Z","time spent":"389.232011ms","remote":"127.0.0.1:43756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:53:49.533422Z","caller":"traceutil/trace.go:171","msg":"trace[2094540155] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"383.598262ms","start":"2026-07-17T10:53:49.149803Z","end":"2026-07-17T10:53:49.533401Z","steps":["trace[2094540155] 'process raft request' (duration: 382.680298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:49.533507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:49.149784Z","time spent":"383.685034ms","remote":"127.0.0.1:43756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:53:49.533805Z","caller":"traceutil/trace.go:171","msg":"trace[1494512095] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"383.413087ms","start":"2026-07-17T10:53:49.150368Z","end":"2026-07-17T10:53:49.533781Z","steps":["trace[1494512095] 'process raft request' (duration: 382.958545ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:53:49.533961Z","caller":"traceutil/trace.go:171","msg":"trace[97635040] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"355.917869ms","start":"2026-07-17T10:53:49.178025Z","end":"2026-07-17T10:53:49.533943Z","steps":["trace[97635040] 'process raft request' (duration: 355.697654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:49.534046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:49.177984Z","time spent":"356.026712ms","remote":"127.0.0.1:43756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T10:53:49.533963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:49.150356Z","time spent":"383.561861ms","remote":"127.0.0.1:43756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:53:49.538867Z","caller":"traceutil/trace.go:171","msg":"trace[294910142] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"347.857217ms","start":"2026-07-17T10:53:49.19092Z","end":"2026-07-17T10:53:49.538777Z","steps":["trace[294910142] 'process raft request' (duration: 342.974738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:49.540213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:49.190904Z","time spent":"348.175575ms","remote":"127.0.0.1:43756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:53:49.540104Z","caller":"traceutil/trace.go:171","msg":"trace[1855920547] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:555; }","duration":"180.683287ms","start":"2026-07-17T10:53:49.359406Z","end":"2026-07-17T10:53:49.54009Z","steps":["trace[1855920547] 'read index received' (duration: 81.383741ms)","trace[1855920547] 'applied index is now lower than readState.Index' (duration: 99.298656ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:53:49.540455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.039656ms","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-17T10:53:49.540496Z","caller":"traceutil/trace.go:171","msg":"trace[779800241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"181.119038ms","start":"2026-07-17T10:53:49.359368Z","end":"2026-07-17T10:53:49.540487Z","steps":["trace[779800241] 'agreement among raft nodes before linearized reading' (duration: 180.987305ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:53:49.540703Z","caller":"traceutil/trace.go:171","msg":"trace[1832211683] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"219.276148ms","start":"2026-07-17T10:53:49.321418Z","end":"2026-07-17T10:53:49.540695Z","steps":["trace[1832211683] 'process raft request' (duration: 218.57534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:51.675165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.144843ms","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-17T10:53:51.675284Z","caller":"traceutil/trace.go:171","msg":"trace[713877197] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"104.348259ms","start":"2026-07-17T10:53:51.570909Z","end":"2026-07-17T10:53:51.675258Z","steps":["trace[713877197] 'range keys from in-memory index tree' (duration: 103.897667ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:53:52.796524Z","caller":"traceutil/trace.go:171","msg":"trace[942288542] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"575.583417ms","start":"2026-07-17T10:53:52.22092Z","end":"2026-07-17T10:53:52.796504Z","steps":["trace[942288542] 'process raft request' (duration: 575.446284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:52.796654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:52.220892Z","time spent":"575.68788ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:53:52.81693Z","caller":"traceutil/trace.go:171","msg":"trace[366088832] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"440.501386ms","start":"2026-07-17T10:53:52.376353Z","end":"2026-07-17T10:53:52.816854Z","steps":["trace[366088832] 'process raft request' (duration: 440.326652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:52.817229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:52.376326Z","time spent":"440.813024ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:53:53.584767Z","caller":"traceutil/trace.go:171","msg":"trace[551823049] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"115.308089ms","start":"2026-07-17T10:53:53.469435Z","end":"2026-07-17T10:53:53.584743Z","steps":["trace[551823049] 'read index received' (duration: 114.835737ms)","trace[551823049] 'applied index is now lower than readState.Index' (duration: 471.312µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:53:53.58483Z","caller":"traceutil/trace.go:171","msg":"trace[789797681] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"124.644765ms","start":"2026-07-17T10:53:53.460166Z","end":"2026-07-17T10:53:53.58481Z","steps":["trace[789797681] 'process raft request' (duration: 124.236255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:53.584919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.464653ms","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-17T10:53:53.585004Z","caller":"traceutil/trace.go:171","msg":"trace[294047045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"115.586526ms","start":"2026-07-17T10:53:53.469398Z","end":"2026-07-17T10:53:53.584985Z","steps":["trace[294047045] 'agreement among raft nodes before linearized reading' (duration: 115.439803ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:53:54.485669Z","caller":"traceutil/trace.go:171","msg":"trace[137392792] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"405.965583ms","start":"2026-07-17T10:53:54.079674Z","end":"2026-07-17T10:53:54.485639Z","steps":["trace[137392792] 'process raft request' (duration: 404.955106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:54.485851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:54.079645Z","time spent":"406.117487ms","remote":"127.0.0.1:43818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:53:57.874419Z","caller":"traceutil/trace.go:171","msg":"trace[391170775] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"834.591486ms","start":"2026-07-17T10:53:57.03979Z","end":"2026-07-17T10:53:57.874381Z","steps":["trace[391170775] 'process raft request' (duration: 834.315929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:57.874818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:57.039771Z","time spent":"834.902234ms","remote":"127.0.0.1:43908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:53:58.720183Z","caller":"traceutil/trace.go:171","msg":"trace[1987329537] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:669; }","duration":"1.058193037s","start":"2026-07-17T10:53:57.661972Z","end":"2026-07-17T10:53:58.720165Z","steps":["trace[1987329537] 'read index received' (duration: 211.997185ms)","trace[1987329537] 'applied index is now lower than readState.Index' (duration: 846.193811ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:53:58.720276Z","caller":"traceutil/trace.go:171","msg":"trace[1710578921] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.585223082s","start":"2026-07-17T10:53:57.135023Z","end":"2026-07-17T10:53:58.720246Z","steps":["trace[1710578921] 'process raft request' (duration: 1.584779069s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:58.720407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058435663s","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-17T10:53:58.720412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:57.134993Z","time spent":"1.585344925s","remote":"127.0.0.1:43818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:53:58.720428Z","caller":"traceutil/trace.go:171","msg":"trace[847859268] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"1.058498785s","start":"2026-07-17T10:53:57.661922Z","end":"2026-07-17T10:53:58.72042Z","steps":["trace[847859268] 'agreement among raft nodes before linearized reading' (duration: 1.058329261s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:58.720457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:57.661894Z","time spent":"1.058559347s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T10:53:58.721915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.530865ms","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-17T10:53:58.721973Z","caller":"traceutil/trace.go:171","msg":"trace[1814422373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"943.629208ms","start":"2026-07-17T10:53:57.778315Z","end":"2026-07-17T10:53:58.721944Z","steps":["trace[1814422373] 'agreement among raft nodes before linearized reading' (duration: 942.067247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:58.722005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:57.778283Z","time spent":"943.71255ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T10:53:59.288008Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609582947,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:53:59.730791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.984532ms","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-17T10:53:59.730986Z","caller":"traceutil/trace.go:171","msg":"trace[904420550] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:649; }","duration":"662.31423ms","start":"2026-07-17T10:53:59.068646Z","end":"2026-07-17T10:53:59.73096Z","steps":["trace[904420550] 'range keys from in-memory index tree' (duration: 661.948341ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:53:59.731292Z","caller":"traceutil/trace.go:171","msg":"trace[1591912465] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"1.001216691s","start":"2026-07-17T10:53:58.730024Z","end":"2026-07-17T10:53:59.73124Z","steps":["trace[1591912465] 'process raft request' (duration: 982.036604ms)","trace[1591912465] 'compare' (duration: 18.94551ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:53:59.731467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:58.729993Z","time spent":"1.001378885s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:53:59.757632Z","caller":"traceutil/trace.go:171","msg":"trace[542886343] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:671; }","duration":"970.237412ms","start":"2026-07-17T10:53:58.787362Z","end":"2026-07-17T10:53:59.757599Z","steps":["trace[542886343] 'read index received' (duration: 924.717048ms)","trace[542886343] 'applied index is now lower than readState.Index' (duration: 45.519304ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:53:59.757832Z","caller":"traceutil/trace.go:171","msg":"trace[49133189] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"976.304192ms","start":"2026-07-17T10:53:58.781511Z","end":"2026-07-17T10:53:59.757815Z","steps":["trace[49133189] 'process raft request' (duration: 976.014364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:59.757964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:58.78148Z","time spent":"976.405885ms","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:53:59.757928Z","caller":"traceutil/trace.go:171","msg":"trace[1213504964] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.024883066s","start":"2026-07-17T10:53:58.732975Z","end":"2026-07-17T10:53:59.757858Z","steps":["trace[1213504964] 'process raft request' (duration: 1.024428394s)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:53:59.757951Z","caller":"traceutil/trace.go:171","msg":"trace[1701039334] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"777.230189ms","start":"2026-07-17T10:53:58.980669Z","end":"2026-07-17T10:53:59.757899Z","steps":["trace[1701039334] 'process raft request' (duration: 776.89266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:59.75816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:58.980644Z","time spent":"777.409284ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:53:59.758171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:58.73294Z","time spent":"1.025104502s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:53:59.758334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.827691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T10:53:59.758458Z","caller":"traceutil/trace.go:171","msg":"trace[1685862673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:653; }","duration":"936.021256ms","start":"2026-07-17T10:53:58.822411Z","end":"2026-07-17T10:53:59.758432Z","steps":["trace[1685862673] 'agreement among raft nodes before linearized reading' (duration: 935.727729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:59.758375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.178455ms","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-17T10:53:59.75848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.943639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-17T10:53:59.758551Z","caller":"traceutil/trace.go:171","msg":"trace[501220611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:653; }","duration":"918.412911ms","start":"2026-07-17T10:53:58.840121Z","end":"2026-07-17T10:53:59.758534Z","steps":["trace[501220611] 'agreement among raft nodes before linearized reading' (duration: 918.121383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:59.758595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:58.840091Z","time spent":"918.494533ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T10:53:59.758604Z","caller":"traceutil/trace.go:171","msg":"trace[58014327] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:653; }","duration":"123.169196ms","start":"2026-07-17T10:53:59.635404Z","end":"2026-07-17T10:53:59.758573Z","steps":["trace[58014327] 'agreement among raft nodes before linearized reading' (duration: 122.732384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:59.758527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:58.822383Z","time spent":"936.13012ms","remote":"127.0.0.1:44004","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-17T10:53:59.758879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.496755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:53:59.75903Z","caller":"traceutil/trace.go:171","msg":"trace[1115455792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"971.650649ms","start":"2026-07-17T10:53:58.787313Z","end":"2026-07-17T10:53:59.758964Z","steps":["trace[1115455792] 'agreement among raft nodes before linearized reading' (duration: 970.55309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:53:59.759067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:53:58.787289Z","time spent":"971.771142ms","remote":"127.0.0.1:43724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T10:54:01.290696Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609582964,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:54:01.79108Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609582964,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T10:54:02.017368Z","caller":"traceutil/trace.go:171","msg":"trace[2035850130] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"1.227231806s","start":"2026-07-17T10:54:00.790105Z","end":"2026-07-17T10:54:02.017337Z","steps":["trace[2035850130] 'read index received' (duration: 1.221262247s)","trace[2035850130] 'applied index is now lower than readState.Index' (duration: 5.968169ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:54:02.017737Z","caller":"traceutil/trace.go:171","msg":"trace[219796191] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.78489629s","start":"2026-07-17T10:54:00.232805Z","end":"2026-07-17T10:54:02.017702Z","steps":["trace[219796191] 'process raft request' (duration: 1.779025194s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:54:02.017825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227670688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T10:54:02.017899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.461788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-17T10:54:02.017925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:54:00.232762Z","time spent":"1.785058444s","remote":"127.0.0.1:43818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:54:02.017929Z","caller":"traceutil/trace.go:171","msg":"trace[981060881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"1.227836492s","start":"2026-07-17T10:54:00.79006Z","end":"2026-07-17T10:54:02.017897Z","steps":["trace[981060881] 'agreement among raft nodes before linearized reading' (duration: 1.227613126s)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:54:02.017955Z","caller":"traceutil/trace.go:171","msg":"trace[1806459887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:657; }","duration":"226.579351ms","start":"2026-07-17T10:54:01.791361Z","end":"2026-07-17T10:54:02.01794Z","steps":["trace[1806459887] 'agreement among raft nodes before linearized reading' (duration: 226.361195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:54:02.017988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:54:00.790033Z","time spent":"1.227944885s","remote":"127.0.0.1:43724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T10:54:02.017965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.453341ms","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-17T10:54:02.018047Z","caller":"traceutil/trace.go:171","msg":"trace[474200933] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"281.589225ms","start":"2026-07-17T10:54:01.736435Z","end":"2026-07-17T10:54:02.018025Z","steps":["trace[474200933] 'agreement among raft nodes before linearized reading' (duration: 281.265887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:54:02.018141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.640402ms","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-17T10:54:02.018184Z","caller":"traceutil/trace.go:171","msg":"trace[954287659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"226.729094ms","start":"2026-07-17T10:54:01.791441Z","end":"2026-07-17T10:54:02.01817Z","steps":["trace[954287659] 'agreement among raft nodes before linearized reading' (duration: 226.618042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:54:02.018286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.892422ms","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-17T10:54:02.018371Z","caller":"traceutil/trace.go:171","msg":"trace[339746899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"253.999374ms","start":"2026-07-17T10:54:01.76433Z","end":"2026-07-17T10:54:02.01833Z","steps":["trace[339746899] 'agreement among raft nodes before linearized reading' (duration: 253.777579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:54:18.620956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609583358,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T10:54:18.659182Z","caller":"traceutil/trace.go:171","msg":"trace[65931492] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"577.808657ms","start":"2026-07-17T10:54:18.081336Z","end":"2026-07-17T10:54:18.659145Z","steps":["trace[65931492] 'process raft request' (duration: 577.402736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:54:18.659376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:54:18.081315Z","time spent":"577.96753ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:54:18.666913Z","caller":"traceutil/trace.go:171","msg":"trace[675220144] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"472.81417ms","start":"2026-07-17T10:54:18.194086Z","end":"2026-07-17T10:54:18.6669Z","steps":["trace[675220144] 'process raft request' (duration: 472.604314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:54:18.667004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:54:18.194055Z","time spent":"472.903173ms","remote":"127.0.0.1:43978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:54:18.667185Z","caller":"traceutil/trace.go:171","msg":"trace[846449970] linearizableReadLoop","detail":"{readStateIndex:850; appliedIndex:848; }","duration":"546.905908ms","start":"2026-07-17T10:54:18.119909Z","end":"2026-07-17T10:54:18.666815Z","steps":["trace[846449970] 'read index received' (duration: 538.789074ms)","trace[846449970] 'applied index is now lower than readState.Index' (duration: 8.115614ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:54:18.667215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.269247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-17T10:54:18.667306Z","caller":"traceutil/trace.go:171","msg":"trace[1849812811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:822; }","duration":"547.421241ms","start":"2026-07-17T10:54:18.119865Z","end":"2026-07-17T10:54:18.667286Z","steps":["trace[1849812811] 'agreement among raft nodes before linearized reading' (duration: 547.142904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:54:18.667368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:54:18.119835Z","time spent":"547.518236ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T10:54:18.667776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.378968ms","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-17T10:54:18.667805Z","caller":"traceutil/trace.go:171","msg":"trace[1664506882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:822; }","duration":"545.420279ms","start":"2026-07-17T10:54:18.122378Z","end":"2026-07-17T10:54:18.667798Z","steps":["trace[1664506882] 'agreement among raft nodes before linearized reading' (duration: 545.334287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:54:18.667823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:54:18.122372Z","time spent":"545.44697ms","remote":"127.0.0.1:44004","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-17T10:54:18.668015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.376424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-17T10:54:18.668078Z","caller":"traceutil/trace.go:171","msg":"trace[1315352920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:822; }","duration":"546.458128ms","start":"2026-07-17T10:54:18.1216Z","end":"2026-07-17T10:54:18.668059Z","steps":["trace[1315352920] 'agreement among raft nodes before linearized reading' (duration: 546.152609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:54:18.668369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:54:18.121593Z","time spent":"546.674653ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T10:54:18.668875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.834004ms","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-17T10:54:18.669162Z","caller":"traceutil/trace.go:171","msg":"trace[1484950187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:822; }","duration":"542.005249ms","start":"2026-07-17T10:54:18.12696Z","end":"2026-07-17T10:54:18.668965Z","steps":["trace[1484950187] 'agreement among raft nodes before linearized reading' (duration: 540.770226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:54:18.669408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:54:18.126953Z","time spent":"542.390329ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T10:54:22.399855Z","caller":"traceutil/trace.go:171","msg":"trace[530609891] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"111.862398ms","start":"2026-07-17T10:54:22.287958Z","end":"2026-07-17T10:54:22.39982Z","steps":["trace[530609891] 'process raft request' (duration: 60.200192ms)","trace[530609891] 'compare' (duration: 51.329757ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:54:22.399846Z","caller":"traceutil/trace.go:171","msg":"trace[1943782049] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"111.305903ms","start":"2026-07-17T10:54:22.288506Z","end":"2026-07-17T10:54:22.399812Z","steps":["trace[1943782049] 'process raft request' (duration: 111.230021ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:54:55.004646Z","caller":"traceutil/trace.go:171","msg":"trace[575891137] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"129.032889ms","start":"2026-07-17T10:54:54.875597Z","end":"2026-07-17T10:54:55.00463Z","steps":["trace[575891137] 'process raft request' (duration: 128.756912ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:54:55.004585Z","caller":"traceutil/trace.go:171","msg":"trace[1123276611] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"127.394066ms","start":"2026-07-17T10:54:54.877161Z","end":"2026-07-17T10:54:55.004556Z","steps":["trace[1123276611] 'read index received' (duration: 127.151929ms)","trace[1123276611] 'applied index is now lower than readState.Index' (duration: 240.607µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:54:55.004899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.706054ms","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-17T10:54:55.004959Z","caller":"traceutil/trace.go:171","msg":"trace[1092482486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1045; }","duration":"127.856047ms","start":"2026-07-17T10:54:54.877094Z","end":"2026-07-17T10:54:55.00495Z","steps":["trace[1092482486] 'agreement among raft nodes before linearized reading' (duration: 127.637091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:54:55.505461Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609583832,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:54:56.006556Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609583832,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:54:56.021414Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.017092697s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T10:54:56.02252Z","caller":"traceutil/trace.go:171","msg":"trace[1253619345] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"1.145110099s","start":"2026-07-17T10:54:54.877378Z","end":"2026-07-17T10:54:56.022488Z","steps":["trace[1253619345] 'process raft request' (duration: 1.144441162s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:54:56.022665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:54:54.877359Z","time spent":"1.145228453s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:54:56.400016Z","caller":"traceutil/trace.go:171","msg":"trace[1411560869] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"1.389853554s","start":"2026-07-17T10:54:55.010129Z","end":"2026-07-17T10:54:56.399983Z","steps":["trace[1411560869] 'process raft request' (duration: 1.353040181s)","trace[1411560869] 'compare' (duration: 36.008992ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:54:56.400207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:54:55.010095Z","time spent":"1.390004958s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:54:56.405428Z","caller":"traceutil/trace.go:171","msg":"trace[1520531295] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1081; }","duration":"1.400467315s","start":"2026-07-17T10:54:55.004942Z","end":"2026-07-17T10:54:56.40541Z","steps":["trace[1520531295] 'read index received' (duration: 1.01681535s)","trace[1520531295] 'applied index is now lower than readState.Index' (duration: 383.650205ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:54:56.405605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.52712997s","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-17T10:54:56.405559Z","caller":"traceutil/trace.go:171","msg":"trace[1774052526] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"160.192441ms","start":"2026-07-17T10:54:56.245328Z","end":"2026-07-17T10:54:56.40552Z","steps":["trace[1774052526] 'process raft request' (duration: 159.942845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:54:56.405717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.519600051s","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-17T10:54:56.405743Z","caller":"traceutil/trace.go:171","msg":"trace[1729376852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1048; }","duration":"1.519641512s","start":"2026-07-17T10:54:54.886096Z","end":"2026-07-17T10:54:56.405738Z","steps":["trace[1729376852] 'agreement among raft nodes before linearized reading' (duration: 1.51956529s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:54:56.405771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:54:54.88609Z","time spent":"1.519673622s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T10:54:56.405646Z","caller":"traceutil/trace.go:171","msg":"trace[1805999270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1048; }","duration":"1.527193652s","start":"2026-07-17T10:54:54.878442Z","end":"2026-07-17T10:54:56.405635Z","steps":["trace[1805999270] 'agreement among raft nodes before linearized reading' (duration: 1.527031968s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:54:56.405963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:54:54.878435Z","time spent":"1.527521021s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T10:55:20.67317Z","caller":"traceutil/trace.go:171","msg":"trace[200810310] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1168; }","duration":"105.61104ms","start":"2026-07-17T10:55:20.567524Z","end":"2026-07-17T10:55:20.673135Z","steps":["trace[200810310] 'read index received' (duration: 89.194596ms)","trace[200810310] 'applied index is now lower than readState.Index' (duration: 16.415394ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:55:20.673234Z","caller":"traceutil/trace.go:171","msg":"trace[1798208858] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"122.174157ms","start":"2026-07-17T10:55:20.551018Z","end":"2026-07-17T10:55:20.673193Z","steps":["trace[1798208858] 'process raft request' (duration: 105.694022ms)","trace[1798208858] 'compare' (duration: 15.983093ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:55:20.673417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.872978ms","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-17T10:55:20.673477Z","caller":"traceutil/trace.go:171","msg":"trace[257135006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1127; }","duration":"105.995751ms","start":"2026-07-17T10:55:20.567472Z","end":"2026-07-17T10:55:20.673468Z","steps":["trace[257135006] 'agreement among raft nodes before linearized reading' (duration: 105.809405ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:55:21.414114Z","caller":"traceutil/trace.go:171","msg":"trace[1181035549] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"258.656803ms","start":"2026-07-17T10:55:21.155428Z","end":"2026-07-17T10:55:21.414085Z","steps":["trace[1181035549] 'process raft request' (duration: 258.473817ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:55:22.383694Z","caller":"traceutil/trace.go:171","msg":"trace[699969784] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"166.063307ms","start":"2026-07-17T10:55:22.217599Z","end":"2026-07-17T10:55:22.383662Z","steps":["trace[699969784] 'process raft request' (duration: 165.857731ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:55:23.801012Z","caller":"traceutil/trace.go:171","msg":"trace[795634949] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"377.457161ms","start":"2026-07-17T10:55:23.423526Z","end":"2026-07-17T10:55:23.800983Z","steps":["trace[795634949] 'process raft request' (duration: 377.324118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:55:23.801443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:55:23.423508Z","time spent":"377.629485ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:55:24.366529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.091215ms","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":"info","ts":"2026-07-17T10:55:24.366626Z","caller":"traceutil/trace.go:171","msg":"trace[441129468] 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:1137; }","duration":"164.255459ms","start":"2026-07-17T10:55:24.202349Z","end":"2026-07-17T10:55:24.366605Z","steps":["trace[441129468] 'count revisions from in-memory index tree' (duration: 163.967751ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:55:24.812691Z","caller":"traceutil/trace.go:171","msg":"trace[362978249] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1179; }","duration":"423.856306ms","start":"2026-07-17T10:55:24.388812Z","end":"2026-07-17T10:55:24.812668Z","steps":["trace[362978249] 'read index received' (duration: 423.676811ms)","trace[362978249] 'applied index is now lower than readState.Index' (duration: 178.535µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:55:24.812756Z","caller":"traceutil/trace.go:171","msg":"trace[19667002] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"445.517398ms","start":"2026-07-17T10:55:24.367199Z","end":"2026-07-17T10:55:24.812716Z","steps":["trace[19667002] 'process raft request' (duration: 445.312143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:55:24.812899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.065161ms","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-17T10:55:24.812927Z","caller":"traceutil/trace.go:171","msg":"trace[596561101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1138; }","duration":"424.151373ms","start":"2026-07-17T10:55:24.388768Z","end":"2026-07-17T10:55:24.812919Z","steps":["trace[596561101] 'agreement among raft nodes before linearized reading' (duration: 424.01857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:55:24.812954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:55:24.388747Z","time spent":"424.201315ms","remote":"127.0.0.1:44004","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-17T10:55:24.813192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:55:24.367148Z","time spent":"445.681133ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:55:24.823031Z","caller":"traceutil/trace.go:171","msg":"trace[2126287167] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"198.172795ms","start":"2026-07-17T10:55:24.624838Z","end":"2026-07-17T10:55:24.823011Z","steps":["trace[2126287167] 'process raft request' (duration: 198.039842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:55:24.823441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.133781ms","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-17T10:55:24.823476Z","caller":"traceutil/trace.go:171","msg":"trace[43146308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1139; }","duration":"137.222914ms","start":"2026-07-17T10:55:24.686245Z","end":"2026-07-17T10:55:24.823468Z","steps":["trace[43146308] 'agreement among raft nodes before linearized reading' (duration: 137.098471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:55:24.823637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.337491ms","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-17T10:55:24.823753Z","caller":"traceutil/trace.go:171","msg":"trace[581249012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1139; }","duration":"100.563437ms","start":"2026-07-17T10:55:24.723163Z","end":"2026-07-17T10:55:24.823727Z","steps":["trace[581249012] 'agreement among raft nodes before linearized reading' (duration: 100.232638ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:55:24.985234Z","caller":"traceutil/trace.go:171","msg":"trace[168149045] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"166.847597ms","start":"2026-07-17T10:55:24.81835Z","end":"2026-07-17T10:55:24.985198Z","steps":["trace[168149045] 'process raft request' (duration: 141.978211ms)","trace[168149045] 'compare' (duration: 24.678251ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:55:24.985241Z","caller":"traceutil/trace.go:171","msg":"trace[282407918] linearizableReadLoop","detail":"{readStateIndex:1182; appliedIndex:1181; }","duration":"160.625174ms","start":"2026-07-17T10:55:24.824589Z","end":"2026-07-17T10:55:24.985214Z","steps":["trace[282407918] 'read index received' (duration: 135.729135ms)","trace[282407918] 'applied index is now lower than readState.Index' (duration: 24.894969ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:55:24.9856Z","caller":"traceutil/trace.go:171","msg":"trace[228427734] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"157.425849ms","start":"2026-07-17T10:55:24.828161Z","end":"2026-07-17T10:55:24.985587Z","steps":["trace[228427734] 'process raft request' (duration: 157.394018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:55:24.985589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.962093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:55:24.985684Z","caller":"traceutil/trace.go:171","msg":"trace[1791181283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1141; }","duration":"161.091206ms","start":"2026-07-17T10:55:24.82457Z","end":"2026-07-17T10:55:24.985662Z","steps":["trace[1791181283] 'agreement among raft nodes before linearized reading' (duration: 160.764058ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:55:24.98581Z","caller":"traceutil/trace.go:171","msg":"trace[852196048] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"158.055815ms","start":"2026-07-17T10:55:24.827729Z","end":"2026-07-17T10:55:24.985785Z","steps":["trace[852196048] 'process raft request' (duration: 157.456319ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:55:24.985806Z","caller":"traceutil/trace.go:171","msg":"trace[1797011294] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"157.917601ms","start":"2026-07-17T10:55:24.827807Z","end":"2026-07-17T10:55:24.985724Z","steps":["trace[1797011294] 'process raft request' (duration: 157.564833ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:55:39.288321Z","caller":"traceutil/trace.go:171","msg":"trace[2096276092] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"100.66784ms","start":"2026-07-17T10:55:39.187617Z","end":"2026-07-17T10:55:39.288285Z","steps":["trace[2096276092] 'process raft request' (duration: 89.72637ms)","trace[2096276092] 'compare' (duration: 10.701193ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:55:39.288599Z","caller":"traceutil/trace.go:171","msg":"trace[753714347] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"100.556867ms","start":"2026-07-17T10:55:39.18802Z","end":"2026-07-17T10:55:39.288577Z","steps":["trace[753714347] 'process raft request' (duration: 100.218908ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:55:39.288927Z","caller":"traceutil/trace.go:171","msg":"trace[1208813016] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"100.790043ms","start":"2026-07-17T10:55:39.1881Z","end":"2026-07-17T10:55:39.28889Z","steps":["trace[1208813016] 'process raft request' (duration: 100.443083ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:55:39.523629Z","caller":"traceutil/trace.go:171","msg":"trace[834333047] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"149.776947ms","start":"2026-07-17T10:55:39.373819Z","end":"2026-07-17T10:55:39.523596Z","steps":["trace[834333047] 'process raft request' (duration: 149.53712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:55:41.546627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.387343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-17T10:55:41.546792Z","caller":"traceutil/trace.go:171","msg":"trace[869978027] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1242; }","duration":"253.62802ms","start":"2026-07-17T10:55:41.293135Z","end":"2026-07-17T10:55:41.546763Z","steps":["trace[869978027] 'range keys from in-memory index tree' (duration: 253.135266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:55:41.546839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.337652ms","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-17T10:55:41.546915Z","caller":"traceutil/trace.go:171","msg":"trace[2134461419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1242; }","duration":"253.454435ms","start":"2026-07-17T10:55:41.293443Z","end":"2026-07-17T10:55:41.546898Z","steps":["trace[2134461419] 'range keys from in-memory index tree' (duration: 253.129716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:55:41.546897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.079165ms","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-17T10:55:41.546987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.516143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-17T10:55:41.547041Z","caller":"traceutil/trace.go:171","msg":"trace[461547737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1242; }","duration":"254.612496ms","start":"2026-07-17T10:55:41.292416Z","end":"2026-07-17T10:55:41.547029Z","steps":["trace[461547737] 'range keys from in-memory index tree' (duration: 254.373679ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:55:41.546997Z","caller":"traceutil/trace.go:171","msg":"trace[1249601647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1242; }","duration":"359.228509ms","start":"2026-07-17T10:55:41.187746Z","end":"2026-07-17T10:55:41.546974Z","steps":["trace[1249601647] 'range keys from in-memory index tree' (duration: 358.835079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:55:41.547151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:55:41.187722Z","time spent":"359.402113ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T10:55:43.222687Z","caller":"traceutil/trace.go:171","msg":"trace[728710151] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"273.682428ms","start":"2026-07-17T10:55:42.948979Z","end":"2026-07-17T10:55:43.222661Z","steps":["trace[728710151] 'process raft request' (duration: 273.493283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:55:44.113027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.39499ms","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-17T10:55:44.11303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.313031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:55:44.11311Z","caller":"traceutil/trace.go:171","msg":"trace[101455695] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1249; }","duration":"137.505412ms","start":"2026-07-17T10:55:43.975588Z","end":"2026-07-17T10:55:44.113094Z","steps":["trace[101455695] 'range keys from in-memory index tree' (duration: 137.195484ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:55:44.113132Z","caller":"traceutil/trace.go:171","msg":"trace[531236182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"323.461885ms","start":"2026-07-17T10:55:43.789644Z","end":"2026-07-17T10:55:44.113106Z","steps":["trace[531236182] 'range keys from in-memory index tree' (duration: 323.223147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:55:44.113194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:55:43.78962Z","time spent":"323.560457ms","remote":"127.0.0.1:43724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T10:55:44.113178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.282262ms","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-17T10:55:44.11327Z","caller":"traceutil/trace.go:171","msg":"trace[393465704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1249; }","duration":"551.398346ms","start":"2026-07-17T10:55:43.561849Z","end":"2026-07-17T10:55:44.113247Z","steps":["trace[393465704] 'range keys from in-memory index tree' (duration: 551.097968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:55:44.113319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:55:43.561845Z","time spent":"551.463838ms","remote":"127.0.0.1:44004","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-17T10:55:44.113645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.552839ms","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-17T10:55:44.11368Z","caller":"traceutil/trace.go:171","msg":"trace[890002355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1249; }","duration":"554.622181ms","start":"2026-07-17T10:55:43.559044Z","end":"2026-07-17T10:55:44.113666Z","steps":["trace[890002355] 'range keys from in-memory index tree' (duration: 554.434816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:55:44.113715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:55:43.559015Z","time spent":"554.687323ms","remote":"127.0.0.1:44004","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-17T10:55:44.113851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.738034ms","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-17T10:55:44.113891Z","caller":"traceutil/trace.go:171","msg":"trace[433644445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1249; }","duration":"551.793246ms","start":"2026-07-17T10:55:43.562086Z","end":"2026-07-17T10:55:44.113879Z","steps":["trace[433644445] 'range keys from in-memory index tree' (duration: 551.506238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:55:44.113893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.114654ms","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-17T10:55:44.113921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:55:43.562079Z","time spent":"551.834798ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T10:55:44.113926Z","caller":"traceutil/trace.go:171","msg":"trace[549895312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1249; }","duration":"552.194197ms","start":"2026-07-17T10:55:43.561721Z","end":"2026-07-17T10:55:44.113916Z","steps":["trace[549895312] 'range keys from in-memory index tree' (duration: 551.921399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:55:44.113952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:55:43.561696Z","time spent":"552.249079ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T10:55:57.917834Z","caller":"traceutil/trace.go:171","msg":"trace[47949051] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"101.301446ms","start":"2026-07-17T10:55:57.816292Z","end":"2026-07-17T10:55:57.917594Z","steps":["trace[47949051] 'process raft request' (duration: 88.314013ms)","trace[47949051] 'compare' (duration: 12.616843ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:55:58.095565Z","caller":"traceutil/trace.go:171","msg":"trace[769630477] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"173.466982ms","start":"2026-07-17T10:55:57.922045Z","end":"2026-07-17T10:55:58.095512Z","steps":["trace[769630477] 'process raft request' (duration: 159.01819ms)","trace[769630477] 'compare' (duration: 14.02564ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:56:03.492022Z","caller":"traceutil/trace.go:171","msg":"trace[410825253] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"195.944666ms","start":"2026-07-17T10:56:03.29604Z","end":"2026-07-17T10:56:03.491984Z","steps":["trace[410825253] 'process raft request' (duration: 195.75171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:04.086934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.69652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:56:04.087037Z","caller":"traceutil/trace.go:171","msg":"trace[1044046648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1335; }","duration":"298.861674ms","start":"2026-07-17T10:56:03.788153Z","end":"2026-07-17T10:56:04.087015Z","steps":["trace[1044046648] 'range keys from in-memory index tree' (duration: 298.594657ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:04.467666Z","caller":"traceutil/trace.go:171","msg":"trace[1793035025] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"162.362249ms","start":"2026-07-17T10:56:04.30528Z","end":"2026-07-17T10:56:04.467642Z","steps":["trace[1793035025] 'process raft request' (duration: 162.089222ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:04.467733Z","caller":"traceutil/trace.go:171","msg":"trace[177344141] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"161.569517ms","start":"2026-07-17T10:56:04.306157Z","end":"2026-07-17T10:56:04.467726Z","steps":["trace[177344141] 'process raft request' (duration: 161.446594ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:04.467682Z","caller":"traceutil/trace.go:171","msg":"trace[90293863] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"162.291066ms","start":"2026-07-17T10:56:04.305374Z","end":"2026-07-17T10:56:04.467666Z","steps":["trace[90293863] 'process raft request' (duration: 162.189583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:05.295832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.173168ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-17T10:56:05.295972Z","caller":"traceutil/trace.go:171","msg":"trace[15269078] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1393; }","duration":"160.93419ms","start":"2026-07-17T10:56:05.135006Z","end":"2026-07-17T10:56:05.29594Z","steps":["trace[15269078] 'read index received' (duration: 43.161µs)","trace[15269078] 'applied index is now lower than readState.Index' (duration: 160.889189ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:56:05.296092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.064835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:56:05.29625Z","caller":"traceutil/trace.go:171","msg":"trace[747322008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1341; }","duration":"161.262239ms","start":"2026-07-17T10:56:05.134967Z","end":"2026-07-17T10:56:05.29623Z","steps":["trace[747322008] 'agreement among raft nodes before linearized reading' (duration: 161.035463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:06.824514Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609584540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:56:07.334632Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609584540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:56:07.835814Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609584540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:56:08.005396Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.695932038s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T10:56:08.005919Z","caller":"traceutil/trace.go:171","msg":"trace[1843086155] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"1.696579024s","start":"2026-07-17T10:56:06.309319Z","end":"2026-07-17T10:56:08.005898Z","steps":["trace[1843086155] 'process raft request' (duration: 1.696438351s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:08.006053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:06.309287Z","time spent":"1.696683397s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:56:08.859506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000041359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-17T10:56:08.859624Z","caller":"traceutil/trace.go:171","msg":"trace[522850622] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000283396s","start":"2026-07-17T10:56:06.859314Z","end":"2026-07-17T10:56:08.859597Z","steps":["trace[522850622] 'agreement among raft nodes before linearized reading' (duration: 2.00007349s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:08.859695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:06.859292Z","time spent":"2.000387329s","remote":"127.0.0.1:43724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/17 10:56:08 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-17T10:56:09.036764Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.031108066s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T10:56:09.050245Z","caller":"traceutil/trace.go:171","msg":"trace[1422285437] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1402; }","duration":"2.726696395s","start":"2026-07-17T10:56:06.323515Z","end":"2026-07-17T10:56:09.050212Z","steps":["trace[1422285437] 'read index received' (duration: 1.682304938s)","trace[1422285437] 'applied index is now lower than readState.Index' (duration: 1.044389437s)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:56:09.050679Z","caller":"traceutil/trace.go:171","msg":"trace[43939529] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"1.53324252s","start":"2026-07-17T10:56:07.517415Z","end":"2026-07-17T10:56:09.050658Z","steps":["trace[43939529] 'process raft request' (duration: 1.519573368s)","trace[43939529] 'compare' (duration: 12.407348ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:56:09.050943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:07.517386Z","time spent":"1.533399144s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:56:09.051107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.727561177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T10:56:09.051189Z","caller":"traceutil/trace.go:171","msg":"trace[1077850665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1351; }","duration":"2.727733562s","start":"2026-07-17T10:56:06.323436Z","end":"2026-07-17T10:56:09.051169Z","steps":["trace[1077850665] 'agreement among raft nodes before linearized reading' (duration: 2.727464335s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:09.051233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:06.323404Z","time spent":"2.727819894s","remote":"127.0.0.1:44004","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-17T10:56:09.051933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207637229s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-07-17T10:56:09.051946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.830927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:56:09.051998Z","caller":"traceutil/trace.go:171","msg":"trace[1412611504] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1351; }","duration":"1.207742853s","start":"2026-07-17T10:56:07.844241Z","end":"2026-07-17T10:56:09.051984Z","steps":["trace[1412611504] 'agreement among raft nodes before linearized reading' (duration: 1.207613349s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:09.052063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:07.844216Z","time spent":"1.207838806s","remote":"127.0.0.1:43778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-07-17T10:56:09.052053Z","caller":"traceutil/trace.go:171","msg":"trace[424784668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1351; }","duration":"192.005453ms","start":"2026-07-17T10:56:08.860022Z","end":"2026-07-17T10:56:09.052027Z","steps":["trace[424784668] 'agreement among raft nodes before linearized reading' (duration: 191.824467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:09.052114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.841798202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T10:56:09.052157Z","caller":"traceutil/trace.go:171","msg":"trace[1634183628] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1351; }","duration":"1.841886164s","start":"2026-07-17T10:56:07.210259Z","end":"2026-07-17T10:56:09.052145Z","steps":["trace[1634183628] 'agreement among raft nodes before linearized reading' (duration: 1.84171574s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:09.05219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:07.210229Z","time spent":"1.841951405s","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-07-17T10:56:09.052274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.692892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T10:56:09.052309Z","caller":"traceutil/trace.go:171","msg":"trace[1836265059] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1351; }","duration":"908.781275ms","start":"2026-07-17T10:56:08.143516Z","end":"2026-07-17T10:56:09.052298Z","steps":["trace[1836265059] 'agreement among raft nodes before linearized reading' (duration: 908.720963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:09.052343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:08.143484Z","time spent":"908.848897ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-07-17T10:56:09.052418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.580481563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-17T10:56:09.052582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052369837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T10:56:09.052607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.580720588s","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-17T10:56:09.052627Z","caller":"traceutil/trace.go:171","msg":"trace[138174467] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1351; }","duration":"1.05247067s","start":"2026-07-17T10:56:08.000144Z","end":"2026-07-17T10:56:09.052615Z","steps":["trace[138174467] 'agreement among raft nodes before linearized reading' (duration: 1.052373317s)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:09.052654Z","caller":"traceutil/trace.go:171","msg":"trace[1354594506] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1351; }","duration":"2.58081064s","start":"2026-07-17T10:56:06.471831Z","end":"2026-07-17T10:56:09.052642Z","steps":["trace[1354594506] 'agreement among raft nodes before linearized reading' (duration: 2.580608755s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:09.052711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:06.471797Z","time spent":"2.580900474s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T10:56:09.052782Z","caller":"traceutil/trace.go:171","msg":"trace[834666215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1351; }","duration":"2.580512063s","start":"2026-07-17T10:56:06.471926Z","end":"2026-07-17T10:56:09.052438Z","steps":["trace[834666215] 'agreement among raft nodes before linearized reading' (duration: 2.580420991s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:09.052826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:06.471843Z","time spent":"2.580972776s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T10:56:09.052891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190763603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:93680"} {"level":"info","ts":"2026-07-17T10:56:09.052925Z","caller":"traceutil/trace.go:171","msg":"trace[58714358] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1351; }","duration":"1.190841475s","start":"2026-07-17T10:56:07.862074Z","end":"2026-07-17T10:56:09.052916Z","steps":["trace[58714358] 'agreement among raft nodes before linearized reading' (duration: 1.190606739s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:09.052662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:08.000114Z","time spent":"1.052538692s","remote":"127.0.0.1:53978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-17T10:56:09.052948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:07.862046Z","time spent":"1.190896667s","remote":"127.0.0.1:43764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":93703,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-07-17T10:56:09.051946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.580170734s","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-17T10:56:09.053084Z","caller":"traceutil/trace.go:171","msg":"trace[1273246580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1351; }","duration":"2.581347175s","start":"2026-07-17T10:56:06.471727Z","end":"2026-07-17T10:56:09.053074Z","steps":["trace[1273246580] 'agreement among raft nodes before linearized reading' (duration: 2.580122253s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:09.053114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:06.471706Z","time spent":"2.581399766s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T10:56:09.320386Z","caller":"traceutil/trace.go:171","msg":"trace[336517770] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"262.893995ms","start":"2026-07-17T10:56:09.057426Z","end":"2026-07-17T10:56:09.32032Z","steps":["trace[336517770] 'process raft request' (duration: 205.628042ms)","trace[336517770] 'compare' (duration: 57.031796ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:56:09.321001Z","caller":"traceutil/trace.go:171","msg":"trace[782249261] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"263.142952ms","start":"2026-07-17T10:56:09.057848Z","end":"2026-07-17T10:56:09.320991Z","steps":["trace[782249261] 'process raft request' (duration: 263.06404ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:09.321304Z","caller":"traceutil/trace.go:171","msg":"trace[1345616564] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1404; }","duration":"261.671042ms","start":"2026-07-17T10:56:09.05962Z","end":"2026-07-17T10:56:09.321291Z","steps":["trace[1345616564] 'read index received' (duration: 203.442964ms)","trace[1345616564] 'applied index is now lower than readState.Index' (duration: 58.227238ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:56:09.321365Z","caller":"traceutil/trace.go:171","msg":"trace[477903259] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"258.859427ms","start":"2026-07-17T10:56:09.062494Z","end":"2026-07-17T10:56:09.321353Z","steps":["trace[477903259] 'process raft request' (duration: 258.736874ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:09.321356Z","caller":"traceutil/trace.go:171","msg":"trace[574858912] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"259.007162ms","start":"2026-07-17T10:56:09.062337Z","end":"2026-07-17T10:56:09.321344Z","steps":["trace[574858912] 'process raft request' (duration: 258.795036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:09.321557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.17473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-17T10:56:09.321585Z","caller":"traceutil/trace.go:171","msg":"trace[1184300528] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1356; }","duration":"261.253922ms","start":"2026-07-17T10:56:09.060324Z","end":"2026-07-17T10:56:09.321578Z","steps":["trace[1184300528] 'agreement among raft nodes before linearized reading' (duration: 261.17924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:09.321632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.141265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:93680"} {"level":"info","ts":"2026-07-17T10:56:09.321674Z","caller":"traceutil/trace.go:171","msg":"trace[1738883396] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1356; }","duration":"262.216298ms","start":"2026-07-17T10:56:09.059448Z","end":"2026-07-17T10:56:09.321664Z","steps":["trace[1738883396] 'agreement among raft nodes before linearized reading' (duration: 262.000411ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:09.321304Z","caller":"traceutil/trace.go:171","msg":"trace[1157085457] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"263.211163ms","start":"2026-07-17T10:56:09.05806Z","end":"2026-07-17T10:56:09.321271Z","steps":["trace[1157085457] 'process raft request' (duration: 262.894475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:09.321646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.014066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:56:09.321781Z","caller":"traceutil/trace.go:171","msg":"trace[1227177957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1356; }","duration":"258.19296ms","start":"2026-07-17T10:56:09.063572Z","end":"2026-07-17T10:56:09.321765Z","steps":["trace[1227177957] 'agreement among raft nodes before linearized reading' (duration: 258.049426ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:11.464527Z","caller":"traceutil/trace.go:171","msg":"trace[476795633] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"131.688659ms","start":"2026-07-17T10:56:11.332812Z","end":"2026-07-17T10:56:11.464501Z","steps":["trace[476795633] 'process raft request' (duration: 131.549645ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:11.464606Z","caller":"traceutil/trace.go:171","msg":"trace[1170139437] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"133.541898ms","start":"2026-07-17T10:56:11.331018Z","end":"2026-07-17T10:56:11.464559Z","steps":["trace[1170139437] 'process raft request' (duration: 109.42758ms)","trace[1170139437] 'compare' (duration: 23.708697ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:56:11.464765Z","caller":"traceutil/trace.go:171","msg":"trace[2142639064] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"132.137781ms","start":"2026-07-17T10:56:11.332616Z","end":"2026-07-17T10:56:11.464754Z","steps":["trace[2142639064] 'process raft request' (duration: 131.701259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:19.826758Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609584687,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:56:20.326923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609584687,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:56:20.827991Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609584687,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:56:21.328688Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609584687,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:56:21.789132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000617538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-17T10:56:21.78924Z","caller":"traceutil/trace.go:171","msg":"trace[858722291] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000787322s","start":"2026-07-17T10:56:19.788428Z","end":"2026-07-17T10:56:21.789215Z","steps":["trace[858722291] 'agreement among raft nodes before linearized reading' (duration: 2.000645268s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:21.789304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:19.788402Z","time spent":"2.000883794s","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/17 10:56:21 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-17T10:56:21.829092Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609584687,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:56:22.330288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609584687,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:56:22.835864Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609584687,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:56:23.336705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568452609584687,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:56:23.797795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000401711s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-17T10:56:23.797899Z","caller":"traceutil/trace.go:171","msg":"trace[29809582] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000593966s","start":"2026-07-17T10:56:21.797281Z","end":"2026-07-17T10:56:23.797875Z","steps":["trace[29809582] 'agreement among raft nodes before linearized reading' (duration: 2.000436102s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:23.797951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:21.797259Z","time spent":"2.000681078s","remote":"127.0.0.1:43724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T10:56:23.834497Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.695890804s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T10:56:23.835164Z","caller":"traceutil/trace.go:171","msg":"trace[1032886283] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"4.696718215s","start":"2026-07-17T10:56:19.138403Z","end":"2026-07-17T10:56:23.835121Z","steps":["trace[1032886283] 'process raft request' (duration: 4.696363056s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:23.835381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:19.138367Z","time spent":"4.69690238s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:56:24.043658Z","caller":"traceutil/trace.go:171","msg":"trace[1074948792] linearizableReadLoop","detail":"{readStateIndex:1481; appliedIndex:1479; }","duration":"4.717591365s","start":"2026-07-17T10:56:19.326038Z","end":"2026-07-17T10:56:24.04363Z","steps":["trace[1074948792] 'read index received' (duration: 4.508745959s)","trace[1074948792] 'applied index is now lower than readState.Index' (duration: 208.844316ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:56:24.043791Z","caller":"traceutil/trace.go:171","msg":"trace[1209614638] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"1.245924689s","start":"2026-07-17T10:56:22.797813Z","end":"2026-07-17T10:56:24.043738Z","steps":["trace[1209614638] 'process raft request' (duration: 1.161955081s)","trace[1209614638] 'compare' (duration: 83.589359ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:56:24.043961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.717898924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-07-17T10:56:24.044015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:22.797771Z","time spent":"1.246122345s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:56:24.044026Z","caller":"traceutil/trace.go:171","msg":"trace[169334353] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1426; }","duration":"4.718021827s","start":"2026-07-17T10:56:19.325992Z","end":"2026-07-17T10:56:24.044014Z","steps":["trace[169334353] 'agreement among raft nodes before linearized reading' (duration: 4.7177882s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:24.044085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:19.325968Z","time spent":"4.718105879s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-17T10:56:24.0441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.31362239s","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-17T10:56:24.044195Z","caller":"traceutil/trace.go:171","msg":"trace[1941451656] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1426; }","duration":"3.313762333s","start":"2026-07-17T10:56:20.730405Z","end":"2026-07-17T10:56:24.044167Z","steps":["trace[1941451656] 'agreement among raft nodes before linearized reading' (duration: 3.313520187s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:24.044248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:20.730377Z","time spent":"3.313858705s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-17T10:56:24.044221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.642703797s","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-17T10:56:24.044346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.360022805s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:56:24.044402Z","caller":"traceutil/trace.go:171","msg":"trace[1900162933] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1426; }","duration":"3.360087736s","start":"2026-07-17T10:56:20.684287Z","end":"2026-07-17T10:56:24.044374Z","steps":["trace[1900162933] 'agreement among raft nodes before linearized reading' (duration: 3.360030895s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:24.044413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.171206ms","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":"warn","ts":"2026-07-17T10:56:24.044445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:20.684264Z","time spent":"3.360171069s","remote":"127.0.0.1:54058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-17T10:56:24.04445Z","caller":"traceutil/trace.go:171","msg":"trace[672400509] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1426; }","duration":"189.242728ms","start":"2026-07-17T10:56:23.855197Z","end":"2026-07-17T10:56:24.04444Z","steps":["trace[672400509] 'agreement among raft nodes before linearized reading' (duration: 189.174196ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:24.044482Z","caller":"traceutil/trace.go:171","msg":"trace[1462952556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1426; }","duration":"4.642860661s","start":"2026-07-17T10:56:19.401467Z","end":"2026-07-17T10:56:24.044328Z","steps":["trace[1462952556] 'agreement among raft nodes before linearized reading' (duration: 4.642447631s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:24.044565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:19.401385Z","time spent":"4.643161911s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T10:56:24.044645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.853713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:56:24.044674Z","caller":"traceutil/trace.go:171","msg":"trace[1076351004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1426; }","duration":"245.908865ms","start":"2026-07-17T10:56:23.798758Z","end":"2026-07-17T10:56:24.044666Z","steps":["trace[1076351004] 'agreement among raft nodes before linearized reading' (duration: 245.860083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:24.044697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.972816643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-17T10:56:24.044736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.539819011s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-17T10:56:24.04477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.56085002s","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-17T10:56:24.04484Z","caller":"traceutil/trace.go:171","msg":"trace[41978280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1426; }","duration":"4.539947773s","start":"2026-07-17T10:56:19.504869Z","end":"2026-07-17T10:56:24.044816Z","steps":["trace[41978280] 'agreement among raft nodes before linearized reading' (duration: 4.539676367s)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:24.044763Z","caller":"traceutil/trace.go:171","msg":"trace[1803755757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1426; }","duration":"3.972903315s","start":"2026-07-17T10:56:20.07182Z","end":"2026-07-17T10:56:24.044723Z","steps":["trace[1803755757] 'agreement among raft nodes before linearized reading' (duration: 3.972788702s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:24.044868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.541904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T10:56:24.044871Z","caller":"traceutil/trace.go:171","msg":"trace[631942271] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1426; }","duration":"3.561043886s","start":"2026-07-17T10:56:20.483804Z","end":"2026-07-17T10:56:24.044848Z","steps":["trace[631942271] 'agreement among raft nodes before linearized reading' (duration: 3.560719548s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:24.044905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:20.071788Z","time spent":"3.97310673s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T10:56:24.044944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.400711807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-17T10:56:24.044975Z","caller":"traceutil/trace.go:171","msg":"trace[2108109709] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:1426; }","duration":"4.400787068s","start":"2026-07-17T10:56:19.644182Z","end":"2026-07-17T10:56:24.04497Z","steps":["trace[2108109709] 'agreement among raft nodes before linearized reading' (duration: 4.400718827s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:24.045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:19.644157Z","time spent":"4.400839479s","remote":"127.0.0.1:43740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"warn","ts":"2026-07-17T10:56:24.045057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.54019225s","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-17T10:56:24.045109Z","caller":"traceutil/trace.go:171","msg":"trace[1921260190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1426; }","duration":"4.540278542s","start":"2026-07-17T10:56:19.504815Z","end":"2026-07-17T10:56:24.045093Z","steps":["trace[1921260190] 'agreement among raft nodes before linearized reading' (duration: 4.540154719s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:24.044883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:19.504788Z","time spent":"4.540088117s","remote":"127.0.0.1:44004","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-17T10:56:24.045149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:19.504796Z","time spent":"4.540344454s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T10:56:24.044909Z","caller":"traceutil/trace.go:171","msg":"trace[1500229615] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1426; }","duration":"627.626776ms","start":"2026-07-17T10:56:23.417271Z","end":"2026-07-17T10:56:24.044898Z","steps":["trace[1500229615] 'agreement among raft nodes before linearized reading' (duration: 627.564075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:24.04429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.128677689s","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-17T10:56:24.045241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:23.417244Z","time spent":"627.985767ms","remote":"127.0.0.1:44100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-17T10:56:24.044971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.90542ms","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-17T10:56:24.045266Z","caller":"traceutil/trace.go:171","msg":"trace[1531559989] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1426; }","duration":"4.129698307s","start":"2026-07-17T10:56:19.915551Z","end":"2026-07-17T10:56:24.045249Z","steps":["trace[1531559989] 'agreement among raft nodes before linearized reading' (duration: 4.12868909s)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:24.04529Z","caller":"traceutil/trace.go:171","msg":"trace[942944458] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1426; }","duration":"925.25158ms","start":"2026-07-17T10:56:23.120027Z","end":"2026-07-17T10:56:24.045278Z","steps":["trace[942944458] 'agreement among raft nodes before linearized reading' (duration: 924.90737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:24.045309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:19.915527Z","time spent":"4.129766719s","remote":"127.0.0.1:54110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"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-17T10:56:24.045331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:23.119998Z","time spent":"925.321081ms","remote":"127.0.0.1:44158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-17T10:56:24.044925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:20.483773Z","time spent":"3.561143558s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-17T10:56:24.044931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.540063897s","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-17T10:56:24.045697Z","caller":"traceutil/trace.go:171","msg":"trace[1760480678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1426; }","duration":"4.540873378s","start":"2026-07-17T10:56:19.504815Z","end":"2026-07-17T10:56:24.045688Z","steps":["trace[1760480678] 'agreement among raft nodes before linearized reading' (duration: 4.540015256s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:24.045725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:19.504788Z","time spent":"4.540928349s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T10:56:24.045803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182111304s","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-17T10:56:24.045827Z","caller":"traceutil/trace.go:171","msg":"trace[1618425093] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1426; }","duration":"1.182184055s","start":"2026-07-17T10:56:22.863632Z","end":"2026-07-17T10:56:24.045816Z","steps":["trace[1618425093] 'agreement among raft nodes before linearized reading' (duration: 1.182123594s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:24.045848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:22.863607Z","time spent":"1.182234877s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-17T10:56:24.045901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.247360313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18c30e74125c3ed9\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-07-17T10:56:24.045973Z","caller":"traceutil/trace.go:171","msg":"trace[455126651] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18c30e74125c3ed9; range_end:; response_count:1; response_revision:1426; }","duration":"2.247519589s","start":"2026-07-17T10:56:21.798436Z","end":"2026-07-17T10:56:24.045956Z","steps":["trace[455126651] 'agreement among raft nodes before linearized reading' (duration: 2.247305963s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:24.046008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.31436815s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-07-17T10:56:24.046015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:21.79841Z","time spent":"2.24759272s","remote":"127.0.0.1:43794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18c30e74125c3ed9\" "} {"level":"info","ts":"2026-07-17T10:56:24.046036Z","caller":"traceutil/trace.go:171","msg":"trace[41503946] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1426; }","duration":"3.314450042s","start":"2026-07-17T10:56:20.731576Z","end":"2026-07-17T10:56:24.046026Z","steps":["trace[41503946] 'agreement among raft nodes before linearized reading' (duration: 3.31437517s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:24.046216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:20.731549Z","time spent":"3.314660207s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-17T10:56:24.053049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.818911056s","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":"info","ts":"2026-07-17T10:56:24.053133Z","caller":"traceutil/trace.go:171","msg":"trace[859121669] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1426; }","duration":"1.826064185s","start":"2026-07-17T10:56:22.227054Z","end":"2026-07-17T10:56:24.053118Z","steps":["trace[859121669] 'agreement among raft nodes before linearized reading' (duration: 1.818779962s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:24.053162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:56:22.227033Z","time spent":"1.826121928s","remote":"127.0.0.1:43794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":160,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-17T10:56:38.419795Z","caller":"traceutil/trace.go:171","msg":"trace[430634147] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"187.424044ms","start":"2026-07-17T10:56:38.232339Z","end":"2026-07-17T10:56:38.419763Z","steps":["trace[430634147] 'process raft request' (duration: 187.332882ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:38.420454Z","caller":"traceutil/trace.go:171","msg":"trace[303459232] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"187.554946ms","start":"2026-07-17T10:56:38.232866Z","end":"2026-07-17T10:56:38.420421Z","steps":["trace[303459232] 'process raft request' (duration: 187.35137ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:38.419857Z","caller":"traceutil/trace.go:171","msg":"trace[1565920298] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"187.717301ms","start":"2026-07-17T10:56:38.232096Z","end":"2026-07-17T10:56:38.419813Z","steps":["trace[1565920298] 'process raft request' (duration: 186.856769ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:38.420225Z","caller":"traceutil/trace.go:171","msg":"trace[518464821] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"187.67176ms","start":"2026-07-17T10:56:38.232539Z","end":"2026-07-17T10:56:38.420211Z","steps":["trace[518464821] 'process raft request' (duration: 187.179568ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:39.332274Z","caller":"traceutil/trace.go:171","msg":"trace[1587443891] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"117.382536ms","start":"2026-07-17T10:56:39.21485Z","end":"2026-07-17T10:56:39.332232Z","steps":["trace[1587443891] 'process raft request' (duration: 52.177376ms)","trace[1587443891] 'compare' (duration: 64.46909ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:56:39.384067Z","caller":"traceutil/trace.go:171","msg":"trace[1504239319] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"169.1149ms","start":"2026-07-17T10:56:39.214927Z","end":"2026-07-17T10:56:39.384042Z","steps":["trace[1504239319] 'process raft request' (duration: 168.902235ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:39.384334Z","caller":"traceutil/trace.go:171","msg":"trace[1148048542] transaction","detail":"{read_only:false; response_revision:1504; number_of_response:1; }","duration":"169.301475ms","start":"2026-07-17T10:56:39.215024Z","end":"2026-07-17T10:56:39.384325Z","steps":["trace[1148048542] 'process raft request' (duration: 168.949746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:39.49069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.089439ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-17T10:56:39.80062Z","caller":"traceutil/trace.go:171","msg":"trace[916344199] transaction","detail":"{read_only:false; response_revision:1505; number_of_response:1; }","duration":"126.437615ms","start":"2026-07-17T10:56:39.674139Z","end":"2026-07-17T10:56:39.800577Z","steps":["trace[916344199] 'process raft request' (duration: 104.079695ms)","trace[916344199] 'compare' (duration: 22.191126ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:56:40.536273Z","caller":"traceutil/trace.go:171","msg":"trace[2118606098] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"106.018976ms","start":"2026-07-17T10:56:40.430222Z","end":"2026-07-17T10:56:40.536241Z","steps":["trace[2118606098] 'process raft request' (duration: 105.935944ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:40.536317Z","caller":"traceutil/trace.go:171","msg":"trace[551761331] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"106.314144ms","start":"2026-07-17T10:56:40.429963Z","end":"2026-07-17T10:56:40.536277Z","steps":["trace[551761331] 'process raft request' (duration: 104.859676ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:56:40.536392Z","caller":"traceutil/trace.go:171","msg":"trace[1984239297] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"105.834431ms","start":"2026-07-17T10:56:40.430533Z","end":"2026-07-17T10:56:40.536368Z","steps":["trace[1984239297] 'process raft request' (duration: 105.667767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:56:42.439402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.847016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:56:42.43953Z","caller":"traceutil/trace.go:171","msg":"trace[1140425201] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"140.705361ms","start":"2026-07-17T10:56:42.298801Z","end":"2026-07-17T10:56:42.439506Z","steps":["trace[1140425201] 'compare' (duration: 134.681781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:15.006827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.996259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:57:15.007243Z","caller":"traceutil/trace.go:171","msg":"trace[593022257] transaction","detail":"{read_only:false; response_revision:1670; number_of_response:1; }","duration":"150.423948ms","start":"2026-07-17T10:57:14.856782Z","end":"2026-07-17T10:57:15.007206Z","steps":["trace[593022257] 'process raft request' (duration: 30.873415ms)","trace[593022257] 'compare' (duration: 118.853635ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:15.055017Z","caller":"traceutil/trace.go:171","msg":"trace[690894423] linearizableReadLoop","detail":"{readStateIndex:1737; appliedIndex:1735; }","duration":"104.018714ms","start":"2026-07-17T10:57:14.950968Z","end":"2026-07-17T10:57:15.054987Z","steps":["trace[690894423] 'read index received' (duration: 40.161µs)","trace[690894423] 'applied index is now lower than readState.Index' (duration: 103.976993ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:15.055161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.160687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:57:15.055197Z","caller":"traceutil/trace.go:171","msg":"trace[1708636749] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1670; }","duration":"104.26214ms","start":"2026-07-17T10:57:14.950921Z","end":"2026-07-17T10:57:15.055183Z","steps":["trace[1708636749] 'agreement among raft nodes before linearized reading' (duration: 104.161577ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:27.051484Z","caller":"traceutil/trace.go:171","msg":"trace[1857973324] linearizableReadLoop","detail":"{readStateIndex:1791; appliedIndex:1790; }","duration":"134.25032ms","start":"2026-07-17T10:57:26.917197Z","end":"2026-07-17T10:57:27.051447Z","steps":["trace[1857973324] 'read index received' (duration: 133.698155ms)","trace[1857973324] 'applied index is now lower than readState.Index' (duration: 550.085µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:27.05165Z","caller":"traceutil/trace.go:171","msg":"trace[1633273586] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"159.288441ms","start":"2026-07-17T10:57:26.892328Z","end":"2026-07-17T10:57:27.051616Z","steps":["trace[1633273586] 'process raft request' (duration: 158.623214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:27.05168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.817305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T10:57:27.051734Z","caller":"traceutil/trace.go:171","msg":"trace[938089475] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1722; }","duration":"140.927888ms","start":"2026-07-17T10:57:26.910792Z","end":"2026-07-17T10:57:27.05172Z","steps":["trace[938089475] 'agreement among raft nodes before linearized reading' (duration: 140.788824ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:27.054369Z","caller":"traceutil/trace.go:171","msg":"trace[1534949209] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"131.899928ms","start":"2026-07-17T10:57:26.922439Z","end":"2026-07-17T10:57:27.054339Z","steps":["trace[1534949209] 'process raft request' (duration: 131.747085ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:32.700097Z","caller":"traceutil/trace.go:171","msg":"trace[962654132] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"137.450585ms","start":"2026-07-17T10:57:32.562611Z","end":"2026-07-17T10:57:32.700061Z","steps":["trace[962654132] 'process raft request' (duration: 137.310351ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:17.715977Z","caller":"traceutil/trace.go:171","msg":"trace[1760468535] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"231.533983ms","start":"2026-07-17T10:58:17.484417Z","end":"2026-07-17T10:58:17.715951Z","steps":["trace[1760468535] 'process raft request' (duration: 131.951689ms)","trace[1760468535] 'compare' (duration: 99.362618ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:58:17.716108Z","caller":"traceutil/trace.go:171","msg":"trace[682409455] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"231.38994ms","start":"2026-07-17T10:58:17.484701Z","end":"2026-07-17T10:58:17.716091Z","steps":["trace[682409455] 'process raft request' (duration: 231.195514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:20.894979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.477061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:58:20.895069Z","caller":"traceutil/trace.go:171","msg":"trace[1437039669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1968; }","duration":"107.620685ms","start":"2026-07-17T10:58:20.787429Z","end":"2026-07-17T10:58:20.895049Z","steps":["trace[1437039669] 'range keys from in-memory index tree' (duration: 107.38847ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:31.69411Z","caller":"traceutil/trace.go:171","msg":"trace[194088135] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"128.905589ms","start":"2026-07-17T10:58:31.56516Z","end":"2026-07-17T10:58:31.694065Z","steps":["trace[194088135] 'process raft request' (duration: 128.655153ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:31.959957Z","caller":"traceutil/trace.go:171","msg":"trace[130771605] linearizableReadLoop","detail":"{readStateIndex:2100; appliedIndex:2099; }","duration":"177.108244ms","start":"2026-07-17T10:58:31.782824Z","end":"2026-07-17T10:58:31.959932Z","steps":["trace[130771605] 'read index received' (duration: 115.108385ms)","trace[130771605] 'applied index is now lower than readState.Index' (duration: 61.998969ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:58:31.960028Z","caller":"traceutil/trace.go:171","msg":"trace[555787308] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"261.017803ms","start":"2026-07-17T10:58:31.698985Z","end":"2026-07-17T10:58:31.960002Z","steps":["trace[555787308] 'process raft request' (duration: 199.057185ms)","trace[555787308] 'compare' (duration: 61.771473ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:58:31.960172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.33906ms","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-17T10:58:31.960208Z","caller":"traceutil/trace.go:171","msg":"trace[913996484] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2018; }","duration":"177.420613ms","start":"2026-07-17T10:58:31.782777Z","end":"2026-07-17T10:58:31.960197Z","steps":["trace[913996484] 'agreement among raft nodes before linearized reading' (duration: 177.289679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:31.960242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.866629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-17T10:58:31.960312Z","caller":"traceutil/trace.go:171","msg":"trace[1272639202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2018; }","duration":"170.975632ms","start":"2026-07-17T10:58:31.789317Z","end":"2026-07-17T10:58:31.960293Z","steps":["trace[1272639202] 'agreement among raft nodes before linearized reading' (duration: 170.845618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:31.960417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.043425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:58:31.960512Z","caller":"traceutil/trace.go:171","msg":"trace[1084517627] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2018; }","duration":"134.182248ms","start":"2026-07-17T10:58:31.826309Z","end":"2026-07-17T10:58:31.960492Z","steps":["trace[1084517627] 'agreement among raft nodes before linearized reading' (duration: 134.031674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:32.28516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.190042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:58:32.285273Z","caller":"traceutil/trace.go:171","msg":"trace[1118580810] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"318.880873ms","start":"2026-07-17T10:58:31.96637Z","end":"2026-07-17T10:58:32.285251Z","steps":["trace[1118580810] 'process raft request' (duration: 120.483026ms)","trace[1118580810] 'compare' (duration: 197.901634ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:58:32.28534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:31.966344Z","time spent":"318.975016ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:59:10.201232Z","caller":"traceutil/trace.go:171","msg":"trace[666354516] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"121.860557ms","start":"2026-07-17T10:59:10.079344Z","end":"2026-07-17T10:59:10.201204Z","steps":["trace[666354516] 'process raft request' (duration: 121.702713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:59:24.53982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.037925ms","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-17T10:59:24.539946Z","caller":"traceutil/trace.go:171","msg":"trace[307281413] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2255; }","duration":"104.21521ms","start":"2026-07-17T10:59:24.435704Z","end":"2026-07-17T10:59:24.539919Z","steps":["trace[307281413] 'range keys from in-memory index tree' (duration: 103.755607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:59:24.851945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.861881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:59:24.852094Z","caller":"traceutil/trace.go:171","msg":"trace[904366011] transaction","detail":"{read_only:false; response_revision:2259; number_of_response:1; }","duration":"122.924745ms","start":"2026-07-17T10:59:24.729144Z","end":"2026-07-17T10:59:24.852068Z","steps":["trace[904366011] 'process raft request' (duration: 21.834668ms)","trace[904366011] 'compare' (duration: 100.727498ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:00:10.538285Z","caller":"traceutil/trace.go:171","msg":"trace[516747248] transaction","detail":"{read_only:false; response_revision:2468; number_of_response:1; }","duration":"103.296249ms","start":"2026-07-17T11:00:10.434964Z","end":"2026-07-17T11:00:10.538261Z","steps":["trace[516747248] 'process raft request' (duration: 103.125255ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:00:22.198992Z","caller":"traceutil/trace.go:171","msg":"trace[2076863508] transaction","detail":"{read_only:false; response_revision:2521; number_of_response:1; }","duration":"142.030398ms","start":"2026-07-17T11:00:22.056932Z","end":"2026-07-17T11:00:22.198963Z","steps":["trace[2076863508] 'process raft request' (duration: 141.841333ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:00:45.118837Z","caller":"traceutil/trace.go:171","msg":"trace[2037739215] transaction","detail":"{read_only:false; response_revision:2623; number_of_response:1; }","duration":"153.741507ms","start":"2026-07-17T11:00:44.965062Z","end":"2026-07-17T11:00:45.118803Z","steps":["trace[2037739215] 'process raft request' (duration: 153.485301ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:00:45.120895Z","caller":"traceutil/trace.go:171","msg":"trace[1255996546] linearizableReadLoop","detail":"{readStateIndex:2733; appliedIndex:2731; }","duration":"112.674429ms","start":"2026-07-17T11:00:45.008196Z","end":"2026-07-17T11:00:45.12087Z","steps":["trace[1255996546] 'read index received' (duration: 110.347928ms)","trace[1255996546] 'applied index is now lower than readState.Index' (duration: 2.325651ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:00:45.120994Z","caller":"traceutil/trace.go:171","msg":"trace[1851066643] transaction","detail":"{read_only:false; response_revision:2624; number_of_response:1; }","duration":"153.872771ms","start":"2026-07-17T11:00:44.967084Z","end":"2026-07-17T11:00:45.120957Z","steps":["trace[1851066643] 'process raft request' (duration: 153.643075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:00:45.12169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.473421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-17T11:00:45.121772Z","caller":"traceutil/trace.go:171","msg":"trace[1138512491] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2624; }","duration":"113.606194ms","start":"2026-07-17T11:00:45.008144Z","end":"2026-07-17T11:00:45.12175Z","steps":["trace[1138512491] 'agreement among raft nodes before linearized reading' (duration: 112.851693ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:01:03.33621Z","caller":"traceutil/trace.go:171","msg":"trace[1947076190] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"119.577213ms","start":"2026-07-17T11:01:03.216589Z","end":"2026-07-17T11:01:03.336167Z","steps":["trace[1947076190] 'process raft request' (duration: 119.396198ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:01:03.342705Z","caller":"traceutil/trace.go:171","msg":"trace[664664961] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"125.952163ms","start":"2026-07-17T11:01:03.216728Z","end":"2026-07-17T11:01:03.34268Z","steps":["trace[664664961] 'process raft request' (duration: 125.741857ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:01:03.342705Z","caller":"traceutil/trace.go:171","msg":"trace[67191482] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"123.490156ms","start":"2026-07-17T11:01:03.219178Z","end":"2026-07-17T11:01:03.342669Z","steps":["trace[67191482] 'process raft request' (duration: 123.443335ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:01:12.975489Z","caller":"traceutil/trace.go:171","msg":"trace[1588693118] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"143.509388ms","start":"2026-07-17T11:01:12.831956Z","end":"2026-07-17T11:01:12.975465Z","steps":["trace[1588693118] 'process raft request' (duration: 143.287783ms)"],"step_count":1}