{"level":"warn","ts":"2026-07-30T21:10:34.652485Z","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-30T21:10:34.652575Z","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-30T21:10:34.652638Z","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-30T21:10:34.652653Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-07-30T21:10:34.652671Z","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-30T21:10:34.653365Z","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-30T21:10:34.653439Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.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-30T21:10:34.659676Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.024306ms"} {"level":"info","ts":"2026-07-30T21:10:34.669277Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-07-30T21:10:34.669507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T21:10:34.669541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-07-30T21:10:34.669557Z","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-30T21:10:34.669575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-07-30T21:10:34.66965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-07-30T21:10:34.676597Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T21:10:34.678844Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T21:10:34.680717Z","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-30T21:10:34.683186Z","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-30T21:10:34.683403Z","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-30T21:10:34.683484Z","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-30T21:10:34.683605Z","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-30T21:10:34.683618Z","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-30T21:10:34.685303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-07-30T21:10:34.685475Z","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-30T21:10:34.687707Z","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-30T21:10:34.687851Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-07-30T21:10:34.687889Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-07-30T21:10:34.687979Z","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-30T21:10:34.68804Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T21:10:34.970916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T21:10:34.971025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T21:10:34.971081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-07-30T21:10:34.971115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-07-30T21:10:34.971132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-07-30T21:10:34.971167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-07-30T21:10:34.971353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-07-30T21:10:34.980103Z","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-30T21:10:34.980191Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T21:10:34.980669Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T21:10:34.98105Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:10:34.981265Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T21:10:34.981307Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T21:10:34.982136Z","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-30T21:10:34.982356Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:10:34.982401Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:10:34.984337Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-07-30T21:10:34.986279Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-30T21:10:49.102558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.097775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:10:49.1027Z","caller":"traceutil/trace.go:171","msg":"trace[726555944] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:373; }","duration":"676.812536ms","start":"2026-07-30T21:10:48.425871Z","end":"2026-07-30T21:10:49.102684Z","steps":["trace[726555944] 'read index received' (duration: 358.126098ms)","trace[726555944] 'applied index is now lower than readState.Index' (duration: 318.685028ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:10:49.102749Z","caller":"traceutil/trace.go:171","msg":"trace[599650224] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"677.900251ms","start":"2026-07-30T21:10:48.424816Z","end":"2026-07-30T21:10:49.102716Z","steps":["trace[599650224] 'process raft request' (duration: 359.14292ms)","trace[599650224] 'compare' (duration: 317.982753ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:10:49.102886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.564884ms","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-30T21:10:49.102908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.477299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-30T21:10:49.102906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.028392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"warn","ts":"2026-07-30T21:10:49.102979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.704853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:10:49.102985Z","caller":"traceutil/trace.go:171","msg":"trace[867628227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:362; }","duration":"185.565171ms","start":"2026-07-30T21:10:48.917404Z","end":"2026-07-30T21:10:49.10297Z","steps":["trace[867628227] 'agreement among raft nodes before linearized reading' (duration: 185.425568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:10:49.102927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:10:48.424789Z","time spent":"678.049445ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:10:49.102994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.076472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T21:10:49.103013Z","caller":"traceutil/trace.go:171","msg":"trace[2059390106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:362; }","duration":"311.768855ms","start":"2026-07-30T21:10:48.791237Z","end":"2026-07-30T21:10:49.103005Z","steps":["trace[2059390106] 'agreement among raft nodes before linearized reading' (duration: 311.723184ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:10:49.103061Z","caller":"traceutil/trace.go:171","msg":"trace[1715490661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:362; }","duration":"186.161944ms","start":"2026-07-30T21:10:48.916882Z","end":"2026-07-30T21:10:49.103044Z","steps":["trace[1715490661] 'agreement among raft nodes before linearized reading' (duration: 186.040242ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:10:49.103013Z","caller":"traceutil/trace.go:171","msg":"trace[305533508] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:362; }","duration":"677.207606ms","start":"2026-07-30T21:10:48.425787Z","end":"2026-07-30T21:10:49.102994Z","steps":["trace[305533508] 'agreement among raft nodes before linearized reading' (duration: 676.976281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:10:49.103175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:10:48.425753Z","time spent":"677.40712ms","remote":"127.0.0.1:35198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5349,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-07-30T21:10:49.103063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:10:48.791218Z","time spent":"311.836796ms","remote":"127.0.0.1:34990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T21:10:49.102926Z","caller":"traceutil/trace.go:171","msg":"trace[691021397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:362; }","duration":"364.648966ms","start":"2026-07-30T21:10:48.738268Z","end":"2026-07-30T21:10:49.102917Z","steps":["trace[691021397] 'agreement among raft nodes before linearized reading' (duration: 364.531743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:10:49.103368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:10:48.738244Z","time spent":"365.113765ms","remote":"127.0.0.1:35262","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-30T21:11:12.047687Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568749633286468,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T21:11:12.098137Z","caller":"traceutil/trace.go:171","msg":"trace[1837676600] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"612.683282ms","start":"2026-07-30T21:11:11.485416Z","end":"2026-07-30T21:11:12.098099Z","steps":["trace[1837676600] 'process raft request' (duration: 612.434897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:12.098356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:11.485381Z","time spent":"612.865437ms","remote":"127.0.0.1:35060","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-30T21:11:12.103138Z","caller":"traceutil/trace.go:171","msg":"trace[371252431] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"613.210064ms","start":"2026-07-30T21:11:11.489906Z","end":"2026-07-30T21:11:12.103116Z","steps":["trace[371252431] 'process raft request' (duration: 613.081691ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:12.103234Z","caller":"traceutil/trace.go:171","msg":"trace[1458457124] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"613.300846ms","start":"2026-07-30T21:11:11.489916Z","end":"2026-07-30T21:11:12.103217Z","steps":["trace[1458457124] 'process raft request' (duration: 613.152323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:12.103249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:11.489883Z","time spent":"613.314096ms","remote":"127.0.0.1:35060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:11:12.1033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:11.489889Z","time spent":"613.384528ms","remote":"127.0.0.1:35060","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-30T21:11:12.103381Z","caller":"traceutil/trace.go:171","msg":"trace[991703003] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"597.221483ms","start":"2026-07-30T21:11:11.506152Z","end":"2026-07-30T21:11:12.103373Z","steps":["trace[991703003] 'process raft request' (duration: 597.045479ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:12.103385Z","caller":"traceutil/trace.go:171","msg":"trace[195031506] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"597.293905ms","start":"2026-07-30T21:11:11.506057Z","end":"2026-07-30T21:11:12.103351Z","steps":["trace[195031506] 'process raft request' (duration: 597.102571ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:12.103459Z","caller":"traceutil/trace.go:171","msg":"trace[1893552060] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"593.764856ms","start":"2026-07-30T21:11:11.509686Z","end":"2026-07-30T21:11:12.103451Z","steps":["trace[1893552060] 'process raft request' (duration: 593.577772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:12.103491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:11.509681Z","time spent":"593.796487ms","remote":"127.0.0.1:35060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:11:12.103456Z","caller":"traceutil/trace.go:171","msg":"trace[1652710555] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"593.759446ms","start":"2026-07-30T21:11:11.50964Z","end":"2026-07-30T21:11:12.103399Z","steps":["trace[1652710555] 'process raft request' (duration: 593.586252ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:12.10349Z","caller":"traceutil/trace.go:171","msg":"trace[154976083] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"597.434999ms","start":"2026-07-30T21:11:11.505993Z","end":"2026-07-30T21:11:12.103428Z","steps":["trace[154976083] 'process raft request' (duration: 597.126262ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:12.103566Z","caller":"traceutil/trace.go:171","msg":"trace[428259985] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"564.055646ms","start":"2026-07-30T21:11:11.539503Z","end":"2026-07-30T21:11:12.103559Z","steps":["trace[428259985] 'process raft request' (duration: 563.854032ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:12.103566Z","caller":"traceutil/trace.go:171","msg":"trace[92280200] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:501; }","duration":"556.505196ms","start":"2026-07-30T21:11:11.547049Z","end":"2026-07-30T21:11:12.103554Z","steps":["trace[92280200] 'read index received' (duration: 550.804568ms)","trace[92280200] 'applied index is now lower than readState.Index' (duration: 5.699968ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:11:12.103589Z","caller":"traceutil/trace.go:171","msg":"trace[460369103] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"581.431848ms","start":"2026-07-30T21:11:11.522144Z","end":"2026-07-30T21:11:12.103576Z","steps":["trace[460369103] 'process raft request' (duration: 581.160482ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:12.103592Z","caller":"traceutil/trace.go:171","msg":"trace[783910343] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"555.590195ms","start":"2026-07-30T21:11:11.547989Z","end":"2026-07-30T21:11:12.103579Z","steps":["trace[783910343] 'process raft request' (duration: 555.424781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:12.103603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:11.53949Z","time spent":"564.095647ms","remote":"127.0.0.1:35060","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-30T21:11:12.103632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:11.547967Z","time spent":"555.646627ms","remote":"127.0.0.1:35060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:11:12.103623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:11.522118Z","time spent":"581.489709ms","remote":"127.0.0.1:35060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:11:12.103629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:11.509634Z","time spent":"593.902819ms","remote":"127.0.0.1:35060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:11:12.103421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:11.506147Z","time spent":"597.257464ms","remote":"127.0.0.1:35060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:11:12.103724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.487039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:11:12.103731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.690631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-30T21:11:12.103725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:11.505974Z","time spent":"597.620232ms","remote":"127.0.0.1:35060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:11:12.103642Z","caller":"traceutil/trace.go:171","msg":"trace[580152300] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"434.763164ms","start":"2026-07-30T21:11:11.668872Z","end":"2026-07-30T21:11:12.103635Z","steps":["trace[580152300] 'process raft request' (duration: 434.617701ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:12.10376Z","caller":"traceutil/trace.go:171","msg":"trace[1261460859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"312.573421ms","start":"2026-07-30T21:11:11.791177Z","end":"2026-07-30T21:11:12.103751Z","steps":["trace[1261460859] 'agreement among raft nodes before linearized reading' (duration: 312.517679ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:12.10376Z","caller":"traceutil/trace.go:171","msg":"trace[498255900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:496; }","duration":"556.759303ms","start":"2026-07-30T21:11:11.546994Z","end":"2026-07-30T21:11:12.103753Z","steps":["trace[498255900] 'agreement among raft nodes before linearized reading' (duration: 556.661361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:12.103782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:11.791085Z","time spent":"312.692623ms","remote":"127.0.0.1:34990","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-30T21:11:12.103782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:11.546968Z","time spent":"556.809383ms","remote":"127.0.0.1:35262","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-30T21:11:12.103787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:11.668836Z","time spent":"434.932987ms","remote":"127.0.0.1:35060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:11:12.103534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:11.506051Z","time spent":"597.409187ms","remote":"127.0.0.1:35060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:11:18.618933Z","caller":"traceutil/trace.go:171","msg":"trace[120403319] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"484.090105ms","start":"2026-07-30T21:11:18.134819Z","end":"2026-07-30T21:11:18.618909Z","steps":["trace[120403319] 'read index received' (duration: 482.08547ms)","trace[120403319] 'applied index is now lower than readState.Index' (duration: 2.003505ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:11:18.619023Z","caller":"traceutil/trace.go:171","msg":"trace[843932041] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"551.372981ms","start":"2026-07-30T21:11:18.067615Z","end":"2026-07-30T21:11:18.618988Z","steps":["trace[843932041] 'process raft request' (duration: 549.923448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:18.619106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.275889ms","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-30T21:11:18.619158Z","caller":"traceutil/trace.go:171","msg":"trace[888433798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"484.372492ms","start":"2026-07-30T21:11:18.134771Z","end":"2026-07-30T21:11:18.619144Z","steps":["trace[888433798] 'agreement among raft nodes before linearized reading' (duration: 484.268369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:18.619163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:18.067581Z","time spent":"551.499874ms","remote":"127.0.0.1:35102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:11:18.619198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:18.134749Z","time spent":"484.439493ms","remote":"127.0.0.1:35262","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-30T21:11:18.619226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.690573ms","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-30T21:11:18.619335Z","caller":"traceutil/trace.go:171","msg":"trace[752322652] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:580; }","duration":"387.844977ms","start":"2026-07-30T21:11:18.231446Z","end":"2026-07-30T21:11:18.619291Z","steps":["trace[752322652] 'agreement among raft nodes before linearized reading' (duration: 387.651293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:18.619369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:18.231422Z","time spent":"387.939988ms","remote":"127.0.0.1:35048","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-30T21:11:18.824555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.516677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:11:18.824596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.954947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-30T21:11:18.82465Z","caller":"traceutil/trace.go:171","msg":"trace[874647420] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:583; }","duration":"109.67479ms","start":"2026-07-30T21:11:18.714956Z","end":"2026-07-30T21:11:18.824631Z","steps":["trace[874647420] 'range keys from in-memory index tree' (duration: 109.371623ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:18.824676Z","caller":"traceutil/trace.go:171","msg":"trace[205858699] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:583; }","duration":"118.10335ms","start":"2026-07-30T21:11:18.706553Z","end":"2026-07-30T21:11:18.824656Z","steps":["trace[205858699] 'range keys from in-memory index tree' (duration: 117.784954ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:19.518318Z","caller":"traceutil/trace.go:171","msg":"trace[2018063229] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"594.165675ms","start":"2026-07-30T21:11:18.924125Z","end":"2026-07-30T21:11:19.518291Z","steps":["trace[2018063229] 'process raft request' (duration: 594.019832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:19.518478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:18.924103Z","time spent":"594.297787ms","remote":"127.0.0.1:35198","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-30T21:11:19.579276Z","caller":"traceutil/trace.go:171","msg":"trace[236014135] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:605; }","duration":"300.386328ms","start":"2026-07-30T21:11:19.278852Z","end":"2026-07-30T21:11:19.579239Z","steps":["trace[236014135] 'read index received' (duration: 239.385044ms)","trace[236014135] 'applied index is now lower than readState.Index' (duration: 60.999954ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:11:19.579347Z","caller":"traceutil/trace.go:171","msg":"trace[1883834605] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"346.319732ms","start":"2026-07-30T21:11:19.232987Z","end":"2026-07-30T21:11:19.579307Z","steps":["trace[1883834605] 'process raft request' (duration: 340.591793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:19.579554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.148569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-30T21:11:19.579551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:19.232962Z","time spent":"346.475896ms","remote":"127.0.0.1:35102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:11:19.579606Z","caller":"traceutil/trace.go:171","msg":"trace[68703532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"295.252202ms","start":"2026-07-30T21:11:19.284343Z","end":"2026-07-30T21:11:19.579596Z","steps":["trace[68703532] 'agreement among raft nodes before linearized reading' (duration: 295.065678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:19.579554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.683505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T21:11:19.57966Z","caller":"traceutil/trace.go:171","msg":"trace[97496187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"300.835938ms","start":"2026-07-30T21:11:19.278797Z","end":"2026-07-30T21:11:19.579633Z","steps":["trace[97496187] 'agreement among raft nodes before linearized reading' (duration: 300.581952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:19.579705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:19.278772Z","time spent":"300.925339ms","remote":"127.0.0.1:35262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T21:11:19.579904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.954364ms","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-30T21:11:19.58Z","caller":"traceutil/trace.go:171","msg":"trace[796973548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"295.086408ms","start":"2026-07-30T21:11:19.284892Z","end":"2026-07-30T21:11:19.579978Z","steps":["trace[796973548] 'agreement among raft nodes before linearized reading' (duration: 294.694059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:20.291517Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568749633286721,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:11:20.791766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568749633286721,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:11:21.044054Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.393175115s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-30T21:11:21.252197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327820462s","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-30T21:11:21.252316Z","caller":"traceutil/trace.go:171","msg":"trace[985888268] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:588; }","duration":"1.327949685s","start":"2026-07-30T21:11:19.924348Z","end":"2026-07-30T21:11:21.252298Z","steps":["trace[985888268] 'range keys from in-memory index tree' (duration: 1.327803672s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:21.252358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.981135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:11:21.252525Z","caller":"traceutil/trace.go:171","msg":"trace[1807833183] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:610; }","duration":"1.46178807s","start":"2026-07-30T21:11:19.79072Z","end":"2026-07-30T21:11:21.252508Z","steps":["trace[1807833183] 'read index received' (duration: 1.25360133s)","trace[1807833183] 'applied index is now lower than readState.Index' (duration: 208.18591ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:11:21.252701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.461987815s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:11:21.252746Z","caller":"traceutil/trace.go:171","msg":"trace[1839168365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"1.462078647s","start":"2026-07-30T21:11:19.790656Z","end":"2026-07-30T21:11:21.252735Z","steps":["trace[1839168365] 'agreement among raft nodes before linearized reading' (duration: 1.461979165s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:21.252779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:19.790629Z","time spent":"1.462141968s","remote":"127.0.0.1:34990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T21:11:21.252763Z","caller":"traceutil/trace.go:171","msg":"trace[1186857930] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.602003969s","start":"2026-07-30T21:11:19.650705Z","end":"2026-07-30T21:11:21.252709Z","steps":["trace[1186857930] 'process raft request' (duration: 1.393603125s)","trace[1186857930] 'compare' (duration: 207.858772ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:11:21.253025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:19.650673Z","time spent":"1.602193973s","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:11:21.252807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.67306ms","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-30T21:11:21.253349Z","caller":"traceutil/trace.go:171","msg":"trace[597857319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"561.251283ms","start":"2026-07-30T21:11:20.692082Z","end":"2026-07-30T21:11:21.253333Z","steps":["trace[597857319] 'agreement among raft nodes before linearized reading' (duration: 560.617409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:21.253389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:20.692062Z","time spent":"561.314715ms","remote":"127.0.0.1:35262","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-30T21:11:21.252781Z","caller":"traceutil/trace.go:171","msg":"trace[1234131222] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"473.004396ms","start":"2026-07-30T21:11:20.779735Z","end":"2026-07-30T21:11:21.252739Z","steps":["trace[1234131222] 'process raft request' (duration: 472.71292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:21.253504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:20.779711Z","time spent":"473.749062ms","remote":"127.0.0.1:35262","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-30T21:11:22.296244Z","caller":"traceutil/trace.go:171","msg":"trace[1406827480] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"100.647487ms","start":"2026-07-30T21:11:22.195562Z","end":"2026-07-30T21:11:22.29621Z","steps":["trace[1406827480] 'process raft request' (duration: 99.652474ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:23.299189Z","caller":"traceutil/trace.go:171","msg":"trace[1217394453] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"172.467588ms","start":"2026-07-30T21:11:23.12655Z","end":"2026-07-30T21:11:23.299017Z","steps":["trace[1217394453] 'process raft request' (duration: 172.262883ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:26.256302Z","caller":"traceutil/trace.go:171","msg":"trace[1936727915] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"103.724879ms","start":"2026-07-30T21:11:26.152548Z","end":"2026-07-30T21:11:26.256273Z","steps":["trace[1936727915] 'process raft request' (duration: 103.521634ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:37.494052Z","caller":"traceutil/trace.go:171","msg":"trace[1787187140] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"427.15305ms","start":"2026-07-30T21:11:37.066856Z","end":"2026-07-30T21:11:37.494009Z","steps":["trace[1787187140] 'read index received' (duration: 426.987937ms)","trace[1787187140] 'applied index is now lower than readState.Index' (duration: 163.863µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:11:37.494144Z","caller":"traceutil/trace.go:171","msg":"trace[1951542764] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"457.312ms","start":"2026-07-30T21:11:37.036786Z","end":"2026-07-30T21:11:37.494098Z","steps":["trace[1951542764] 'process raft request' (duration: 457.041924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:37.494244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.351585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:11:37.494862Z","caller":"traceutil/trace.go:171","msg":"trace[2055769394] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:756; }","duration":"428.02855ms","start":"2026-07-30T21:11:37.066816Z","end":"2026-07-30T21:11:37.494845Z","steps":["trace[2055769394] 'agreement among raft nodes before linearized reading' (duration: 427.361795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:37.494924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:37.0668Z","time spent":"428.107541ms","remote":"127.0.0.1:35140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:11:37.494926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:37.036765Z","time spent":"458.027516ms","remote":"127.0.0.1:35198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:11:37.494551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.131593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:11:37.495235Z","caller":"traceutil/trace.go:171","msg":"trace[572134906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"102.91158ms","start":"2026-07-30T21:11:37.392309Z","end":"2026-07-30T21:11:37.49522Z","steps":["trace[572134906] 'agreement among raft nodes before linearized reading' (duration: 101.962729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:37.711754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.014077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:11:37.711839Z","caller":"traceutil/trace.go:171","msg":"trace[460604671] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"122.226895ms","start":"2026-07-30T21:11:37.589599Z","end":"2026-07-30T21:11:37.711826Z","steps":["trace[460604671] 'read index received' (duration: 76.581µs)","trace[460604671] 'applied index is now lower than readState.Index' (duration: 122.149214ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:11:37.711915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.328507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:11:37.711931Z","caller":"traceutil/trace.go:171","msg":"trace[254310362] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:757; }","duration":"122.398399ms","start":"2026-07-30T21:11:37.589526Z","end":"2026-07-30T21:11:37.711924Z","steps":["trace[254310362] 'agreement among raft nodes before linearized reading' (duration: 122.361818ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:37.711919Z","caller":"traceutil/trace.go:171","msg":"trace[944534088] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"211.15807ms","start":"2026-07-30T21:11:37.500727Z","end":"2026-07-30T21:11:37.711885Z","steps":["trace[944534088] 'process raft request' (duration: 58.944389ms)","trace[944534088] 'compare' (duration: 151.931695ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:12:16.112405Z","caller":"traceutil/trace.go:171","msg":"trace[451223608] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1071; }","duration":"195.929487ms","start":"2026-07-30T21:12:15.916455Z","end":"2026-07-30T21:12:16.112385Z","steps":["trace[451223608] 'read index received' (duration: 195.799034ms)","trace[451223608] 'applied index is now lower than readState.Index' (duration: 129.603µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:12:16.112453Z","caller":"traceutil/trace.go:171","msg":"trace[1953321367] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"198.898894ms","start":"2026-07-30T21:12:15.913533Z","end":"2026-07-30T21:12:16.112431Z","steps":["trace[1953321367] 'process raft request' (duration: 198.734901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:16.112588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.115681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:12:16.11262Z","caller":"traceutil/trace.go:171","msg":"trace[1616384155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1036; }","duration":"196.161772ms","start":"2026-07-30T21:12:15.91645Z","end":"2026-07-30T21:12:16.112612Z","steps":["trace[1616384155] 'agreement among raft nodes before linearized reading' (duration: 196.03712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:16.112638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.063024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:12:16.112678Z","caller":"traceutil/trace.go:171","msg":"trace[1499449467] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1036; }","duration":"170.123796ms","start":"2026-07-30T21:12:15.942543Z","end":"2026-07-30T21:12:16.112667Z","steps":["trace[1499449467] 'agreement among raft nodes before linearized reading' (duration: 170.051884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:16.112829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.126259ms","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-30T21:12:16.112919Z","caller":"traceutil/trace.go:171","msg":"trace[2139936375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1036; }","duration":"195.222721ms","start":"2026-07-30T21:12:15.917677Z","end":"2026-07-30T21:12:16.112899Z","steps":["trace[2139936375] 'agreement among raft nodes before linearized reading' (duration: 194.995266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:16.112863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.864006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T21:12:16.112995Z","caller":"traceutil/trace.go:171","msg":"trace[1581422414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1036; }","duration":"196.010589ms","start":"2026-07-30T21:12:15.916963Z","end":"2026-07-30T21:12:16.112974Z","steps":["trace[1581422414] 'agreement among raft nodes before linearized reading' (duration: 195.627591ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:16.220043Z","caller":"traceutil/trace.go:171","msg":"trace[2127602070] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"102.032891ms","start":"2026-07-30T21:12:16.117982Z","end":"2026-07-30T21:12:16.220015Z","steps":["trace[2127602070] 'process raft request' (duration: 101.952809ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:16.220054Z","caller":"traceutil/trace.go:171","msg":"trace[391631721] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"102.061151ms","start":"2026-07-30T21:12:16.117946Z","end":"2026-07-30T21:12:16.220007Z","steps":["trace[391631721] 'process raft request' (duration: 101.905408ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:18.593177Z","caller":"traceutil/trace.go:171","msg":"trace[282746810] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1077; }","duration":"414.307351ms","start":"2026-07-30T21:12:18.178845Z","end":"2026-07-30T21:12:18.593153Z","steps":["trace[282746810] 'read index received' (duration: 414.204329ms)","trace[282746810] 'applied index is now lower than readState.Index' (duration: 99.932µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:12:18.593514Z","caller":"traceutil/trace.go:171","msg":"trace[15886713] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"472.702788ms","start":"2026-07-30T21:12:18.120799Z","end":"2026-07-30T21:12:18.593502Z","steps":["trace[15886713] 'process raft request' (duration: 472.215887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:18.593679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:18.12077Z","time spent":"472.781879ms","remote":"127.0.0.1:35262","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-30T21:12:18.593824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.975496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:12:18.594145Z","caller":"traceutil/trace.go:171","msg":"trace[56881669] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1042; }","duration":"415.319063ms","start":"2026-07-30T21:12:18.1788Z","end":"2026-07-30T21:12:18.594119Z","steps":["trace[56881669] 'agreement among raft nodes before linearized reading' (duration: 414.993406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:18.594187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:18.178779Z","time spent":"415.394095ms","remote":"127.0.0.1:58930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-07-30T21:12:19.010677Z","caller":"traceutil/trace.go:171","msg":"trace[17510075] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"783.962584ms","start":"2026-07-30T21:12:18.226687Z","end":"2026-07-30T21:12:19.010649Z","steps":["trace[17510075] 'process raft request' (duration: 780.732041ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:19.01078Z","caller":"traceutil/trace.go:171","msg":"trace[976663300] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1078; }","duration":"417.477971ms","start":"2026-07-30T21:12:18.593271Z","end":"2026-07-30T21:12:19.010749Z","steps":["trace[976663300] 'read index received' (duration: 414.115216ms)","trace[976663300] 'applied index is now lower than readState.Index' (duration: 3.359645ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:12:19.010847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:18.22667Z","time spent":"784.070427ms","remote":"127.0.0.1:35262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:12:19.010865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.899173ms","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-30T21:12:19.010911Z","caller":"traceutil/trace.go:171","msg":"trace[735769805] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:1044; }","duration":"775.961974ms","start":"2026-07-30T21:12:18.234937Z","end":"2026-07-30T21:12:19.010899Z","steps":["trace[735769805] 'agreement among raft nodes before linearized reading' (duration: 775.840961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:19.01094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:18.234926Z","time spent":"776.006834ms","remote":"127.0.0.1:35048","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-30T21:12:19.011023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.878055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:12:19.011072Z","caller":"traceutil/trace.go:171","msg":"trace[1002042949] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:1044; }","duration":"670.949567ms","start":"2026-07-30T21:12:18.340106Z","end":"2026-07-30T21:12:19.011055Z","steps":["trace[1002042949] 'agreement among raft nodes before linearized reading' (duration: 670.870425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:19.011108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:18.340088Z","time spent":"671.012208ms","remote":"127.0.0.1:35102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-07-30T21:12:19.011107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.137066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-30T21:12:19.011121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.039953ms","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-30T21:12:19.011165Z","caller":"traceutil/trace.go:171","msg":"trace[2035131586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1044; }","duration":"787.222707ms","start":"2026-07-30T21:12:18.223923Z","end":"2026-07-30T21:12:19.011146Z","steps":["trace[2035131586] 'agreement among raft nodes before linearized reading' (duration: 787.011972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:19.011181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.825447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:12:19.011196Z","caller":"traceutil/trace.go:171","msg":"trace[1515203203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1044; }","duration":"791.123535ms","start":"2026-07-30T21:12:18.220053Z","end":"2026-07-30T21:12:19.011177Z","steps":["trace[1515203203] 'agreement among raft nodes before linearized reading' (duration: 790.89672ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:19.011213Z","caller":"traceutil/trace.go:171","msg":"trace[203379973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"219.899888ms","start":"2026-07-30T21:12:18.791304Z","end":"2026-07-30T21:12:19.011204Z","steps":["trace[203379973] 'agreement among raft nodes before linearized reading' (duration: 219.847957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:19.011238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:18.219991Z","time spent":"791.239188ms","remote":"127.0.0.1:35262","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-30T21:12:19.011237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:18.22391Z","time spent":"787.294109ms","remote":"127.0.0.1:35262","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-30T21:12:19.011438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.517414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-30T21:12:19.011437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.423865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T21:12:19.01148Z","caller":"traceutil/trace.go:171","msg":"trace[1275164155] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1044; }","duration":"787.579225ms","start":"2026-07-30T21:12:18.223887Z","end":"2026-07-30T21:12:19.011467Z","steps":["trace[1275164155] 'agreement among raft nodes before linearized reading' (duration: 787.472693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:19.011544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:18.223873Z","time spent":"787.635857ms","remote":"127.0.0.1:35262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T21:12:19.012115Z","caller":"traceutil/trace.go:171","msg":"trace[931993808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1044; }","duration":"183.517126ms","start":"2026-07-30T21:12:18.827957Z","end":"2026-07-30T21:12:19.011474Z","steps":["trace[931993808] 'agreement among raft nodes before linearized reading' (duration: 183.381183ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:38.826648Z","caller":"traceutil/trace.go:171","msg":"trace[1906184343] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"137.779864ms","start":"2026-07-30T21:12:38.688836Z","end":"2026-07-30T21:12:38.826616Z","steps":["trace[1906184343] 'process raft request' (duration: 137.61805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:42.901711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.328357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:12:42.902045Z","caller":"traceutil/trace.go:171","msg":"trace[845026409] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"190.330582ms","start":"2026-07-30T21:12:42.711601Z","end":"2026-07-30T21:12:42.901932Z","steps":["trace[845026409] 'process raft request' (duration: 39.682447ms)","trace[845026409] 'compare' (duration: 150.192635ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:12:43.636811Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568749633288195,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T21:12:43.977879Z","caller":"traceutil/trace.go:171","msg":"trace[437490121] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"877.021851ms","start":"2026-07-30T21:12:43.100823Z","end":"2026-07-30T21:12:43.977845Z","steps":["trace[437490121] 'process raft request' (duration: 876.839257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:43.978078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:43.100795Z","time spent":"877.177143ms","remote":"127.0.0.1:35262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:12:44.351287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.350905ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-30T21:12:44.351383Z","caller":"traceutil/trace.go:171","msg":"trace[921546241] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1373; }","duration":"1.215564511s","start":"2026-07-30T21:12:43.135802Z","end":"2026-07-30T21:12:44.351367Z","steps":["trace[921546241] 'read index received' (duration: 841.966997ms)","trace[921546241] 'applied index is now lower than readState.Index' (duration: 373.596213ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:12:44.351726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214865725s","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-30T21:12:44.351752Z","caller":"traceutil/trace.go:171","msg":"trace[962720467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1332; }","duration":"1.214928657s","start":"2026-07-30T21:12:43.136817Z","end":"2026-07-30T21:12:44.351745Z","steps":["trace[962720467] 'agreement among raft nodes before linearized reading' (duration: 1.214804644s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:44.351713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.300636ms","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":"warn","ts":"2026-07-30T21:12:44.351778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:43.1368Z","time spent":"1.214972078s","remote":"127.0.0.1:35262","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-30T21:12:44.351762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.889357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:12:44.351764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21596629s","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-30T21:12:44.351804Z","caller":"traceutil/trace.go:171","msg":"trace[513250570] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1332; }","duration":"169.44292ms","start":"2026-07-30T21:12:44.182338Z","end":"2026-07-30T21:12:44.351781Z","steps":["trace[513250570] 'agreement among raft nodes before linearized reading' (duration: 169.288366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:44.351837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211397948s","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-30T21:12:44.351853Z","caller":"traceutil/trace.go:171","msg":"trace[523174474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1332; }","duration":"561.03108ms","start":"2026-07-30T21:12:43.790796Z","end":"2026-07-30T21:12:44.351827Z","steps":["trace[523174474] 'agreement among raft nodes before linearized reading' (duration: 560.899867ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:44.351854Z","caller":"traceutil/trace.go:171","msg":"trace[408759678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1332; }","duration":"1.216105263s","start":"2026-07-30T21:12:43.135732Z","end":"2026-07-30T21:12:44.351837Z","steps":["trace[408759678] 'agreement among raft nodes before linearized reading' (duration: 1.215880708s)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:44.3519Z","caller":"traceutil/trace.go:171","msg":"trace[494024653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1332; }","duration":"1.211473539s","start":"2026-07-30T21:12:43.140413Z","end":"2026-07-30T21:12:44.351886Z","steps":["trace[494024653] 'agreement among raft nodes before linearized reading' (duration: 1.211289165s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:44.351903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:43.790768Z","time spent":"561.126771ms","remote":"127.0.0.1:34990","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-30T21:12:44.351907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:43.135694Z","time spent":"1.216203315s","remote":"127.0.0.1:35262","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-30T21:12:44.351941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:43.140407Z","time spent":"1.21152635s","remote":"127.0.0.1:35262","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-30T21:12:44.352038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206592798s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-30T21:12:44.352142Z","caller":"traceutil/trace.go:171","msg":"trace[514424396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1332; }","duration":"1.206707271s","start":"2026-07-30T21:12:43.145415Z","end":"2026-07-30T21:12:44.352122Z","steps":["trace[514424396] 'agreement among raft nodes before linearized reading' (duration: 1.206253341s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:44.35222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:43.145395Z","time spent":"1.206812753s","remote":"127.0.0.1:35262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-30T21:12:44.572505Z","caller":"traceutil/trace.go:171","msg":"trace[8195460] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"215.726046ms","start":"2026-07-30T21:12:44.356747Z","end":"2026-07-30T21:12:44.572473Z","steps":["trace[8195460] 'process raft request' (duration: 150.657345ms)","trace[8195460] 'compare' (duration: 64.860486ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:12:44.572771Z","caller":"traceutil/trace.go:171","msg":"trace[549564054] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"214.981769ms","start":"2026-07-30T21:12:44.357778Z","end":"2026-07-30T21:12:44.57276Z","steps":["trace[549564054] 'process raft request' (duration: 214.65092ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:44.573104Z","caller":"traceutil/trace.go:171","msg":"trace[1611005282] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"207.754835ms","start":"2026-07-30T21:12:44.365335Z","end":"2026-07-30T21:12:44.573089Z","steps":["trace[1611005282] 'process raft request' (duration: 207.387267ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:46.567963Z","caller":"traceutil/trace.go:171","msg":"trace[1248907062] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1381; }","duration":"286.547695ms","start":"2026-07-30T21:12:46.281385Z","end":"2026-07-30T21:12:46.567933Z","steps":["trace[1248907062] 'read index received' (duration: 286.387931ms)","trace[1248907062] 'applied index is now lower than readState.Index' (duration: 158.554µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:12:46.568033Z","caller":"traceutil/trace.go:171","msg":"trace[1124844415] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"583.044336ms","start":"2026-07-30T21:12:45.984948Z","end":"2026-07-30T21:12:46.567992Z","steps":["trace[1124844415] 'process raft request' (duration: 582.791591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:46.568232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.819812ms","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:508"} {"level":"warn","ts":"2026-07-30T21:12:46.56827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.028783ms","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-30T21:12:46.568311Z","caller":"traceutil/trace.go:171","msg":"trace[683941365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1339; }","duration":"159.129896ms","start":"2026-07-30T21:12:46.40917Z","end":"2026-07-30T21:12:46.5683Z","steps":["trace[683941365] 'agreement among raft nodes before linearized reading' (duration: 158.945602ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:46.568308Z","caller":"traceutil/trace.go:171","msg":"trace[397535921] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1339; }","duration":"286.952114ms","start":"2026-07-30T21:12:46.281339Z","end":"2026-07-30T21:12:46.568291Z","steps":["trace[397535921] 'agreement among raft nodes before linearized reading' (duration: 286.712849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:46.568613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:45.984903Z","time spent":"583.21623ms","remote":"127.0.0.1:35262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:12:46.679001Z","caller":"traceutil/trace.go:171","msg":"trace[458847892] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"105.690368ms","start":"2026-07-30T21:12:46.573282Z","end":"2026-07-30T21:12:46.678973Z","steps":["trace[458847892] 'process raft request' (duration: 100.165843ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:46.683958Z","caller":"traceutil/trace.go:171","msg":"trace[1252785148] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1382; }","duration":"107.246923ms","start":"2026-07-30T21:12:46.57669Z","end":"2026-07-30T21:12:46.683937Z","steps":["trace[1252785148] 'read index received' (duration: 96.777646ms)","trace[1252785148] 'applied index is now lower than readState.Index' (duration: 10.467397ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:12:46.683996Z","caller":"traceutil/trace.go:171","msg":"trace[2059537573] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"110.493557ms","start":"2026-07-30T21:12:46.573472Z","end":"2026-07-30T21:12:46.683966Z","steps":["trace[2059537573] 'process raft request' (duration: 110.361574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:46.684226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.513899ms","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-30T21:12:46.684283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.087377ms","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-30T21:12:46.684293Z","caller":"traceutil/trace.go:171","msg":"trace[1204307766] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1341; }","duration":"107.639503ms","start":"2026-07-30T21:12:46.576636Z","end":"2026-07-30T21:12:46.684276Z","steps":["trace[1204307766] 'agreement among raft nodes before linearized reading' (duration: 107.443768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:46.684212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.490328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-30T21:12:46.684327Z","caller":"traceutil/trace.go:171","msg":"trace[100138719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1341; }","duration":"106.144629ms","start":"2026-07-30T21:12:46.578172Z","end":"2026-07-30T21:12:46.684316Z","steps":["trace[100138719] 'agreement among raft nodes before linearized reading' (duration: 105.995465ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:46.684359Z","caller":"traceutil/trace.go:171","msg":"trace[234072117] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1341; }","duration":"107.702154ms","start":"2026-07-30T21:12:46.576637Z","end":"2026-07-30T21:12:46.684339Z","steps":["trace[234072117] 'agreement among raft nodes before linearized reading' (duration: 107.360786ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:48.494077Z","caller":"traceutil/trace.go:171","msg":"trace[1912812575] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"231.970832ms","start":"2026-07-30T21:12:48.262077Z","end":"2026-07-30T21:12:48.494048Z","steps":["trace[1912812575] 'process raft request' (duration: 229.207869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:48.903397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.235314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:12:48.903539Z","caller":"traceutil/trace.go:171","msg":"trace[1412056980] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1390; }","duration":"220.195166ms","start":"2026-07-30T21:12:48.683323Z","end":"2026-07-30T21:12:48.903518Z","steps":["trace[1412056980] 'read index received' (duration: 58.061µs)","trace[1412056980] 'applied index is now lower than readState.Index' (duration: 220.135475ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:12:48.903694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.488882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:12:48.903644Z","caller":"traceutil/trace.go:171","msg":"trace[1879271484] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"329.404465ms","start":"2026-07-30T21:12:48.574182Z","end":"2026-07-30T21:12:48.903586Z","steps":["trace[1879271484] 'process raft request' (duration: 105.878413ms)","trace[1879271484] 'compare' (duration: 223.109071ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:12:48.903741Z","caller":"traceutil/trace.go:171","msg":"trace[692745723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"108.551724ms","start":"2026-07-30T21:12:48.795175Z","end":"2026-07-30T21:12:48.903727Z","steps":["trace[692745723] 'agreement among raft nodes before linearized reading' (duration: 108.477742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:48.903777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.451263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T21:12:48.90382Z","caller":"traceutil/trace.go:171","msg":"trace[1737311283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1347; }","duration":"197.545015ms","start":"2026-07-30T21:12:48.706262Z","end":"2026-07-30T21:12:48.903807Z","steps":["trace[1737311283] 'agreement among raft nodes before linearized reading' (duration: 197.367131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:48.903817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.481083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-30T21:12:48.903887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:48.574143Z","time spent":"329.62258ms","remote":"127.0.0.1:35262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:12:48.903912Z","caller":"traceutil/trace.go:171","msg":"trace[212405238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1347; }","duration":"220.664887ms","start":"2026-07-30T21:12:48.683223Z","end":"2026-07-30T21:12:48.903887Z","steps":["trace[212405238] 'agreement among raft nodes before linearized reading' (duration: 220.363451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:48.90402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.510018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T21:12:48.904093Z","caller":"traceutil/trace.go:171","msg":"trace[1087700704] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1347; }","duration":"187.581119ms","start":"2026-07-30T21:12:48.716486Z","end":"2026-07-30T21:12:48.904068Z","steps":["trace[1087700704] 'agreement among raft nodes before linearized reading' (duration: 187.332984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:48.904092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.145062ms","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-30T21:12:48.904218Z","caller":"traceutil/trace.go:171","msg":"trace[1222295155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1347; }","duration":"188.330646ms","start":"2026-07-30T21:12:48.715852Z","end":"2026-07-30T21:12:48.904183Z","steps":["trace[1222295155] 'agreement among raft nodes before linearized reading' (duration: 187.955698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:49.564669Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568749633288237,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T21:12:49.57605Z","caller":"traceutil/trace.go:171","msg":"trace[672960259] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"557.043569ms","start":"2026-07-30T21:12:49.018963Z","end":"2026-07-30T21:12:49.576006Z","steps":["trace[672960259] 'process raft request' (duration: 556.831254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:49.576346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:49.018937Z","time spent":"557.220753ms","remote":"127.0.0.1:35096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:12:49.595844Z","caller":"traceutil/trace.go:171","msg":"trace[1255987086] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1395; }","duration":"532.015963ms","start":"2026-07-30T21:12:49.063789Z","end":"2026-07-30T21:12:49.595805Z","steps":["trace[1255987086] 'read index received' (duration: 511.96364ms)","trace[1255987086] 'applied index is now lower than readState.Index' (duration: 20.050963ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:12:49.596055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.253969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T21:12:49.596095Z","caller":"traceutil/trace.go:171","msg":"trace[1198545828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1353; }","duration":"532.372571ms","start":"2026-07-30T21:12:49.063712Z","end":"2026-07-30T21:12:49.596085Z","steps":["trace[1198545828] 'agreement among raft nodes before linearized reading' (duration: 532.210128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:49.596445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:49.06368Z","time spent":"532.447383ms","remote":"127.0.0.1:35262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T21:12:50.289427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568749633288245,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:12:51.049438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124104415s","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-30T21:12:51.049794Z","caller":"traceutil/trace.go:171","msg":"trace[1271298377] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1353; }","duration":"1.124474003s","start":"2026-07-30T21:12:49.925305Z","end":"2026-07-30T21:12:51.049779Z","steps":["trace[1271298377] 'range keys from in-memory index tree' (duration: 1.124089885s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:51.049922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.545621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:12:51.050217Z","caller":"traceutil/trace.go:171","msg":"trace[1522026589] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1397; }","duration":"1.261082179s","start":"2026-07-30T21:12:49.789116Z","end":"2026-07-30T21:12:51.050199Z","steps":["trace[1522026589] 'read index received' (duration: 626.182781ms)","trace[1522026589] 'applied index is now lower than readState.Index' (duration: 634.898018ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:12:51.050254Z","caller":"traceutil/trace.go:171","msg":"trace[1489590605] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"1.448699439s","start":"2026-07-30T21:12:49.601542Z","end":"2026-07-30T21:12:51.050241Z","steps":["trace[1489590605] 'process raft request' (duration: 1.448513975s)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:51.050297Z","caller":"traceutil/trace.go:171","msg":"trace[234740561] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"1.259522985s","start":"2026-07-30T21:12:49.790764Z","end":"2026-07-30T21:12:51.050287Z","steps":["trace[234740561] 'process raft request' (duration: 1.259394642s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:51.050361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:49.790729Z","time spent":"1.259594756s","remote":"127.0.0.1:35262","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-30T21:12:51.050374Z","caller":"traceutil/trace.go:171","msg":"trace[1658876445] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"1.449360034s","start":"2026-07-30T21:12:49.60099Z","end":"2026-07-30T21:12:51.05035Z","steps":["trace[1658876445] 'process raft request' (duration: 814.281591ms)","trace[1658876445] 'compare' (duration: 634.193393ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:12:51.050397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:49.60151Z","time spent":"1.448800692s","remote":"127.0.0.1:35262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:12:51.050487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:49.600979Z","time spent":"1.449446126s","remote":"127.0.0.1:35262","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-30T21:12:51.050543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261441638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:12:51.050572Z","caller":"traceutil/trace.go:171","msg":"trace[673441837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1356; }","duration":"1.261529179s","start":"2026-07-30T21:12:49.789034Z","end":"2026-07-30T21:12:51.050563Z","steps":["trace[673441837] 'agreement among raft nodes before linearized reading' (duration: 1.261482568s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:51.050606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:49.788999Z","time spent":"1.261600541s","remote":"127.0.0.1:34990","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-30T21:12:51.057768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.73891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:12:51.057728Z","caller":"traceutil/trace.go:171","msg":"trace[937917621] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"146.364877ms","start":"2026-07-30T21:12:50.911243Z","end":"2026-07-30T21:12:51.057608Z","steps":["trace[937917621] 'process raft request' (duration: 145.726323ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:51.057762Z","caller":"traceutil/trace.go:171","msg":"trace[1819263787] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"261.856559ms","start":"2026-07-30T21:12:50.795829Z","end":"2026-07-30T21:12:51.057686Z","steps":["trace[1819263787] 'process raft request' (duration: 261.07075ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:12:51.05781Z","caller":"traceutil/trace.go:171","msg":"trace[1741817165] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1358; }","duration":"817.835392ms","start":"2026-07-30T21:12:50.239965Z","end":"2026-07-30T21:12:51.0578Z","steps":["trace[1741817165] 'agreement among raft nodes before linearized reading' (duration: 817.74399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:51.057873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089695657s","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":"info","ts":"2026-07-30T21:12:51.057926Z","caller":"traceutil/trace.go:171","msg":"trace[643869835] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1358; }","duration":"1.089792648s","start":"2026-07-30T21:12:49.968125Z","end":"2026-07-30T21:12:51.057918Z","steps":["trace[643869835] 'agreement among raft nodes before linearized reading' (duration: 1.089718716s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:51.057949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:49.968104Z","time spent":"1.089838449s","remote":"127.0.0.1:59086","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-30T21:12:51.057947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:50.239942Z","time spent":"817.960646ms","remote":"127.0.0.1:52988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:12:52.913493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.772286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:12:52.913579Z","caller":"traceutil/trace.go:171","msg":"trace[1219800372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1371; }","duration":"407.906719ms","start":"2026-07-30T21:12:52.505653Z","end":"2026-07-30T21:12:52.913559Z","steps":["trace[1219800372] 'range keys from in-memory index tree' (duration: 407.164541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:52.913542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.01946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:12:52.913622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:12:52.505639Z","time spent":"407.97455ms","remote":"127.0.0.1:34990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T21:12:52.913672Z","caller":"traceutil/trace.go:171","msg":"trace[2132051501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1371; }","duration":"123.242826ms","start":"2026-07-30T21:12:52.790403Z","end":"2026-07-30T21:12:52.913646Z","steps":["trace[2132051501] 'range keys from in-memory index tree' (duration: 122.896698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:13:23.723543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.707976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:basic-user\" ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-07-30T21:13:23.72361Z","caller":"traceutil/trace.go:171","msg":"trace[1688546965] range","detail":"{range_begin:/registry/clusterrolebindings/system:basic-user; range_end:; response_count:1; response_revision:1587; }","duration":"100.829898ms","start":"2026-07-30T21:13:23.622767Z","end":"2026-07-30T21:13:23.723597Z","steps":["trace[1688546965] 'range keys from in-memory index tree' (duration: 100.626894ms)"],"step_count":1}