{"level":"warn","ts":"2026-07-21T20:51:02.69139Z","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-21T20:51:02.691489Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-21T20:51:02.691561Z","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-21T20:51:02.691573Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-07-21T20:51:02.691592Z","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-21T20:51:02.692199Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-07-21T20:51:02.692276Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-21T20:51:02.696929Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.433879ms"} {"level":"info","ts":"2026-07-21T20:51:02.704687Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-07-21T20:51:02.705038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T20:51:02.705276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-07-21T20:51:02.705411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T20:51:02.705545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-07-21T20:51:02.705782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-07-21T20:51:02.711557Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T20:51:02.712855Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T20:51:02.71409Z","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-21T20:51:02.716569Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T20:51:02.720399Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T20:51:02.720476Z","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-21T20:51:02.721269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-07-21T20:51:02.72157Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-07-21T20:51:02.720735Z","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-21T20:51:02.721918Z","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-21T20:51:02.722634Z","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-21T20:51:02.722811Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-07-21T20:51:02.722866Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-07-21T20:51:02.723206Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T20:51:02.723278Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T20:51:03.106848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T20:51:03.106918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T20:51:03.106941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-07-21T20:51:03.106953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-07-21T20:51:03.106958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-07-21T20:51:03.106967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-07-21T20:51:03.106974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-07-21T20:51:03.110112Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:51:03.112504Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T20:51:03.112705Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T20:51:03.11277Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T20:51:03.113039Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:51:03.112947Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T20:51:03.11319Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T20:51:03.113345Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:51:03.113378Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:51:03.115754Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-07-21T20:51:03.116131Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T20:51:11.810731Z","caller":"traceutil/trace.go:171","msg":"trace[930166226] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"113.513193ms","start":"2026-07-21T20:51:11.697195Z","end":"2026-07-21T20:51:11.810709Z","steps":["trace[930166226] 'process raft request' (duration: 108.077413ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:11.825744Z","caller":"traceutil/trace.go:171","msg":"trace[955445647] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"124.779628ms","start":"2026-07-21T20:51:11.700949Z","end":"2026-07-21T20:51:11.825728Z","steps":["trace[955445647] 'process raft request' (duration: 123.708278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:12.063541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.296816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:51:12.064645Z","caller":"traceutil/trace.go:171","msg":"trace[2089568925] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:358; }","duration":"184.305661ms","start":"2026-07-21T20:51:11.880279Z","end":"2026-07-21T20:51:12.064585Z","steps":["trace[2089568925] 'read index received' (duration: 2.883496ms)","trace[2089568925] 'applied index is now lower than readState.Index' (duration: 181.416325ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:51:12.064654Z","caller":"traceutil/trace.go:171","msg":"trace[992184136] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"222.07247ms","start":"2026-07-21T20:51:11.842551Z","end":"2026-07-21T20:51:12.064623Z","steps":["trace[992184136] 'process raft request' (duration: 83.899205ms)","trace[992184136] 'compare' (duration: 136.098319ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:51:12.064878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.536269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-21T20:51:12.065005Z","caller":"traceutil/trace.go:171","msg":"trace[1104075259] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:349; }","duration":"184.715367ms","start":"2026-07-21T20:51:11.880268Z","end":"2026-07-21T20:51:12.064983Z","steps":["trace[1104075259] 'agreement among raft nodes before linearized reading' (duration: 184.457447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:12.065063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.687717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-21T20:51:12.065352Z","caller":"traceutil/trace.go:171","msg":"trace[1867012524] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:349; }","duration":"135.05077ms","start":"2026-07-21T20:51:11.930284Z","end":"2026-07-21T20:51:12.065335Z","steps":["trace[1867012524] 'agreement among raft nodes before linearized reading' (duration: 134.678147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:12.422506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.293442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:51:12.422766Z","caller":"traceutil/trace.go:171","msg":"trace[1257422461] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"291.927127ms","start":"2026-07-21T20:51:12.130786Z","end":"2026-07-21T20:51:12.422713Z","steps":["trace[1257422461] 'process raft request' (duration: 41.329763ms)","trace[1257422461] 'compare' (duration: 250.101956ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:51:12.422859Z","caller":"traceutil/trace.go:171","msg":"trace[263588142] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:362; }","duration":"291.73327ms","start":"2026-07-21T20:51:12.131105Z","end":"2026-07-21T20:51:12.422838Z","steps":["trace[263588142] 'read index received' (duration: 14.29003ms)","trace[263588142] 'applied index is now lower than readState.Index' (duration: 277.44208ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:51:12.422912Z","caller":"traceutil/trace.go:171","msg":"trace[151962396] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"292.061711ms","start":"2026-07-21T20:51:12.130838Z","end":"2026-07-21T20:51:12.422899Z","steps":["trace[151962396] 'process raft request' (duration: 291.797242ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:12.422956Z","caller":"traceutil/trace.go:171","msg":"trace[1754954908] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"291.894065ms","start":"2026-07-21T20:51:12.131031Z","end":"2026-07-21T20:51:12.422925Z","steps":["trace[1754954908] 'process raft request' (duration: 291.674167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:12.423051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.7178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-07-21T20:51:12.423083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.948338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-07-21T20:51:12.423182Z","caller":"traceutil/trace.go:171","msg":"trace[1633303875] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:355; }","duration":"292.104863ms","start":"2026-07-21T20:51:12.131056Z","end":"2026-07-21T20:51:12.423161Z","steps":["trace[1633303875] 'agreement among raft nodes before linearized reading' (duration: 291.877254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:12.423277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.257997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-21T20:51:12.423089Z","caller":"traceutil/trace.go:171","msg":"trace[1502058379] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:355; }","duration":"241.801274ms","start":"2026-07-21T20:51:12.181276Z","end":"2026-07-21T20:51:12.423077Z","steps":["trace[1502058379] 'agreement among raft nodes before linearized reading' (duration: 241.6904ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:12.423335Z","caller":"traceutil/trace.go:171","msg":"trace[155393269] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:355; }","duration":"141.3575ms","start":"2026-07-21T20:51:12.281964Z","end":"2026-07-21T20:51:12.423321Z","steps":["trace[155393269] 'agreement among raft nodes before linearized reading' (duration: 141.260547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:12.423414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.490859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-21T20:51:12.423449Z","caller":"traceutil/trace.go:171","msg":"trace[1321997352] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:355; }","duration":"192.562153ms","start":"2026-07-21T20:51:12.230876Z","end":"2026-07-21T20:51:12.423438Z","steps":["trace[1321997352] 'agreement among raft nodes before linearized reading' (duration: 192.471739ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:12.534299Z","caller":"traceutil/trace.go:171","msg":"trace[999005569] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"103.932009ms","start":"2026-07-21T20:51:12.430334Z","end":"2026-07-21T20:51:12.534266Z","steps":["trace[999005569] 'process raft request' (duration: 97.189864ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:12.534475Z","caller":"traceutil/trace.go:171","msg":"trace[1556458590] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"103.11068ms","start":"2026-07-21T20:51:12.43134Z","end":"2026-07-21T20:51:12.53445Z","steps":["trace[1556458590] 'process raft request' (duration: 102.809819ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:12.534786Z","caller":"traceutil/trace.go:171","msg":"trace[442908916] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"103.828826ms","start":"2026-07-21T20:51:12.430606Z","end":"2026-07-21T20:51:12.534435Z","steps":["trace[442908916] 'process raft request' (duration: 103.482743ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:12.534991Z","caller":"traceutil/trace.go:171","msg":"trace[1059628040] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"103.585927ms","start":"2026-07-21T20:51:12.431387Z","end":"2026-07-21T20:51:12.534973Z","steps":["trace[1059628040] 'process raft request' (duration: 102.793918ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:12.535336Z","caller":"traceutil/trace.go:171","msg":"trace[260328225] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"103.349918ms","start":"2026-07-21T20:51:12.431969Z","end":"2026-07-21T20:51:12.535318Z","steps":["trace[260328225] 'process raft request' (duration: 102.590031ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:12.535432Z","caller":"traceutil/trace.go:171","msg":"trace[475995370] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"103.682611ms","start":"2026-07-21T20:51:12.431733Z","end":"2026-07-21T20:51:12.535416Z","steps":["trace[475995370] 'process raft request' (duration: 102.482438ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:12.536049Z","caller":"traceutil/trace.go:171","msg":"trace[1152364532] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"102.261019ms","start":"2026-07-21T20:51:12.433566Z","end":"2026-07-21T20:51:12.535827Z","steps":["trace[1152364532] 'process raft request' (duration: 101.156429ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:12.537499Z","caller":"traceutil/trace.go:171","msg":"trace[968709078] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"102.810799ms","start":"2026-07-21T20:51:12.434666Z","end":"2026-07-21T20:51:12.537477Z","steps":["trace[968709078] 'process raft request' (duration: 102.742347ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:12.537774Z","caller":"traceutil/trace.go:171","msg":"trace[1567262007] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"103.935009ms","start":"2026-07-21T20:51:12.433828Z","end":"2026-07-21T20:51:12.537763Z","steps":["trace[1567262007] 'process raft request' (duration: 101.026784ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:26.344653Z","caller":"traceutil/trace.go:171","msg":"trace[1557271970] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"126.312153ms","start":"2026-07-21T20:51:26.218311Z","end":"2026-07-21T20:51:26.344623Z","steps":["trace[1557271970] 'process raft request' (duration: 96.399392ms)","trace[1557271970] 'compare' (duration: 29.776657ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:51:28.001759Z","caller":"traceutil/trace.go:171","msg":"trace[8620937] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"719.75168ms","start":"2026-07-21T20:51:27.281979Z","end":"2026-07-21T20:51:28.001731Z","steps":["trace[8620937] 'process raft request' (duration: 719.540284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:28.002061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:27.281967Z","time spent":"719.912626ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:51:35.371467Z","caller":"traceutil/trace.go:171","msg":"trace[948185777] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"112.82073ms","start":"2026-07-21T20:51:35.258623Z","end":"2026-07-21T20:51:35.371443Z","steps":["trace[948185777] 'process raft request' (duration: 110.968547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:36.432872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.978822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T20:51:36.432963Z","caller":"traceutil/trace.go:171","msg":"trace[1836784877] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:455; }","duration":"252.077825ms","start":"2026-07-21T20:51:36.180864Z","end":"2026-07-21T20:51:36.432942Z","steps":["trace[1836784877] 'range keys from in-memory index tree' (duration: 251.89775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:36.432982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.516485ms","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-21T20:51:36.433016Z","caller":"traceutil/trace.go:171","msg":"trace[200188452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:455; }","duration":"226.589047ms","start":"2026-07-21T20:51:36.206418Z","end":"2026-07-21T20:51:36.433007Z","steps":["trace[200188452] 'range keys from in-memory index tree' (duration: 226.429953ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:37.354368Z","caller":"traceutil/trace.go:171","msg":"trace[1294065478] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"111.139063ms","start":"2026-07-21T20:51:37.243211Z","end":"2026-07-21T20:51:37.35435Z","steps":["trace[1294065478] 'process raft request' (duration: 111.04042ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:37.528576Z","caller":"traceutil/trace.go:171","msg":"trace[1283625491] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"272.307469ms","start":"2026-07-21T20:51:37.256207Z","end":"2026-07-21T20:51:37.528514Z","steps":["trace[1283625491] 'process raft request' (duration: 246.034436ms)","trace[1283625491] 'compare' (duration: 26.16732ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:51:37.884553Z","caller":"traceutil/trace.go:171","msg":"trace[1607836050] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"346.57051ms","start":"2026-07-21T20:51:37.537953Z","end":"2026-07-21T20:51:37.884524Z","steps":["trace[1607836050] 'process raft request' (duration: 268.544973ms)","trace[1607836050] 'compare' (duration: 77.905743ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:51:37.884685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:37.537931Z","time spent":"346.697534ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:51:38.198683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.107837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:51:38.198794Z","caller":"traceutil/trace.go:171","msg":"trace[473631050] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"304.748836ms","start":"2026-07-21T20:51:37.894026Z","end":"2026-07-21T20:51:38.198775Z","steps":["trace[473631050] 'process raft request' (duration: 110.472824ms)","trace[473631050] 'compare' (duration: 193.945013ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:51:38.198857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:37.89401Z","time spent":"304.819048ms","remote":"127.0.0.1:48464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:51:38.20296Z","caller":"traceutil/trace.go:171","msg":"trace[784272067] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"100.504016ms","start":"2026-07-21T20:51:38.10244Z","end":"2026-07-21T20:51:38.202944Z","steps":["trace[784272067] 'read index received' (duration: 100.360272ms)","trace[784272067] 'applied index is now lower than readState.Index' (duration: 143.074µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:51:38.203043Z","caller":"traceutil/trace.go:171","msg":"trace[1510244661] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"282.877947ms","start":"2026-07-21T20:51:37.920148Z","end":"2026-07-21T20:51:38.203026Z","steps":["trace[1510244661] 'process raft request' (duration: 282.707832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:38.203118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.670261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-21T20:51:38.203155Z","caller":"traceutil/trace.go:171","msg":"trace[649244834] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:463; }","duration":"100.769723ms","start":"2026-07-21T20:51:38.102376Z","end":"2026-07-21T20:51:38.203145Z","steps":["trace[649244834] 'agreement among raft nodes before linearized reading' (duration: 100.669721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:38.734529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727773851956752,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T20:51:38.869744Z","caller":"traceutil/trace.go:171","msg":"trace[1912611247] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"660.915335ms","start":"2026-07-21T20:51:38.208805Z","end":"2026-07-21T20:51:38.86972Z","steps":["trace[1912611247] 'process raft request' (duration: 651.173171ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:38.870044Z","caller":"traceutil/trace.go:171","msg":"trace[1138117062] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"661.066369ms","start":"2026-07-21T20:51:38.208952Z","end":"2026-07-21T20:51:38.870018Z","steps":["trace[1138117062] 'process raft request' (duration: 660.378849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:38.870195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:38.208944Z","time spent":"661.182942ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:51:38.870512Z","caller":"traceutil/trace.go:171","msg":"trace[930365174] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"469.453952ms","start":"2026-07-21T20:51:38.401053Z","end":"2026-07-21T20:51:38.870507Z","steps":["trace[930365174] 'process raft request' (duration: 469.218965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:38.870602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:38.401034Z","time spent":"469.507264ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:51:38.870631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:38.208791Z","time spent":"661.028569ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:51:38.870237Z","caller":"traceutil/trace.go:171","msg":"trace[228785175] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"661.181183ms","start":"2026-07-21T20:51:38.209042Z","end":"2026-07-21T20:51:38.870223Z","steps":["trace[228785175] 'process raft request' (duration: 660.907665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:38.870807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:38.207742Z","time spent":"663.044885ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:51:38.870255Z","caller":"traceutil/trace.go:171","msg":"trace[1330802764] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"661.027918ms","start":"2026-07-21T20:51:38.209216Z","end":"2026-07-21T20:51:38.870244Z","steps":["trace[1330802764] 'process raft request' (duration: 660.906325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:38.870887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:38.209202Z","time spent":"661.660816ms","remote":"127.0.0.1:59606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:51:38.870277Z","caller":"traceutil/trace.go:171","msg":"trace[206312610] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"663.383564ms","start":"2026-07-21T20:51:38.206887Z","end":"2026-07-21T20:51:38.870271Z","steps":["trace[206312610] 'process raft request' (duration: 663.309932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:38.87093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:38.206865Z","time spent":"664.050473ms","remote":"127.0.0.1:59606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:51:38.870306Z","caller":"traceutil/trace.go:171","msg":"trace[1424654317] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"636.145171ms","start":"2026-07-21T20:51:38.234155Z","end":"2026-07-21T20:51:38.870301Z","steps":["trace[1424654317] 'process raft request' (duration: 636.09666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:38.87099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:38.234142Z","time spent":"636.83692ms","remote":"127.0.0.1:59606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:51:38.870327Z","caller":"traceutil/trace.go:171","msg":"trace[984835431] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"659.383393ms","start":"2026-07-21T20:51:38.210926Z","end":"2026-07-21T20:51:38.87031Z","steps":["trace[984835431] 'process raft request' (duration: 659.290041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:38.871082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:38.210905Z","time spent":"660.143264ms","remote":"127.0.0.1:59688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:51:38.870384Z","caller":"traceutil/trace.go:171","msg":"trace[112367432] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:481; }","duration":"636.84207ms","start":"2026-07-21T20:51:38.233535Z","end":"2026-07-21T20:51:38.870377Z","steps":["trace[112367432] 'read index received' (duration: 626.443849ms)","trace[112367432] 'applied index is now lower than readState.Index' (duration: 10.397641ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:51:38.870491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.942503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-znd5n\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-07-21T20:51:38.871169Z","caller":"traceutil/trace.go:171","msg":"trace[1909178113] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-znd5n; range_end:; response_count:1; response_revision:472; }","duration":"637.666114ms","start":"2026-07-21T20:51:38.233492Z","end":"2026-07-21T20:51:38.871158Z","steps":["trace[1909178113] 'agreement among raft nodes before linearized reading' (duration: 636.900623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:38.87119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.539465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-07-21T20:51:38.871208Z","caller":"traceutil/trace.go:171","msg":"trace[721748112] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:472; }","duration":"634.572927ms","start":"2026-07-21T20:51:38.236629Z","end":"2026-07-21T20:51:38.871202Z","steps":["trace[721748112] 'agreement among raft nodes before linearized reading' (duration: 634.537066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:38.871202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:38.233463Z","time spent":"637.729206ms","remote":"127.0.0.1:59688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4040,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-znd5n\" "} {"level":"warn","ts":"2026-07-21T20:51:38.871236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:38.236611Z","time spent":"634.614918ms","remote":"127.0.0.1:48464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4219,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-07-21T20:51:38.871302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.571786ms","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-21T20:51:38.871328Z","caller":"traceutil/trace.go:171","msg":"trace[923980118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:472; }","duration":"383.634007ms","start":"2026-07-21T20:51:38.487689Z","end":"2026-07-21T20:51:38.871323Z","steps":["trace[923980118] 'agreement among raft nodes before linearized reading' (duration: 383.584896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:38.871346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:38.487669Z","time spent":"383.672228ms","remote":"127.0.0.1:48246","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-21T20:51:46.512823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:46.182923Z","time spent":"329.897287ms","remote":"127.0.0.1:59576","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-21T20:51:52.672076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727773851956925,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T20:51:52.852481Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.149545872s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T20:51:52.852841Z","caller":"traceutil/trace.go:171","msg":"trace[1009820767] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.149972262s","start":"2026-07-21T20:51:51.702848Z","end":"2026-07-21T20:51:52.85282Z","steps":["trace[1009820767] 'process raft request' (duration: 1.149854319s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:52.85295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:51.702832Z","time spent":"1.150049335s","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:51:52.865354Z","caller":"traceutil/trace.go:171","msg":"trace[868996514] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.14471554s","start":"2026-07-21T20:51:51.720588Z","end":"2026-07-21T20:51:52.865304Z","steps":["trace[868996514] 'process raft request' (duration: 1.144453932s)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:52.865401Z","caller":"traceutil/trace.go:171","msg":"trace[418294837] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.137702855s","start":"2026-07-21T20:51:51.727677Z","end":"2026-07-21T20:51:52.86538Z","steps":["trace[418294837] 'process raft request' (duration: 1.137536491s)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:52.865477Z","caller":"traceutil/trace.go:171","msg":"trace[1672395329] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.132532576s","start":"2026-07-21T20:51:51.732928Z","end":"2026-07-21T20:51:52.865461Z","steps":["trace[1672395329] 'process raft request' (duration: 1.132366802s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:52.865496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:51.727669Z","time spent":"1.137787698s","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:51:52.86551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:51.720569Z","time spent":"1.144879463s","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:51:52.865529Z","caller":"traceutil/trace.go:171","msg":"trace[1667108617] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.132177047s","start":"2026-07-21T20:51:51.733338Z","end":"2026-07-21T20:51:52.865515Z","steps":["trace[1667108617] 'process raft request' (duration: 1.131982132s)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:52.865528Z","caller":"traceutil/trace.go:171","msg":"trace[1502941280] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.141265174s","start":"2026-07-21T20:51:51.724243Z","end":"2026-07-21T20:51:52.865508Z","steps":["trace[1502941280] 'process raft request' (duration: 1.140930446s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:52.865546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:51.732921Z","time spent":"1.132592077s","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:51:52.86557Z","caller":"traceutil/trace.go:171","msg":"trace[205630217] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.135548411s","start":"2026-07-21T20:51:51.730014Z","end":"2026-07-21T20:51:52.865563Z","steps":["trace[205630217] 'process raft request' (duration: 1.135224823s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:52.86558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:51.733332Z","time spent":"1.132226488s","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:51:52.865603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:51.730006Z","time spent":"1.135581652s","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:51:52.865604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:51.724236Z","time spent":"1.141338485s","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:51:52.86564Z","caller":"traceutil/trace.go:171","msg":"trace[835268568] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.126588697s","start":"2026-07-21T20:51:51.739034Z","end":"2026-07-21T20:51:52.865623Z","steps":["trace[835268568] 'process raft request' (duration: 1.126342111s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:52.865675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:51.73901Z","time spent":"1.126649199s","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:51:52.865704Z","caller":"traceutil/trace.go:171","msg":"trace[1415016311] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.115430037s","start":"2026-07-21T20:51:51.750266Z","end":"2026-07-21T20:51:52.865696Z","steps":["trace[1415016311] 'process raft request' (duration: 1.11514247s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:52.865742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:51.750246Z","time spent":"1.115478789s","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:51:52.865863Z","caller":"traceutil/trace.go:171","msg":"trace[1473677006] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.113691595s","start":"2026-07-21T20:51:51.752166Z","end":"2026-07-21T20:51:52.865857Z","steps":["trace[1473677006] 'process raft request' (duration: 1.11348924s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:52.8659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:51.752151Z","time spent":"1.113734006s","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:51:52.865953Z","caller":"traceutil/trace.go:171","msg":"trace[1666481392] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.100972128s","start":"2026-07-21T20:51:51.764975Z","end":"2026-07-21T20:51:52.865947Z","steps":["trace[1666481392] 'process raft request' (duration: 1.100794643s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:52.86599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:51.764947Z","time spent":"1.101028808s","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:51:52.878247Z","caller":"traceutil/trace.go:171","msg":"trace[547462038] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.011399729s","start":"2026-07-21T20:51:51.866831Z","end":"2026-07-21T20:51:52.878231Z","steps":["trace[547462038] 'process raft request' (duration: 1.010942008s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:52.878362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:51.866812Z","time spent":"1.011481942s","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:51:52.878619Z","caller":"traceutil/trace.go:171","msg":"trace[251958142] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:575; }","duration":"706.985497ms","start":"2026-07-21T20:51:52.17157Z","end":"2026-07-21T20:51:52.878556Z","steps":["trace[251958142] 'read index received' (duration: 681.189459ms)","trace[251958142] 'applied index is now lower than readState.Index' (duration: 25.794998ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:51:52.878754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.161371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:51:52.878781Z","caller":"traceutil/trace.go:171","msg":"trace[905869814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"707.243434ms","start":"2026-07-21T20:51:52.17153Z","end":"2026-07-21T20:51:52.878774Z","steps":["trace[905869814] 'agreement among raft nodes before linearized reading' (duration: 707.11025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:52.87882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:52.171512Z","time spent":"707.282384ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T20:51:54.015403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.364972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:51:54.015487Z","caller":"traceutil/trace.go:171","msg":"trace[537161724] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"524.756225ms","start":"2026-07-21T20:51:53.490717Z","end":"2026-07-21T20:51:54.015473Z","steps":["trace[537161724] 'process raft request' (duration: 221.250639ms)","trace[537161724] 'compare' (duration: 303.208869ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:51:54.015528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:53.490688Z","time spent":"524.821397ms","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:51:54.016589Z","caller":"traceutil/trace.go:171","msg":"trace[628090913] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"425.759356ms","start":"2026-07-21T20:51:53.590822Z","end":"2026-07-21T20:51:54.016582Z","steps":["trace[628090913] 'process raft request' (duration: 425.252623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:54.016638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:53.59079Z","time spent":"425.827377ms","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:51:54.575801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.350298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:51:54.575896Z","caller":"traceutil/trace.go:171","msg":"trace[1502969750] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"464.848283ms","start":"2026-07-21T20:51:54.111034Z","end":"2026-07-21T20:51:54.575882Z","steps":["trace[1502969750] 'process raft request' (duration: 245.323431ms)","trace[1502969750] 'compare' (duration: 219.206614ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:51:54.575953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:54.111015Z","time spent":"464.916885ms","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20851,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:51:54.577972Z","caller":"traceutil/trace.go:171","msg":"trace[1055232783] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"459.084973ms","start":"2026-07-21T20:51:54.118874Z","end":"2026-07-21T20:51:54.577959Z","steps":["trace[1055232783] 'process raft request' (duration: 458.777485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:54.578046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:54.118855Z","time spent":"459.155275ms","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:51:54.579794Z","caller":"traceutil/trace.go:171","msg":"trace[1195248109] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"408.47143ms","start":"2026-07-21T20:51:54.170764Z","end":"2026-07-21T20:51:54.579236Z","steps":["trace[1195248109] 'read index received' (duration: 185.622748ms)","trace[1195248109] 'applied index is now lower than readState.Index' (duration: 222.847792ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:51:54.580384Z","caller":"traceutil/trace.go:171","msg":"trace[2045038429] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"368.088529ms","start":"2026-07-21T20:51:54.212017Z","end":"2026-07-21T20:51:54.580106Z","steps":["trace[2045038429] 'process raft request' (duration: 366.461519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:54.58064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:54.211983Z","time spent":"368.501498ms","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:51:54.5867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.918492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:51:54.586858Z","caller":"traceutil/trace.go:171","msg":"trace[1839008053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"416.112267ms","start":"2026-07-21T20:51:54.170722Z","end":"2026-07-21T20:51:54.586835Z","steps":["trace[1839008053] 'agreement among raft nodes before linearized reading' (duration: 408.612374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:54.586959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:54.170702Z","time spent":"416.24508ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T20:51:55.078559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.778338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:51:55.078701Z","caller":"traceutil/trace.go:171","msg":"trace[112449224] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"460.825621ms","start":"2026-07-21T20:51:54.617847Z","end":"2026-07-21T20:51:55.078672Z","steps":["trace[112449224] 'process raft request' (duration: 149.855648ms)","trace[112449224] 'compare' (duration: 310.667566ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:51:55.078763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:54.617824Z","time spent":"460.909732ms","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:51:55.083721Z","caller":"traceutil/trace.go:171","msg":"trace[1093919764] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"337.819325ms","start":"2026-07-21T20:51:54.745879Z","end":"2026-07-21T20:51:55.083699Z","steps":["trace[1093919764] 'process raft request' (duration: 337.663131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:55.083862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:54.745852Z","time spent":"337.936098ms","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:51:55.118716Z","caller":"traceutil/trace.go:171","msg":"trace[922474234] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"118.989433ms","start":"2026-07-21T20:51:54.999709Z","end":"2026-07-21T20:51:55.118698Z","steps":["trace[922474234] 'read index received' (duration: 83.735302ms)","trace[922474234] 'applied index is now lower than readState.Index' (duration: 35.253241ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:51:55.119388Z","caller":"traceutil/trace.go:171","msg":"trace[1448213089] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"338.878074ms","start":"2026-07-21T20:51:54.780501Z","end":"2026-07-21T20:51:55.119379Z","steps":["trace[1448213089] 'process raft request' (duration: 337.821859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:55.119501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:54.780478Z","time spent":"338.940095ms","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:51:55.119725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.022668ms","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-21T20:51:55.119769Z","caller":"traceutil/trace.go:171","msg":"trace[705293190] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"120.10692ms","start":"2026-07-21T20:51:54.999654Z","end":"2026-07-21T20:51:55.119761Z","steps":["trace[705293190] 'agreement among raft nodes before linearized reading' (duration: 120.001168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:55.627164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.007541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:51:55.627232Z","caller":"traceutil/trace.go:171","msg":"trace[1641857900] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"476.570513ms","start":"2026-07-21T20:51:55.15065Z","end":"2026-07-21T20:51:55.627221Z","steps":["trace[1641857900] 'read index received' (duration: 164.555532ms)","trace[1641857900] 'applied index is now lower than readState.Index' (duration: 312.014301ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:51:55.627273Z","caller":"traceutil/trace.go:171","msg":"trace[899779663] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"486.189855ms","start":"2026-07-21T20:51:55.141076Z","end":"2026-07-21T20:51:55.627266Z","steps":["trace[899779663] 'process raft request' (duration: 174.03063ms)","trace[899779663] 'compare' (duration: 311.926149ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:51:55.627312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:55.141065Z","time spent":"486.221236ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:51:55.627558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.903092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:59631"} {"level":"info","ts":"2026-07-21T20:51:55.627581Z","caller":"traceutil/trace.go:171","msg":"trace[743033507] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:633; }","duration":"476.962723ms","start":"2026-07-21T20:51:55.15061Z","end":"2026-07-21T20:51:55.627573Z","steps":["trace[743033507] 'agreement among raft nodes before linearized reading' (duration: 476.84323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:55.627601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:55.150565Z","time spent":"477.030514ms","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":59655,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-07-21T20:51:55.639148Z","caller":"traceutil/trace.go:171","msg":"trace[823208785] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"472.997697ms","start":"2026-07-21T20:51:55.16612Z","end":"2026-07-21T20:51:55.639118Z","steps":["trace[823208785] 'process raft request' (duration: 472.833303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:55.63929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.542238ms","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-21T20:51:55.639328Z","caller":"traceutil/trace.go:171","msg":"trace[2048088133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"185.63083ms","start":"2026-07-21T20:51:55.453688Z","end":"2026-07-21T20:51:55.639319Z","steps":["trace[2048088133] 'agreement among raft nodes before linearized reading' (duration: 185.511578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:55.639308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:55.166102Z","time spent":"473.12507ms","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:51:55.639468Z","caller":"traceutil/trace.go:171","msg":"trace[1467551183] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"437.05133ms","start":"2026-07-21T20:51:55.20241Z","end":"2026-07-21T20:51:55.639461Z","steps":["trace[1467551183] 'process raft request' (duration: 436.681591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:55.639509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:55.202388Z","time spent":"437.09553ms","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:52:00.01673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.036837ms","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-21T20:52:00.016928Z","caller":"traceutil/trace.go:171","msg":"trace[1496555876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:659; }","duration":"377.296052ms","start":"2026-07-21T20:51:59.639613Z","end":"2026-07-21T20:52:00.016909Z","steps":["trace[1496555876] 'range keys from in-memory index tree' (duration: 376.865123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:00.01701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:59.639597Z","time spent":"377.402585ms","remote":"127.0.0.1:48246","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-21T20:52:00.041578Z","caller":"traceutil/trace.go:171","msg":"trace[197920072] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"367.427408ms","start":"2026-07-21T20:51:59.674131Z","end":"2026-07-21T20:52:00.041558Z","steps":["trace[197920072] 'read index received' (duration: 365.76687ms)","trace[197920072] 'applied index is now lower than readState.Index' (duration: 1.659938ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:52:00.04209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.944319ms","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-21T20:52:00.042241Z","caller":"traceutil/trace.go:171","msg":"trace[270854793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"368.150464ms","start":"2026-07-21T20:51:59.674064Z","end":"2026-07-21T20:52:00.042214Z","steps":["trace[270854793] 'agreement among raft nodes before linearized reading' (duration: 367.583441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:00.042287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:59.674033Z","time spent":"368.243467ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-21T20:52:00.042454Z","caller":"traceutil/trace.go:171","msg":"trace[211352106] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"401.698369ms","start":"2026-07-21T20:51:59.640688Z","end":"2026-07-21T20:52:00.042386Z","steps":["trace[211352106] 'process raft request' (duration: 399.785375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:00.043786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:59.640655Z","time spent":"402.009877ms","remote":"127.0.0.1:59616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:52:01.132274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.811101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:52:01.132894Z","caller":"traceutil/trace.go:171","msg":"trace[107773503] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"1.091206411s","start":"2026-07-21T20:52:00.04163Z","end":"2026-07-21T20:52:01.132837Z","steps":["trace[107773503] 'read index received' (duration: 260.626422ms)","trace[107773503] 'applied index is now lower than readState.Index' (duration: 830.577629ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:01.13296Z","caller":"traceutil/trace.go:171","msg":"trace[1340670451] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.112107575s","start":"2026-07-21T20:52:00.020812Z","end":"2026-07-21T20:52:01.13292Z","steps":["trace[1340670451] 'process raft request' (duration: 281.430064ms)","trace[1340670451] 'compare' (duration: 829.593396ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:52:01.133163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:00.020782Z","time spent":"1.112278328s","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:52:01.133198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170545226s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nhq8b\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-21T20:52:01.133277Z","caller":"traceutil/trace.go:171","msg":"trace[1751048241] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nhq8b; range_end:; response_count:1; response_revision:661; }","duration":"1.170721801s","start":"2026-07-21T20:51:59.962539Z","end":"2026-07-21T20:52:01.133261Z","steps":["trace[1751048241] 'agreement among raft nodes before linearized reading' (duration: 1.170443795s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:01.133474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:59.962525Z","time spent":"1.170930615s","remote":"127.0.0.1:59688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-nhq8b\" "} {"level":"info","ts":"2026-07-21T20:52:01.227766Z","caller":"traceutil/trace.go:171","msg":"trace[51593066] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"1.126694077s","start":"2026-07-21T20:52:00.10105Z","end":"2026-07-21T20:52:01.227744Z","steps":["trace[51593066] 'process raft request' (duration: 1.126646576s)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:01.2278Z","caller":"traceutil/trace.go:171","msg":"trace[1105403220] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.180169016s","start":"2026-07-21T20:52:00.047608Z","end":"2026-07-21T20:52:01.227777Z","steps":["trace[1105403220] 'process raft request' (duration: 1.179968372s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:01.227894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:00.101005Z","time spent":"1.12682889s","remote":"127.0.0.1:48326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:52:01.227941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:00.047589Z","time spent":"1.180279879s","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:52:01.980846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.228112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:52:01.981015Z","caller":"traceutil/trace.go:171","msg":"trace[259753792] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"834.139065ms","start":"2026-07-21T20:52:01.146834Z","end":"2026-07-21T20:52:01.980973Z","steps":["trace[259753792] 'process raft request' (duration: 664.720758ms)","trace[259753792] 'compare' (duration: 169.124649ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:01.98115Z","caller":"traceutil/trace.go:171","msg":"trace[359930034] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"822.369914ms","start":"2026-07-21T20:52:01.15876Z","end":"2026-07-21T20:52:01.98113Z","steps":["trace[359930034] 'read index received' (duration: 68.996068ms)","trace[359930034] 'applied index is now lower than readState.Index' (duration: 753.372916ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:52:01.981197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:01.146816Z","time spent":"834.280008ms","remote":"127.0.0.1:59688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:52:02.013648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.767237ms","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-21T20:52:02.013727Z","caller":"traceutil/trace.go:171","msg":"trace[516297237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:664; }","duration":"854.872969ms","start":"2026-07-21T20:52:01.158837Z","end":"2026-07-21T20:52:02.01371Z","steps":["trace[516297237] 'agreement among raft nodes before linearized reading' (duration: 854.681835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:02.013767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:01.15883Z","time spent":"854.92803ms","remote":"127.0.0.1:48246","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-21T20:52:02.013678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.90477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-21T20:52:02.013768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.320393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:52:02.013786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.371891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:52:02.013815Z","caller":"traceutil/trace.go:171","msg":"trace[650072097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:664; }","duration":"855.069993ms","start":"2026-07-21T20:52:01.158728Z","end":"2026-07-21T20:52:02.013797Z","steps":["trace[650072097] 'agreement among raft nodes before linearized reading' (duration: 822.488576ms)","trace[650072097] 'range keys from in-memory index tree' (duration: 32.285601ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:02.013829Z","caller":"traceutil/trace.go:171","msg":"trace[483838358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"843.428265ms","start":"2026-07-21T20:52:01.170383Z","end":"2026-07-21T20:52:02.013811Z","steps":["trace[483838358] 'agreement among raft nodes before linearized reading' (duration: 843.316173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:02.013865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:01.170362Z","time spent":"843.497097ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T20:52:02.013864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:01.158715Z","time spent":"855.140235ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-21T20:52:02.01387Z","caller":"traceutil/trace.go:171","msg":"trace[1293268842] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:664; }","duration":"775.471403ms","start":"2026-07-21T20:52:01.238377Z","end":"2026-07-21T20:52:02.013848Z","steps":["trace[1293268842] 'agreement among raft nodes before linearized reading' (duration: 775.292039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:02.013919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:01.238315Z","time spent":"775.593856ms","remote":"127.0.0.1:48332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":29,"request content":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"warn","ts":"2026-07-21T20:52:03.638417Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727773851957118,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T20:52:04.139467Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727773851957118,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T20:52:04.640524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727773851957118,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T20:52:04.87717Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.451105739s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T20:52:04.878063Z","caller":"traceutil/trace.go:171","msg":"trace[717844660] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"1.739694358s","start":"2026-07-21T20:52:03.138346Z","end":"2026-07-21T20:52:04.878041Z","steps":["trace[717844660] 'read index received' (duration: 1.738960701s)","trace[717844660] 'applied index is now lower than readState.Index' (duration: 732.747µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:04.87815Z","caller":"traceutil/trace.go:171","msg":"trace[1045664039] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"2.45380852s","start":"2026-07-21T20:52:02.424297Z","end":"2026-07-21T20:52:04.878105Z","steps":["trace[1045664039] 'process raft request' (duration: 2.453232577s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:04.878254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.835126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:52:04.878302Z","caller":"traceutil/trace.go:171","msg":"trace[1998306626] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:667; }","duration":"448.936398ms","start":"2026-07-21T20:52:04.429352Z","end":"2026-07-21T20:52:04.878289Z","steps":["trace[1998306626] 'agreement among raft nodes before linearized reading' (duration: 448.841676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:04.878311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.451506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-21T20:52:04.878337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:04.429333Z","time spent":"448.994639ms","remote":"127.0.0.1:59626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-07-21T20:52:04.878312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.739944895s","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-21T20:52:04.878193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707717895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:52:04.878383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.642749835s","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-21T20:52:04.87841Z","caller":"traceutil/trace.go:171","msg":"trace[67342467] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:667; }","duration":"1.740082127s","start":"2026-07-21T20:52:03.138303Z","end":"2026-07-21T20:52:04.878386Z","steps":["trace[67342467] 'agreement among raft nodes before linearized reading' (duration: 1.739833582s)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:04.87843Z","caller":"traceutil/trace.go:171","msg":"trace[1775846048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:667; }","duration":"1.642837597s","start":"2026-07-21T20:52:03.23558Z","end":"2026-07-21T20:52:04.878417Z","steps":["trace[1775846048] 'agreement among raft nodes before linearized reading' (duration: 1.642656923s)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:04.878349Z","caller":"traceutil/trace.go:171","msg":"trace[927029985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:667; }","duration":"849.523927ms","start":"2026-07-21T20:52:04.028816Z","end":"2026-07-21T20:52:04.87834Z","steps":["trace[927029985] 'agreement among raft nodes before linearized reading' (duration: 849.395014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:04.878469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:03.235557Z","time spent":"1.642904478s","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T20:52:04.87847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:03.138285Z","time spent":"1.74017109s","remote":"127.0.0.1:48246","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-21T20:52:04.878414Z","caller":"traceutil/trace.go:171","msg":"trace[507821656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"1.707985231s","start":"2026-07-21T20:52:03.170414Z","end":"2026-07-21T20:52:04.878399Z","steps":["trace[507821656] 'agreement among raft nodes before linearized reading' (duration: 1.707730696s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:04.878655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:03.170395Z","time spent":"1.708239237s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T20:52:04.87849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:04.028795Z","time spent":"849.683791ms","remote":"127.0.0.1:48246","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-21T20:52:04.878333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:02.424263Z","time spent":"2.453964764s","remote":"127.0.0.1:59616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:52:04.878837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.342492ms","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-21T20:52:04.878888Z","caller":"traceutil/trace.go:171","msg":"trace[487380654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:667; }","duration":"832.422083ms","start":"2026-07-21T20:52:04.046451Z","end":"2026-07-21T20:52:04.878873Z","steps":["trace[487380654] 'agreement among raft nodes before linearized reading' (duration: 832.27333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:04.87892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:04.046436Z","time spent":"832.476735ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T20:52:05.03911Z","caller":"traceutil/trace.go:171","msg":"trace[487583277] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"154.551438ms","start":"2026-07-21T20:52:04.884535Z","end":"2026-07-21T20:52:05.039087Z","steps":["trace[487583277] 'process raft request' (duration: 143.974522ms)","trace[487583277] 'compare' (duration: 10.201118ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:05.039295Z","caller":"traceutil/trace.go:171","msg":"trace[29680571] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"153.083374ms","start":"2026-07-21T20:52:04.886196Z","end":"2026-07-21T20:52:05.03928Z","steps":["trace[29680571] 'process raft request' (duration: 152.952991ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:05.0393Z","caller":"traceutil/trace.go:171","msg":"trace[1765745135] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:696; }","duration":"149.416522ms","start":"2026-07-21T20:52:04.889839Z","end":"2026-07-21T20:52:05.039256Z","steps":["trace[1765745135] 'read index received' (duration: 138.683442ms)","trace[1765745135] 'applied index is now lower than readState.Index' (duration: 10.73187ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:05.039319Z","caller":"traceutil/trace.go:171","msg":"trace[1738963131] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"153.129936ms","start":"2026-07-21T20:52:04.886118Z","end":"2026-07-21T20:52:05.039248Z","steps":["trace[1738963131] 'process raft request' (duration: 152.88114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:05.039497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.590706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:52:05.039545Z","caller":"traceutil/trace.go:171","msg":"trace[845363864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:672; }","duration":"149.73881ms","start":"2026-07-21T20:52:04.889793Z","end":"2026-07-21T20:52:05.039532Z","steps":["trace[845363864] 'agreement among raft nodes before linearized reading' (duration: 149.573456ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:05.039559Z","caller":"traceutil/trace.go:171","msg":"trace[1966787431] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"153.243518ms","start":"2026-07-21T20:52:04.886136Z","end":"2026-07-21T20:52:05.03938Z","steps":["trace[1966787431] 'process raft request' (duration: 152.964452ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:06.729444Z","caller":"traceutil/trace.go:171","msg":"trace[1293387257] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"277.94508ms","start":"2026-07-21T20:52:06.451475Z","end":"2026-07-21T20:52:06.72942Z","steps":["trace[1293387257] 'read index received' (duration: 238.549429ms)","trace[1293387257] 'applied index is now lower than readState.Index' (duration: 39.394581ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:52:06.72954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:06.183776Z","time spent":"545.758048ms","remote":"127.0.0.1:59576","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-21T20:52:06.729585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.094233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:52:06.729631Z","caller":"traceutil/trace.go:171","msg":"trace[449462436] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:686; }","duration":"278.198486ms","start":"2026-07-21T20:52:06.45142Z","end":"2026-07-21T20:52:06.729619Z","steps":["trace[449462436] 'agreement among raft nodes before linearized reading' (duration: 278.102444ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:10.403056Z","caller":"traceutil/trace.go:171","msg":"trace[110757493] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"225.973484ms","start":"2026-07-21T20:52:10.177058Z","end":"2026-07-21T20:52:10.403032Z","steps":["trace[110757493] 'process raft request' (duration: 225.757149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:21.670652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727773851957545,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T20:52:23.171084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00045563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-21T20:52:23.171219Z","caller":"traceutil/trace.go:171","msg":"trace[220672557] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000664225s","start":"2026-07-21T20:52:21.170534Z","end":"2026-07-21T20:52:23.171198Z","steps":["trace[220672557] 'agreement among raft nodes before linearized reading' (duration: 2.000512512s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:23.171267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:21.170518Z","time spent":"2.000738256s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/21 20:52:23 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-21T20:52:23.296503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.105161881s","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-21T20:52:23.296628Z","caller":"traceutil/trace.go:171","msg":"trace[1486384982] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:839; }","duration":"2.105298553s","start":"2026-07-21T20:52:21.191311Z","end":"2026-07-21T20:52:23.29661Z","steps":["trace[1486384982] 'range keys from in-memory index tree' (duration: 2.105142161s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:23.29683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.528336366s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:52:23.296875Z","caller":"traceutil/trace.go:171","msg":"trace[830385007] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"2.156490111s","start":"2026-07-21T20:52:21.140376Z","end":"2026-07-21T20:52:23.296866Z","steps":["trace[830385007] 'process raft request' (duration: 628.070384ms)","trace[830385007] 'compare' (duration: 1.528197703s)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:52:23.296917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:21.140357Z","time spent":"2.156542492s","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:52:23.30302Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.534595749s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T20:52:23.303255Z","caller":"traceutil/trace.go:171","msg":"trace[1443971806] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:873; }","duration":"2.132631144s","start":"2026-07-21T20:52:21.170601Z","end":"2026-07-21T20:52:23.303232Z","steps":["trace[1443971806] 'read index received' (duration: 597.835851ms)","trace[1443971806] 'applied index is now lower than readState.Index' (duration: 1.534794643s)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:23.303326Z","caller":"traceutil/trace.go:171","msg":"trace[1991428019] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"2.162691653s","start":"2026-07-21T20:52:21.140612Z","end":"2026-07-21T20:52:23.303303Z","steps":["trace[1991428019] 'process raft request' (duration: 2.162525769s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:23.303439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:21.140594Z","time spent":"2.162772964s","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:52:23.314108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178865336s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:52:23.314335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.430164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-21T20:52:23.314386Z","caller":"traceutil/trace.go:171","msg":"trace[1389780662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:842; }","duration":"176.529545ms","start":"2026-07-21T20:52:23.13784Z","end":"2026-07-21T20:52:23.31437Z","steps":["trace[1389780662] 'agreement among raft nodes before linearized reading' (duration: 176.393543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:23.314256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.657341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:52:23.314426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.629291ms","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-21T20:52:23.314469Z","caller":"traceutil/trace.go:171","msg":"trace[1688103849] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:842; }","duration":"275.709873ms","start":"2026-07-21T20:52:23.038747Z","end":"2026-07-21T20:52:23.314456Z","steps":["trace[1688103849] 'agreement among raft nodes before linearized reading' (duration: 275.56566ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:23.31447Z","caller":"traceutil/trace.go:171","msg":"trace[1559838629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:842; }","duration":"142.900005ms","start":"2026-07-21T20:52:23.171551Z","end":"2026-07-21T20:52:23.314451Z","steps":["trace[1559838629] 'agreement among raft nodes before linearized reading' (duration: 142.66313ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:23.314309Z","caller":"traceutil/trace.go:171","msg":"trace[1232251187] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"1.221173782s","start":"2026-07-21T20:52:22.093116Z","end":"2026-07-21T20:52:23.31429Z","steps":["trace[1232251187] 'process raft request' (duration: 1.220590001s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:23.314641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:22.093089Z","time spent":"1.221470718s","remote":"127.0.0.1:59748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:52:23.314863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.900764ms","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-21T20:52:23.314952Z","caller":"traceutil/trace.go:171","msg":"trace[1555477055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:842; }","duration":"174.016726ms","start":"2026-07-21T20:52:23.140922Z","end":"2026-07-21T20:52:23.314938Z","steps":["trace[1555477055] 'agreement among raft nodes before linearized reading' (duration: 173.817683ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:23.314474Z","caller":"traceutil/trace.go:171","msg":"trace[1094900776] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:842; }","duration":"1.179161212s","start":"2026-07-21T20:52:22.135197Z","end":"2026-07-21T20:52:23.314358Z","steps":["trace[1094900776] 'agreement among raft nodes before linearized reading' (duration: 1.178833356s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:23.315047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:22.135181Z","time spent":"1.179852585s","remote":"127.0.0.1:59612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-07-21T20:52:34.547188Z","caller":"traceutil/trace.go:171","msg":"trace[986487290] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"141.344772ms","start":"2026-07-21T20:52:34.405825Z","end":"2026-07-21T20:52:34.547169Z","steps":["trace[986487290] 'process raft request' (duration: 141.22484ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:35.257282Z","caller":"traceutil/trace.go:171","msg":"trace[24318485] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"202.603613ms","start":"2026-07-21T20:52:35.054629Z","end":"2026-07-21T20:52:35.257233Z","steps":["trace[24318485] 'process raft request' (duration: 190.314449ms)","trace[24318485] 'compare' (duration: 11.934868ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:35.257451Z","caller":"traceutil/trace.go:171","msg":"trace[109146288] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"199.809742ms","start":"2026-07-21T20:52:35.05758Z","end":"2026-07-21T20:52:35.25739Z","steps":["trace[109146288] 'process raft request' (duration: 199.548717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:35.511503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.752456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:52:35.511595Z","caller":"traceutil/trace.go:171","msg":"trace[1036910794] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"157.073814ms","start":"2026-07-21T20:52:35.354507Z","end":"2026-07-21T20:52:35.511581Z","steps":["trace[1036910794] 'read index received' (duration: 21.222377ms)","trace[1036910794] 'applied index is now lower than readState.Index' (duration: 135.850337ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:52:35.511945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.029925ms","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-21T20:52:35.51165Z","caller":"traceutil/trace.go:171","msg":"trace[551125931] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"209.50048ms","start":"2026-07-21T20:52:35.302105Z","end":"2026-07-21T20:52:35.511605Z","steps":["trace[551125931] 'process raft request' (duration: 73.592651ms)","trace[551125931] 'compare' (duration: 135.637214ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:35.512062Z","caller":"traceutil/trace.go:171","msg":"trace[1125772005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:969; }","duration":"128.214778ms","start":"2026-07-21T20:52:35.383825Z","end":"2026-07-21T20:52:35.51204Z","steps":["trace[1125772005] 'agreement among raft nodes before linearized reading' (duration: 127.973974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:35.511942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.40994ms","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-21T20:52:35.512152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.574073ms","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-21T20:52:35.512344Z","caller":"traceutil/trace.go:171","msg":"trace[414019917] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:969; }","duration":"123.931299ms","start":"2026-07-21T20:52:35.388386Z","end":"2026-07-21T20:52:35.512317Z","steps":["trace[414019917] 'agreement among raft nodes before linearized reading' (duration: 123.41001ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:35.512248Z","caller":"traceutil/trace.go:171","msg":"trace[1201090953] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"157.786277ms","start":"2026-07-21T20:52:35.354442Z","end":"2026-07-21T20:52:35.512228Z","steps":["trace[1201090953] 'agreement among raft nodes before linearized reading' (duration: 157.215077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:35.512543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.982061ms","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-21T20:52:35.512771Z","caller":"traceutil/trace.go:171","msg":"trace[1301934067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:969; }","duration":"124.057582ms","start":"2026-07-21T20:52:35.388527Z","end":"2026-07-21T20:52:35.512585Z","steps":["trace[1301934067] 'agreement among raft nodes before linearized reading' (duration: 123.204647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:35.51224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.582116ms","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-21T20:52:35.512866Z","caller":"traceutil/trace.go:171","msg":"trace[1629450230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:969; }","duration":"151.231618ms","start":"2026-07-21T20:52:35.361621Z","end":"2026-07-21T20:52:35.512852Z","steps":["trace[1629450230] 'agreement among raft nodes before linearized reading' (duration: 150.081487ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:35.878479Z","caller":"traceutil/trace.go:171","msg":"trace[286678090] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"276.89785ms","start":"2026-07-21T20:52:35.601542Z","end":"2026-07-21T20:52:35.87844Z","steps":["trace[286678090] 'process raft request' (duration: 269.493164ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:36.062355Z","caller":"traceutil/trace.go:171","msg":"trace[862353567] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"114.474313ms","start":"2026-07-21T20:52:35.947851Z","end":"2026-07-21T20:52:36.062325Z","steps":["trace[862353567] 'process raft request' (duration: 93.269778ms)","trace[862353567] 'compare' (duration: 21.042531ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:36.24095Z","caller":"traceutil/trace.go:171","msg":"trace[623218175] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1017; }","duration":"104.458513ms","start":"2026-07-21T20:52:36.13647Z","end":"2026-07-21T20:52:36.240929Z","steps":["trace[623218175] 'read index received' (duration: 104.212679ms)","trace[623218175] 'applied index is now lower than readState.Index' (duration: 245.184µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:52:36.241072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.617471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:52:36.24104Z","caller":"traceutil/trace.go:171","msg":"trace[2099133368] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"112.391048ms","start":"2026-07-21T20:52:36.128618Z","end":"2026-07-21T20:52:36.241009Z","steps":["trace[2099133368] 'process raft request' (duration: 112.263605ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:36.241129Z","caller":"traceutil/trace.go:171","msg":"trace[1771404839] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"114.484476ms","start":"2026-07-21T20:52:36.126601Z","end":"2026-07-21T20:52:36.241085Z","steps":["trace[1771404839] 'process raft request' (duration: 114.16844ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:36.241099Z","caller":"traceutil/trace.go:171","msg":"trace[1916537607] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:982; }","duration":"112.685563ms","start":"2026-07-21T20:52:36.128403Z","end":"2026-07-21T20:52:36.241089Z","steps":["trace[1916537607] 'agreement among raft nodes before linearized reading' (duration: 112.629472ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:53.931382Z","caller":"traceutil/trace.go:171","msg":"trace[51182095] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"209.659735ms","start":"2026-07-21T20:52:53.721517Z","end":"2026-07-21T20:52:53.931177Z","steps":["trace[51182095] 'process raft request' (duration: 206.550553ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:53.984632Z","caller":"traceutil/trace.go:171","msg":"trace[1106751473] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"262.080504ms","start":"2026-07-21T20:52:53.722531Z","end":"2026-07-21T20:52:53.984611Z","steps":["trace[1106751473] 'process raft request' (duration: 262.051573ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:53.984674Z","caller":"traceutil/trace.go:171","msg":"trace[1061945816] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"262.732195ms","start":"2026-07-21T20:52:53.721913Z","end":"2026-07-21T20:52:53.984645Z","steps":["trace[1061945816] 'process raft request' (duration: 262.539092ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:53.984708Z","caller":"traceutil/trace.go:171","msg":"trace[1620738605] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"262.619602ms","start":"2026-07-21T20:52:53.722066Z","end":"2026-07-21T20:52:53.984686Z","steps":["trace[1620738605] 'process raft request' (duration: 262.48703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:08.625852Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727773851958129,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T20:53:08.69943Z","caller":"traceutil/trace.go:171","msg":"trace[1502942104] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"575.768789ms","start":"2026-07-21T20:53:08.123636Z","end":"2026-07-21T20:53:08.699405Z","steps":["trace[1502942104] 'process raft request' (duration: 575.625117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:08.699565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:08.123618Z","time spent":"575.877911ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:53:09.220356Z","caller":"traceutil/trace.go:171","msg":"trace[1073654375] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1163; }","duration":"1.09521103s","start":"2026-07-21T20:53:08.125129Z","end":"2026-07-21T20:53:09.22034Z","steps":["trace[1073654375] 'read index received' (duration: 574.184164ms)","trace[1073654375] 'applied index is now lower than readState.Index' (duration: 521.025916ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:09.220454Z","caller":"traceutil/trace.go:171","msg":"trace[1766521882] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"1.095971582s","start":"2026-07-21T20:53:08.124474Z","end":"2026-07-21T20:53:09.220445Z","steps":["trace[1766521882] 'process raft request' (duration: 1.09329476s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:09.220524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:08.124448Z","time spent":"1.096024562s","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:53:09.220679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05071596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:09.220707Z","caller":"traceutil/trace.go:171","msg":"trace[1228578525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1122; }","duration":"1.050787951s","start":"2026-07-21T20:53:08.169912Z","end":"2026-07-21T20:53:09.2207Z","steps":["trace[1228578525] 'agreement among raft nodes before linearized reading' (duration: 1.05074753s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:09.220681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095538475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-21T20:53:09.22073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:08.169887Z","time spent":"1.050838692s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-21T20:53:09.220759Z","caller":"traceutil/trace.go:171","msg":"trace[1456735712] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1122; }","duration":"1.095638836s","start":"2026-07-21T20:53:08.125102Z","end":"2026-07-21T20:53:09.220741Z","steps":["trace[1456735712] 'agreement among raft nodes before linearized reading' (duration: 1.095473584s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:09.220797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:08.125088Z","time spent":"1.095702338s","remote":"127.0.0.1:48246","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-21T20:53:09.220851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000308999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:53:09.220875Z","caller":"traceutil/trace.go:171","msg":"trace[581119993] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1122; }","duration":"1.000362169s","start":"2026-07-21T20:53:08.220507Z","end":"2026-07-21T20:53:09.220869Z","steps":["trace[581119993] 'agreement among raft nodes before linearized reading' (duration: 1.000322269s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:09.220896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:08.22049Z","time spent":"1.00040167s","remote":"127.0.0.1:48484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-07-21T20:53:09.220952Z","caller":"traceutil/trace.go:171","msg":"trace[1147596053] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"561.29832ms","start":"2026-07-21T20:53:08.659643Z","end":"2026-07-21T20:53:09.220942Z","steps":["trace[1147596053] 'process raft request' (duration: 560.663031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:09.221028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:08.659623Z","time spent":"561.354041ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:53:14.961698Z","caller":"traceutil/trace.go:171","msg":"trace[305065019] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"218.130963ms","start":"2026-07-21T20:53:14.743531Z","end":"2026-07-21T20:53:14.961662Z","steps":["trace[305065019] 'read index received' (duration: 217.887189ms)","trace[305065019] 'applied index is now lower than readState.Index' (duration: 242.384µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:14.962009Z","caller":"traceutil/trace.go:171","msg":"trace[1849124388] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"227.866872ms","start":"2026-07-21T20:53:14.734105Z","end":"2026-07-21T20:53:14.961972Z","steps":["trace[1849124388] 'process raft request' (duration: 227.356644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:14.961935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.358747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:53:14.963023Z","caller":"traceutil/trace.go:171","msg":"trace[349983928] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1140; }","duration":"219.493963ms","start":"2026-07-21T20:53:14.743506Z","end":"2026-07-21T20:53:14.963Z","steps":["trace[349983928] 'agreement among raft nodes before linearized reading' (duration: 218.289385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:15.468536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.929215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:53:15.468627Z","caller":"traceutil/trace.go:171","msg":"trace[713115333] linearizableReadLoop","detail":"{readStateIndex:1187; appliedIndex:1186; }","duration":"174.834898ms","start":"2026-07-21T20:53:15.293777Z","end":"2026-07-21T20:53:15.468612Z","steps":["trace[713115333] 'read index received' (duration: 733.311µs)","trace[713115333] 'applied index is now lower than readState.Index' (duration: 174.099737ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:15.468797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.014821ms","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-21T20:53:15.468838Z","caller":"traceutil/trace.go:171","msg":"trace[2038153026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1142; }","duration":"175.086351ms","start":"2026-07-21T20:53:15.293741Z","end":"2026-07-21T20:53:15.468828Z","steps":["trace[2038153026] 'agreement among raft nodes before linearized reading' (duration: 174.928899ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:15.468877Z","caller":"traceutil/trace.go:171","msg":"trace[607215469] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"205.226157ms","start":"2026-07-21T20:53:15.263623Z","end":"2026-07-21T20:53:15.468849Z","steps":["trace[607215469] 'process raft request' (duration: 30.926766ms)","trace[607215469] 'compare' (duration: 173.773563ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:16.184Z","caller":"traceutil/trace.go:171","msg":"trace[1048943418] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"121.853543ms","start":"2026-07-21T20:53:16.06212Z","end":"2026-07-21T20:53:16.183974Z","steps":["trace[1048943418] 'process raft request' (duration: 121.69292ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:17.640942Z","caller":"traceutil/trace.go:171","msg":"trace[630593683] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"121.682983ms","start":"2026-07-21T20:53:17.519234Z","end":"2026-07-21T20:53:17.640917Z","steps":["trace[630593683] 'process raft request' (duration: 116.490455ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:21.698482Z","caller":"traceutil/trace.go:171","msg":"trace[1748502733] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"130.076811ms","start":"2026-07-21T20:53:21.568385Z","end":"2026-07-21T20:53:21.698462Z","steps":["trace[1748502733] 'process raft request' (duration: 129.945439ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:23.50139Z","caller":"traceutil/trace.go:171","msg":"trace[1782960271] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"104.852758ms","start":"2026-07-21T20:53:23.39649Z","end":"2026-07-21T20:53:23.501342Z","steps":["trace[1782960271] 'process raft request' (duration: 104.333581ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:26.169072Z","caller":"traceutil/trace.go:171","msg":"trace[1775732357] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"106.397735ms","start":"2026-07-21T20:53:26.062635Z","end":"2026-07-21T20:53:26.169033Z","steps":["trace[1775732357] 'process raft request' (duration: 34.794126ms)","trace[1775732357] 'compare' (duration: 71.418786ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:27.562761Z","caller":"traceutil/trace.go:171","msg":"trace[1618195426] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"260.892956ms","start":"2026-07-21T20:53:27.301848Z","end":"2026-07-21T20:53:27.562741Z","steps":["trace[1618195426] 'process raft request' (duration: 260.776604ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:27.716818Z","caller":"traceutil/trace.go:171","msg":"trace[177034968] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"130.59985ms","start":"2026-07-21T20:53:27.586185Z","end":"2026-07-21T20:53:27.716785Z","steps":["trace[177034968] 'process raft request' (duration: 102.502434ms)","trace[177034968] 'compare' (duration: 27.929614ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:28.301966Z","caller":"traceutil/trace.go:171","msg":"trace[2020002438] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"101.09893ms","start":"2026-07-21T20:53:28.200835Z","end":"2026-07-21T20:53:28.301934Z","steps":["trace[2020002438] 'process raft request' (duration: 100.854375ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:28.666416Z","caller":"traceutil/trace.go:171","msg":"trace[1093105438] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"186.252827ms","start":"2026-07-21T20:53:28.480134Z","end":"2026-07-21T20:53:28.666387Z","steps":["trace[1093105438] 'process raft request' (duration: 186.056615ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:30.803886Z","caller":"traceutil/trace.go:171","msg":"trace[189590990] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"130.016024ms","start":"2026-07-21T20:53:30.673846Z","end":"2026-07-21T20:53:30.803862Z","steps":["trace[189590990] 'process raft request' (duration: 129.858062ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:31.439343Z","caller":"traceutil/trace.go:171","msg":"trace[1688849845] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"117.124363ms","start":"2026-07-21T20:53:31.322195Z","end":"2026-07-21T20:53:31.43932Z","steps":["trace[1688849845] 'process raft request' (duration: 116.976641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:31.72862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.525869ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-21T20:53:31.728737Z","caller":"traceutil/trace.go:171","msg":"trace[1603450950] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1241; }","duration":"114.490516ms","start":"2026-07-21T20:53:31.614228Z","end":"2026-07-21T20:53:31.728719Z","steps":["trace[1603450950] 'read index received' (duration: 32.57µs)","trace[1603450950] 'applied index is now lower than readState.Index' (duration: 114.456076ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:31.728878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.639428ms","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-21T20:53:31.72891Z","caller":"traceutil/trace.go:171","msg":"trace[1978019590] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1193; }","duration":"114.70392ms","start":"2026-07-21T20:53:31.614198Z","end":"2026-07-21T20:53:31.728902Z","steps":["trace[1978019590] 'agreement among raft nodes before linearized reading' (duration: 114.566398ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:32.387555Z","caller":"traceutil/trace.go:171","msg":"trace[1973350205] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"213.098676ms","start":"2026-07-21T20:53:32.174439Z","end":"2026-07-21T20:53:32.387537Z","steps":["trace[1973350205] 'process raft request' (duration: 212.975244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:32.54241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.256454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:53:32.542505Z","caller":"traceutil/trace.go:171","msg":"trace[176808379] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"150.84956ms","start":"2026-07-21T20:53:32.391636Z","end":"2026-07-21T20:53:32.542485Z","steps":["trace[176808379] 'process raft request' (duration: 25.446274ms)","trace[176808379] 'compare' (duration: 125.055121ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:33.863902Z","caller":"traceutil/trace.go:171","msg":"trace[394032313] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"104.291241ms","start":"2026-07-21T20:53:33.759575Z","end":"2026-07-21T20:53:33.863866Z","steps":["trace[394032313] 'process raft request' (duration: 104.108728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:34.369292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.12796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:34.369355Z","caller":"traceutil/trace.go:171","msg":"trace[732252069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1202; }","duration":"199.234862ms","start":"2026-07-21T20:53:34.170105Z","end":"2026-07-21T20:53:34.36934Z","steps":["trace[732252069] 'range keys from in-memory index tree' (duration: 199.06061ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:34.502561Z","caller":"traceutil/trace.go:171","msg":"trace[1434031610] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"108.944474ms","start":"2026-07-21T20:53:34.393582Z","end":"2026-07-21T20:53:34.502526Z","steps":["trace[1434031610] 'process raft request' (duration: 108.761481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:35.260833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.171435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:35.260894Z","caller":"traceutil/trace.go:171","msg":"trace[198550960] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1205; }","duration":"122.283187ms","start":"2026-07-21T20:53:35.138594Z","end":"2026-07-21T20:53:35.260877Z","steps":["trace[198550960] 'count revisions from in-memory index tree' (duration: 122.078294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:36.47689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.540883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-21T20:53:36.47697Z","caller":"traceutil/trace.go:171","msg":"trace[2037220594] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1209; }","duration":"168.674365ms","start":"2026-07-21T20:53:36.308275Z","end":"2026-07-21T20:53:36.47695Z","steps":["trace[2037220594] 'range keys from in-memory index tree' (duration: 168.33213ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:36.629281Z","caller":"traceutil/trace.go:171","msg":"trace[449870571] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"119.547864ms","start":"2026-07-21T20:53:36.509707Z","end":"2026-07-21T20:53:36.629254Z","steps":["trace[449870571] 'process raft request' (duration: 119.409602ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:36.847587Z","caller":"traceutil/trace.go:171","msg":"trace[172733625] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"204.960626ms","start":"2026-07-21T20:53:36.6426Z","end":"2026-07-21T20:53:36.84756Z","steps":["trace[172733625] 'process raft request' (duration: 135.929754ms)","trace[172733625] 'compare' (duration: 68.88859ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:38.627403Z","caller":"traceutil/trace.go:171","msg":"trace[1953488729] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"457.11461ms","start":"2026-07-21T20:53:38.170271Z","end":"2026-07-21T20:53:38.627386Z","steps":["trace[1953488729] 'read index received' (duration: 457.073249ms)","trace[1953488729] 'applied index is now lower than readState.Index' (duration: 40.571µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:38.627444Z","caller":"traceutil/trace.go:171","msg":"trace[900885482] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"512.566716ms","start":"2026-07-21T20:53:38.114856Z","end":"2026-07-21T20:53:38.627423Z","steps":["trace[900885482] 'process raft request' (duration: 512.435824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:38.627538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:38.114828Z","time spent":"512.649427ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:53:38.627558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.268772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:38.627644Z","caller":"traceutil/trace.go:171","msg":"trace[1206763782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"457.401464ms","start":"2026-07-21T20:53:38.170223Z","end":"2026-07-21T20:53:38.627625Z","steps":["trace[1206763782] 'agreement among raft nodes before linearized reading' (duration: 457.246392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:38.627687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:38.170203Z","time spent":"457.475476ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T20:53:39.077198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.427736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:53:39.077301Z","caller":"traceutil/trace.go:171","msg":"trace[28832206] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"448.650049ms","start":"2026-07-21T20:53:38.62864Z","end":"2026-07-21T20:53:39.07729Z","steps":["trace[28832206] 'read index received' (duration: 100.088791ms)","trace[28832206] 'applied index is now lower than readState.Index' (duration: 348.560248ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:39.077493Z","caller":"traceutil/trace.go:171","msg":"trace[819751937] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"844.122185ms","start":"2026-07-21T20:53:38.233364Z","end":"2026-07-21T20:53:39.077486Z","steps":["trace[819751937] 'process raft request' (duration: 495.344975ms)","trace[819751937] 'compare' (duration: 348.090491ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:39.07753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:38.233338Z","time spent":"844.175397ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:53:39.077726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.057544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:39.077835Z","caller":"traceutil/trace.go:171","msg":"trace[1299286788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"449.211737ms","start":"2026-07-21T20:53:38.628605Z","end":"2026-07-21T20:53:39.077817Z","steps":["trace[1299286788] 'agreement among raft nodes before linearized reading' (duration: 449.032925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:39.077885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:38.62859Z","time spent":"449.286888ms","remote":"127.0.0.1:59552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T20:53:39.077889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.693938ms","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-21T20:53:39.07802Z","caller":"traceutil/trace.go:171","msg":"trace[181331058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1216; }","duration":"445.836719ms","start":"2026-07-21T20:53:38.632143Z","end":"2026-07-21T20:53:39.07798Z","steps":["trace[181331058] 'agreement among raft nodes before linearized reading' (duration: 445.561955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:39.078091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:38.632131Z","time spent":"445.928431ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T20:53:39.078233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.61012ms","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-21T20:53:39.078314Z","caller":"traceutil/trace.go:171","msg":"trace[529681394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1216; }","duration":"226.729111ms","start":"2026-07-21T20:53:38.851564Z","end":"2026-07-21T20:53:39.078294Z","steps":["trace[529681394] 'agreement among raft nodes before linearized reading' (duration: 226.517459ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:39.19617Z","caller":"traceutil/trace.go:171","msg":"trace[1576581276] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"111.81761ms","start":"2026-07-21T20:53:39.084311Z","end":"2026-07-21T20:53:39.196128Z","steps":["trace[1576581276] 'process raft request' (duration: 108.417063ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:39.196114Z","caller":"traceutil/trace.go:171","msg":"trace[171272554] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"111.509556ms","start":"2026-07-21T20:53:39.084574Z","end":"2026-07-21T20:53:39.196083Z","steps":["trace[171272554] 'process raft request' (duration: 111.438755ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:41.298763Z","caller":"traceutil/trace.go:171","msg":"trace[296671050] linearizableReadLoop","detail":"{readStateIndex:1273; appliedIndex:1272; }","duration":"127.821545ms","start":"2026-07-21T20:53:41.170914Z","end":"2026-07-21T20:53:41.298736Z","steps":["trace[296671050] 'read index received' (duration: 127.583082ms)","trace[296671050] 'applied index is now lower than readState.Index' (duration: 237.193µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:41.298894Z","caller":"traceutil/trace.go:171","msg":"trace[1402203415] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"174.382711ms","start":"2026-07-21T20:53:41.124496Z","end":"2026-07-21T20:53:41.298879Z","steps":["trace[1402203415] 'process raft request' (duration: 174.055047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:41.299307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.381783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:41.299375Z","caller":"traceutil/trace.go:171","msg":"trace[433842780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1223; }","duration":"128.496414ms","start":"2026-07-21T20:53:41.170859Z","end":"2026-07-21T20:53:41.299356Z","steps":["trace[433842780] 'agreement among raft nodes before linearized reading' (duration: 128.390332ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:41.476216Z","caller":"traceutil/trace.go:171","msg":"trace[1412497636] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"172.780368ms","start":"2026-07-21T20:53:41.303404Z","end":"2026-07-21T20:53:41.476185Z","steps":["trace[1412497636] 'process raft request' (duration: 166.182617ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:41.476427Z","caller":"traceutil/trace.go:171","msg":"trace[1918121543] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"172.13465ms","start":"2026-07-21T20:53:41.304265Z","end":"2026-07-21T20:53:41.4764Z","steps":["trace[1918121543] 'process raft request' (duration: 171.786796ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:41.566409Z","caller":"traceutil/trace.go:171","msg":"trace[238865476] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1273; }","duration":"190.005268ms","start":"2026-07-21T20:53:41.37637Z","end":"2026-07-21T20:53:41.566376Z","steps":["trace[238865476] 'read index received' (duration: 93.212264ms)","trace[238865476] 'applied index is now lower than readState.Index' (duration: 96.791334ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:41.566668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.274142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5548"} {"level":"info","ts":"2026-07-21T20:53:41.566712Z","caller":"traceutil/trace.go:171","msg":"trace[1214265212] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1225; }","duration":"190.361714ms","start":"2026-07-21T20:53:41.376339Z","end":"2026-07-21T20:53:41.566701Z","steps":["trace[1214265212] 'agreement among raft nodes before linearized reading' (duration: 190.146071ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:45.814715Z","caller":"traceutil/trace.go:171","msg":"trace[1963534027] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"232.711207ms","start":"2026-07-21T20:53:45.581981Z","end":"2026-07-21T20:53:45.814692Z","steps":["trace[1963534027] 'process raft request' (duration: 183.177386ms)","trace[1963534027] 'compare' (duration: 49.43901ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:46.433721Z","caller":"traceutil/trace.go:171","msg":"trace[767104599] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1292; }","duration":"134.363931ms","start":"2026-07-21T20:53:46.299329Z","end":"2026-07-21T20:53:46.433693Z","steps":["trace[767104599] 'read index received' (duration: 98.449329ms)","trace[767104599] 'applied index is now lower than readState.Index' (duration: 35.913562ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:46.4338Z","caller":"traceutil/trace.go:171","msg":"trace[1418697063] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"136.378349ms","start":"2026-07-21T20:53:46.297387Z","end":"2026-07-21T20:53:46.433765Z","steps":["trace[1418697063] 'process raft request' (duration: 100.456997ms)","trace[1418697063] 'compare' (duration: 35.684399ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:46.433863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.517123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-21T20:53:46.433907Z","caller":"traceutil/trace.go:171","msg":"trace[1054388642] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1241; }","duration":"134.602705ms","start":"2026-07-21T20:53:46.299291Z","end":"2026-07-21T20:53:46.433894Z","steps":["trace[1054388642] 'agreement among raft nodes before linearized reading' (duration: 134.502333ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:46.869782Z","caller":"traceutil/trace.go:171","msg":"trace[1661779655] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"132.285513ms","start":"2026-07-21T20:53:46.737473Z","end":"2026-07-21T20:53:46.869759Z","steps":["trace[1661779655] 'process raft request' (duration: 132.146681ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:47.704604Z","caller":"traceutil/trace.go:171","msg":"trace[275511389] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"106.685408ms","start":"2026-07-21T20:53:47.597876Z","end":"2026-07-21T20:53:47.704562Z","steps":["trace[275511389] 'process raft request' (duration: 106.524346ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:48.585498Z","caller":"traceutil/trace.go:171","msg":"trace[819428742] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"142.671285ms","start":"2026-07-21T20:53:48.442803Z","end":"2026-07-21T20:53:48.585474Z","steps":["trace[819428742] 'process raft request' (duration: 142.478133ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:49.384378Z","caller":"traceutil/trace.go:171","msg":"trace[1705906008] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"162.075735ms","start":"2026-07-21T20:53:49.222241Z","end":"2026-07-21T20:53:49.384316Z","steps":["trace[1705906008] 'process raft request' (duration: 161.876722ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:50.695148Z","caller":"traceutil/trace.go:171","msg":"trace[1773819880] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"101.523628ms","start":"2026-07-21T20:53:50.593606Z","end":"2026-07-21T20:53:50.69513Z","steps":["trace[1773819880] 'process raft request' (duration: 101.383636ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:51.066078Z","caller":"traceutil/trace.go:171","msg":"trace[1465263120] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"165.809479ms","start":"2026-07-21T20:53:50.90025Z","end":"2026-07-21T20:53:51.066059Z","steps":["trace[1465263120] 'process raft request' (duration: 165.699418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:51.308754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.335943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:51.308819Z","caller":"traceutil/trace.go:171","msg":"trace[532745730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1255; }","duration":"141.438654ms","start":"2026-07-21T20:53:51.167366Z","end":"2026-07-21T20:53:51.308805Z","steps":["trace[532745730] 'range keys from in-memory index tree' (duration: 141.262882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:51.308795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.591415ms","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-21T20:53:51.30885Z","caller":"traceutil/trace.go:171","msg":"trace[1941056934] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1255; }","duration":"118.652056ms","start":"2026-07-21T20:53:51.190192Z","end":"2026-07-21T20:53:51.308844Z","steps":["trace[1941056934] 'range keys from in-memory index tree' (duration: 118.582125ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:54.859981Z","caller":"traceutil/trace.go:171","msg":"trace[196522955] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"100.675374ms","start":"2026-07-21T20:53:54.759276Z","end":"2026-07-21T20:53:54.859951Z","steps":["trace[196522955] 'process raft request' (duration: 100.105706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:55.768099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.750418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:55.768163Z","caller":"traceutil/trace.go:171","msg":"trace[434634965] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1268; }","duration":"465.869599ms","start":"2026-07-21T20:53:55.302281Z","end":"2026-07-21T20:53:55.76815Z","steps":["trace[434634965] 'count revisions from in-memory index tree' (duration: 465.655267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:55.768136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.18619ms","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-21T20:53:55.768195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:55.302255Z","time spent":"465.93231ms","remote":"127.0.0.1:59634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-07-21T20:53:55.768229Z","caller":"traceutil/trace.go:171","msg":"trace[142215772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1268; }","duration":"125.324002ms","start":"2026-07-21T20:53:55.642881Z","end":"2026-07-21T20:53:55.768205Z","steps":["trace[142215772] 'range keys from in-memory index tree' (duration: 125.000748ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:55.913743Z","caller":"traceutil/trace.go:171","msg":"trace[712939298] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"141.768741ms","start":"2026-07-21T20:53:55.77193Z","end":"2026-07-21T20:53:55.913698Z","steps":["trace[712939298] 'process raft request' (duration: 141.507658ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:56.531974Z","caller":"traceutil/trace.go:171","msg":"trace[1618091677] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"143.44338ms","start":"2026-07-21T20:53:56.388502Z","end":"2026-07-21T20:53:56.531946Z","steps":["trace[1618091677] 'process raft request' (duration: 143.254217ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:02.504933Z","caller":"traceutil/trace.go:171","msg":"trace[616638444] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"322.870276ms","start":"2026-07-21T20:54:02.182042Z","end":"2026-07-21T20:54:02.504912Z","steps":["trace[616638444] 'process raft request' (duration: 322.737874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:02.505046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:02.182028Z","time spent":"322.959508ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:54:02.53005Z","caller":"traceutil/trace.go:171","msg":"trace[880717785] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1344; }","duration":"305.948113ms","start":"2026-07-21T20:54:02.224091Z","end":"2026-07-21T20:54:02.530039Z","steps":["trace[880717785] 'read index received' (duration: 280.70146ms)","trace[880717785] 'applied index is now lower than readState.Index' (duration: 25.246053ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:02.530136Z","caller":"traceutil/trace.go:171","msg":"trace[1782156358] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"325.185507ms","start":"2026-07-21T20:54:02.204922Z","end":"2026-07-21T20:54:02.530107Z","steps":["trace[1782156358] 'process raft request' (duration: 317.923491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:02.530284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:02.204902Z","time spent":"325.310949ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:54:02.530167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.083925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-21T20:54:02.530395Z","caller":"traceutil/trace.go:171","msg":"trace[1785914854] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1291; }","duration":"306.342878ms","start":"2026-07-21T20:54:02.224025Z","end":"2026-07-21T20:54:02.530368Z","steps":["trace[1785914854] 'agreement among raft nodes before linearized reading' (duration: 306.077755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:02.530494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:02.224Z","time spent":"306.45756ms","remote":"127.0.0.1:48246","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-21T20:54:05.087556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.490402ms","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-21T20:54:05.087635Z","caller":"traceutil/trace.go:171","msg":"trace[987428210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1299; }","duration":"116.628373ms","start":"2026-07-21T20:54:04.970989Z","end":"2026-07-21T20:54:05.087617Z","steps":["trace[987428210] 'range keys from in-memory index tree' (duration: 116.290469ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:05.329533Z","caller":"traceutil/trace.go:171","msg":"trace[1440072211] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1354; }","duration":"154.689402ms","start":"2026-07-21T20:54:05.17482Z","end":"2026-07-21T20:54:05.329509Z","steps":["trace[1440072211] 'read index received' (duration: 154.437808ms)","trace[1440072211] 'applied index is now lower than readState.Index' (duration: 250.514µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:05.329624Z","caller":"traceutil/trace.go:171","msg":"trace[1807505425] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"236.494477ms","start":"2026-07-21T20:54:05.093112Z","end":"2026-07-21T20:54:05.329607Z","steps":["trace[1807505425] 'process raft request' (duration: 236.199073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.329678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.833073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:05.329714Z","caller":"traceutil/trace.go:171","msg":"trace[176391564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1300; }","duration":"154.888574ms","start":"2026-07-21T20:54:05.174815Z","end":"2026-07-21T20:54:05.329703Z","steps":["trace[176391564] 'agreement among raft nodes before linearized reading' (duration: 154.807213ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:05.607066Z","caller":"traceutil/trace.go:171","msg":"trace[1145717496] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"163.77211ms","start":"2026-07-21T20:54:05.443268Z","end":"2026-07-21T20:54:05.60704Z","steps":["trace[1145717496] 'process raft request' (duration: 163.618638ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:06.316037Z","caller":"traceutil/trace.go:171","msg":"trace[1249401170] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"119.714159ms","start":"2026-07-21T20:54:06.196292Z","end":"2026-07-21T20:54:06.316006Z","steps":["trace[1249401170] 'read index received' (duration: 119.477216ms)","trace[1249401170] 'applied index is now lower than readState.Index' (duration: 235.183µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:06.316534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.209456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:06.316726Z","caller":"traceutil/trace.go:171","msg":"trace[172769229] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1302; }","duration":"120.334518ms","start":"2026-07-21T20:54:06.196272Z","end":"2026-07-21T20:54:06.316607Z","steps":["trace[172769229] 'agreement among raft nodes before linearized reading' (duration: 119.8373ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:06.664415Z","caller":"traceutil/trace.go:171","msg":"trace[338537269] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"112.353023ms","start":"2026-07-21T20:54:06.552035Z","end":"2026-07-21T20:54:06.664388Z","steps":["trace[338537269] 'process raft request' (duration: 111.959688ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:06.819123Z","caller":"traceutil/trace.go:171","msg":"trace[174115545] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"145.507108ms","start":"2026-07-21T20:54:06.673601Z","end":"2026-07-21T20:54:06.819108Z","steps":["trace[174115545] 'read index received' (duration: 110.515929ms)","trace[174115545] 'applied index is now lower than readState.Index' (duration: 34.990429ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:06.819176Z","caller":"traceutil/trace.go:171","msg":"trace[180419288] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"146.241227ms","start":"2026-07-21T20:54:06.672904Z","end":"2026-07-21T20:54:06.819146Z","steps":["trace[180419288] 'process raft request' (duration: 111.268279ms)","trace[180419288] 'compare' (duration: 34.787026ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:06.819238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.6269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:06.819262Z","caller":"traceutil/trace.go:171","msg":"trace[1157055684] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:1305; }","duration":"145.6913ms","start":"2026-07-21T20:54:06.673562Z","end":"2026-07-21T20:54:06.819254Z","steps":["trace[1157055684] 'agreement among raft nodes before linearized reading' (duration: 145.63419ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:07.049542Z","caller":"traceutil/trace.go:171","msg":"trace[1829514379] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"228.525594ms","start":"2026-07-21T20:54:06.820994Z","end":"2026-07-21T20:54:07.04952Z","steps":["trace[1829514379] 'process raft request' (duration: 228.002008ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:08.638956Z","caller":"traceutil/trace.go:171","msg":"trace[515036326] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"103.027856ms","start":"2026-07-21T20:54:08.535898Z","end":"2026-07-21T20:54:08.638925Z","steps":["trace[515036326] 'process raft request' (duration: 102.862624ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:08.747468Z","caller":"traceutil/trace.go:171","msg":"trace[360129656] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"100.090207ms","start":"2026-07-21T20:54:08.647348Z","end":"2026-07-21T20:54:08.747438Z","steps":["trace[360129656] 'process raft request' (duration: 99.370978ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:09.798926Z","caller":"traceutil/trace.go:171","msg":"trace[1072504680] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"158.456886ms","start":"2026-07-21T20:54:09.640445Z","end":"2026-07-21T20:54:09.798902Z","steps":["trace[1072504680] 'read index received' (duration: 158.230773ms)","trace[1072504680] 'applied index is now lower than readState.Index' (duration: 225.243µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:09.799004Z","caller":"traceutil/trace.go:171","msg":"trace[1730469661] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"407.837898ms","start":"2026-07-21T20:54:09.391123Z","end":"2026-07-21T20:54:09.798961Z","steps":["trace[1730469661] 'process raft request' (duration: 407.639835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:09.799087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.636738ms","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-21T20:54:09.799134Z","caller":"traceutil/trace.go:171","msg":"trace[145423680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1326; }","duration":"158.74121ms","start":"2026-07-21T20:54:09.640371Z","end":"2026-07-21T20:54:09.799112Z","steps":["trace[145423680] 'agreement among raft nodes before linearized reading' (duration: 158.608698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:09.799155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:09.391105Z","time spent":"407.975399ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:54:10.013347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.658401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:54:10.013571Z","caller":"traceutil/trace.go:171","msg":"trace[279075621] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"180.377711ms","start":"2026-07-21T20:54:09.833166Z","end":"2026-07-21T20:54:10.013544Z","steps":["trace[279075621] 'process raft request' (duration: 180.276819ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:10.013577Z","caller":"traceutil/trace.go:171","msg":"trace[632759435] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"210.95053ms","start":"2026-07-21T20:54:09.802604Z","end":"2026-07-21T20:54:10.013554Z","steps":["trace[632759435] 'process raft request' (duration: 96.992685ms)","trace[632759435] 'compare' (duration: 113.449388ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:10.269724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.89752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7bdc31a8-79c8-4276-ba5e-6579e7fa51d9\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-07-21T20:54:10.269823Z","caller":"traceutil/trace.go:171","msg":"trace[1461203145] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7bdc31a8-79c8-4276-ba5e-6579e7fa51d9; range_end:; response_count:1; response_revision:1328; }","duration":"143.024171ms","start":"2026-07-21T20:54:10.126774Z","end":"2026-07-21T20:54:10.269799Z","steps":["trace[1461203145] 'range keys from in-memory index tree' (duration: 142.712417ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:10.429636Z","caller":"traceutil/trace.go:171","msg":"trace[840172498] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"155.504534ms","start":"2026-07-21T20:54:10.274103Z","end":"2026-07-21T20:54:10.429607Z","steps":["trace[840172498] 'process raft request' (duration: 155.347452ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:10.889237Z","caller":"traceutil/trace.go:171","msg":"trace[2065780428] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"104.846874ms","start":"2026-07-21T20:54:10.784356Z","end":"2026-07-21T20:54:10.889203Z","steps":["trace[2065780428] 'process raft request' (duration: 104.53654ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:10.996531Z","caller":"traceutil/trace.go:171","msg":"trace[705443037] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"103.109852ms","start":"2026-07-21T20:54:10.8934Z","end":"2026-07-21T20:54:10.99651Z","steps":["trace[705443037] 'process raft request' (duration: 60.465478ms)","trace[705443037] 'compare' (duration: 42.508743ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:11.068841Z","caller":"traceutil/trace.go:171","msg":"trace[1963029719] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"161.431949ms","start":"2026-07-21T20:54:10.907379Z","end":"2026-07-21T20:54:11.068811Z","steps":["trace[1963029719] 'process raft request' (duration: 161.280017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:11.633104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.772579ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-21T20:54:11.633209Z","caller":"traceutil/trace.go:171","msg":"trace[1195016285] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1389; }","duration":"138.321187ms","start":"2026-07-21T20:54:11.494867Z","end":"2026-07-21T20:54:11.633188Z","steps":["trace[1195016285] 'read index received' (duration: 37.05µs)","trace[1195016285] 'applied index is now lower than readState.Index' (duration: 138.282467ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:11.633407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.52119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/test-pvc.18c46968095a05b5\" ","response":"range_response_count:1 size:916"} {"level":"info","ts":"2026-07-21T20:54:11.633461Z","caller":"traceutil/trace.go:171","msg":"trace[1744763762] range","detail":"{range_begin:/registry/events/default/test-pvc.18c46968095a05b5; range_end:; response_count:1; response_revision:1332; }","duration":"138.608261ms","start":"2026-07-21T20:54:11.494836Z","end":"2026-07-21T20:54:11.633444Z","steps":["trace[1744763762] 'agreement among raft nodes before linearized reading' (duration: 138.415028ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:12.882741Z","caller":"traceutil/trace.go:171","msg":"trace[1536781133] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"125.535888ms","start":"2026-07-21T20:54:12.757176Z","end":"2026-07-21T20:54:12.882712Z","steps":["trace[1536781133] 'process raft request' (duration: 125.271423ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:13.726936Z","caller":"traceutil/trace.go:171","msg":"trace[1666588475] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"127.825555ms","start":"2026-07-21T20:54:13.59906Z","end":"2026-07-21T20:54:13.726885Z","steps":["trace[1666588475] 'process raft request' (duration: 127.660483ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:14.031282Z","caller":"traceutil/trace.go:171","msg":"trace[1943239537] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"112.382205ms","start":"2026-07-21T20:54:13.918863Z","end":"2026-07-21T20:54:14.031245Z","steps":["trace[1943239537] 'process raft request' (duration: 112.134371ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:14.203756Z","caller":"traceutil/trace.go:171","msg":"trace[868344195] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"274.998685ms","start":"2026-07-21T20:54:13.928724Z","end":"2026-07-21T20:54:14.203723Z","steps":["trace[868344195] 'process raft request' (duration: 220.44263ms)","trace[868344195] 'compare' (duration: 54.305522ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:15.26301Z","caller":"traceutil/trace.go:171","msg":"trace[1101374018] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"109.594164ms","start":"2026-07-21T20:54:15.153395Z","end":"2026-07-21T20:54:15.262989Z","steps":["trace[1101374018] 'process raft request' (duration: 109.475622ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:16.502385Z","caller":"traceutil/trace.go:171","msg":"trace[1830983340] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1412; }","duration":"184.601618ms","start":"2026-07-21T20:54:16.317752Z","end":"2026-07-21T20:54:16.502354Z","steps":["trace[1830983340] 'read index received' (duration: 165.755415ms)","trace[1830983340] 'applied index is now lower than readState.Index' (duration: 18.844603ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:16.502836Z","caller":"traceutil/trace.go:171","msg":"trace[1836233918] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"185.314256ms","start":"2026-07-21T20:54:16.31748Z","end":"2026-07-21T20:54:16.502794Z","steps":["trace[1836233918] 'process raft request' (duration: 166.033598ms)","trace[1836233918] 'compare' (duration: 18.658271ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:16.502945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.163975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T20:54:16.503038Z","caller":"traceutil/trace.go:171","msg":"trace[74538830] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1354; }","duration":"185.298236ms","start":"2026-07-21T20:54:16.31772Z","end":"2026-07-21T20:54:16.503018Z","steps":["trace[74538830] 'agreement among raft nodes before linearized reading' (duration: 185.024413ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:17.50508Z","caller":"traceutil/trace.go:171","msg":"trace[1259071557] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"154.957743ms","start":"2026-07-21T20:54:17.350093Z","end":"2026-07-21T20:54:17.50505Z","steps":["trace[1259071557] 'process raft request' (duration: 154.769281ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:17.859533Z","caller":"traceutil/trace.go:171","msg":"trace[1078543193] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"142.117548ms","start":"2026-07-21T20:54:17.717384Z","end":"2026-07-21T20:54:17.859502Z","steps":["trace[1078543193] 'process raft request' (duration: 141.940685ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:19.096742Z","caller":"traceutil/trace.go:171","msg":"trace[586391789] linearizableReadLoop","detail":"{readStateIndex:1431; appliedIndex:1430; }","duration":"155.298232ms","start":"2026-07-21T20:54:18.941417Z","end":"2026-07-21T20:54:19.096716Z","steps":["trace[586391789] 'read index received' (duration: 152.929331ms)","trace[586391789] 'applied index is now lower than readState.Index' (duration: 2.367611ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:19.096851Z","caller":"traceutil/trace.go:171","msg":"trace[500433118] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"156.103902ms","start":"2026-07-21T20:54:18.940675Z","end":"2026-07-21T20:54:19.096779Z","steps":["trace[500433118] 'process raft request' (duration: 153.647351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:19.097592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.594056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1536"} {"level":"info","ts":"2026-07-21T20:54:19.097669Z","caller":"traceutil/trace.go:171","msg":"trace[654977970] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1371; }","duration":"156.242204ms","start":"2026-07-21T20:54:18.941401Z","end":"2026-07-21T20:54:19.097643Z","steps":["trace[654977970] 'agreement among raft nodes before linearized reading' (duration: 155.428934ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:19.261308Z","caller":"traceutil/trace.go:171","msg":"trace[309457733] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"159.103199ms","start":"2026-07-21T20:54:19.102132Z","end":"2026-07-21T20:54:19.261236Z","steps":["trace[309457733] 'process raft request' (duration: 158.336628ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:19.26217Z","caller":"traceutil/trace.go:171","msg":"trace[2072099535] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"158.754995ms","start":"2026-07-21T20:54:19.103396Z","end":"2026-07-21T20:54:19.262151Z","steps":["trace[2072099535] 'process raft request' (duration: 158.41021ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:19.262479Z","caller":"traceutil/trace.go:171","msg":"trace[1609400763] linearizableReadLoop","detail":"{readStateIndex:1434; appliedIndex:1431; }","duration":"150.977715ms","start":"2026-07-21T20:54:19.111469Z","end":"2026-07-21T20:54:19.262447Z","steps":["trace[1609400763] 'read index received' (duration: 149.02804ms)","trace[1609400763] 'applied index is now lower than readState.Index' (duration: 1.947765ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:19.262758Z","caller":"traceutil/trace.go:171","msg":"trace[240996378] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"158.780715ms","start":"2026-07-21T20:54:19.103962Z","end":"2026-07-21T20:54:19.262742Z","steps":["trace[240996378] 'process raft request' (duration: 158.024385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:19.263008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.515702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-07-21T20:54:19.26309Z","caller":"traceutil/trace.go:171","msg":"trace[537699381] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1374; }","duration":"151.629563ms","start":"2026-07-21T20:54:19.11144Z","end":"2026-07-21T20:54:19.26307Z","steps":["trace[537699381] 'agreement among raft nodes before linearized reading' (duration: 151.432691ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:19.520422Z","caller":"traceutil/trace.go:171","msg":"trace[105912213] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"170.467335ms","start":"2026-07-21T20:54:19.349727Z","end":"2026-07-21T20:54:19.520194Z","steps":["trace[105912213] 'process raft request' (duration: 143.362957ms)","trace[105912213] 'compare' (duration: 26.832524ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:20.103577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.279679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:54:20.103753Z","caller":"traceutil/trace.go:171","msg":"trace[1525523117] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"237.709034ms","start":"2026-07-21T20:54:19.866019Z","end":"2026-07-21T20:54:20.103728Z","steps":["trace[1525523117] 'process raft request' (duration: 103.200533ms)","trace[1525523117] 'compare' (duration: 134.084416ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:20.450526Z","caller":"traceutil/trace.go:171","msg":"trace[1977249565] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"341.721442ms","start":"2026-07-21T20:54:20.108768Z","end":"2026-07-21T20:54:20.450489Z","steps":["trace[1977249565] 'process raft request' (duration: 300.851369ms)","trace[1977249565] 'compare' (duration: 40.269176ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:20.451665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:20.108741Z","time spent":"342.818176ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:54:20.478634Z","caller":"traceutil/trace.go:171","msg":"trace[1390993446] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"282.176ms","start":"2026-07-21T20:54:20.19643Z","end":"2026-07-21T20:54:20.478606Z","steps":["trace[1390993446] 'process raft request' (duration: 281.970197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:20.984722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.896321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:54:20.984802Z","caller":"traceutil/trace.go:171","msg":"trace[404642156] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"247.941532ms","start":"2026-07-21T20:54:20.736846Z","end":"2026-07-21T20:54:20.984788Z","steps":["trace[404642156] 'process raft request' (duration: 139.91753ms)","trace[404642156] 'compare' (duration: 107.81239ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:21.400864Z","caller":"traceutil/trace.go:171","msg":"trace[2067327968] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"129.034898ms","start":"2026-07-21T20:54:21.271803Z","end":"2026-07-21T20:54:21.400838Z","steps":["trace[2067327968] 'process raft request' (duration: 128.904607ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:21.566743Z","caller":"traceutil/trace.go:171","msg":"trace[2146221894] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"160.705264ms","start":"2026-07-21T20:54:21.406018Z","end":"2026-07-21T20:54:21.566723Z","steps":["trace[2146221894] 'process raft request' (duration: 160.581772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:21.826789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.036537ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-21T20:54:21.827483Z","caller":"traceutil/trace.go:171","msg":"trace[7580817] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"300.381697ms","start":"2026-07-21T20:54:21.527076Z","end":"2026-07-21T20:54:21.827458Z","steps":["trace[7580817] 'process raft request' (duration: 299.84214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:21.827607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:21.527044Z","time spent":"300.495039ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:54:22.564134Z","caller":"traceutil/trace.go:171","msg":"trace[127713707] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"103.161396ms","start":"2026-07-21T20:54:22.460945Z","end":"2026-07-21T20:54:22.564106Z","steps":["trace[127713707] 'process raft request' (duration: 102.990943ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:23.094814Z","caller":"traceutil/trace.go:171","msg":"trace[475521997] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"102.547427ms","start":"2026-07-21T20:54:22.992226Z","end":"2026-07-21T20:54:23.094773Z","steps":["trace[475521997] 'process raft request' (duration: 102.370845ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:23.321167Z","caller":"traceutil/trace.go:171","msg":"trace[463484712] linearizableReadLoop","detail":"{readStateIndex:1450; appliedIndex:1449; }","duration":"150.955102ms","start":"2026-07-21T20:54:23.170188Z","end":"2026-07-21T20:54:23.321144Z","steps":["trace[463484712] 'read index received' (duration: 150.665979ms)","trace[463484712] 'applied index is now lower than readState.Index' (duration: 288.073µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:23.321333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.133804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:23.321388Z","caller":"traceutil/trace.go:171","msg":"trace[1074763085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1389; }","duration":"151.239336ms","start":"2026-07-21T20:54:23.170137Z","end":"2026-07-21T20:54:23.321377Z","steps":["trace[1074763085] 'agreement among raft nodes before linearized reading' (duration: 151.141405ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:23.321249Z","caller":"traceutil/trace.go:171","msg":"trace[1355189882] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"223.84717ms","start":"2026-07-21T20:54:23.097373Z","end":"2026-07-21T20:54:23.32122Z","steps":["trace[1355189882] 'process raft request' (duration: 223.579697ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:25.581302Z","caller":"traceutil/trace.go:171","msg":"trace[1303575688] linearizableReadLoop","detail":"{readStateIndex:1456; appliedIndex:1455; }","duration":"409.537371ms","start":"2026-07-21T20:54:25.17174Z","end":"2026-07-21T20:54:25.581277Z","steps":["trace[1303575688] 'read index received' (duration: 409.288608ms)","trace[1303575688] 'applied index is now lower than readState.Index' (duration: 247.783µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:25.581425Z","caller":"traceutil/trace.go:171","msg":"trace[1605502689] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"478.696878ms","start":"2026-07-21T20:54:25.102718Z","end":"2026-07-21T20:54:25.581414Z","steps":["trace[1605502689] 'process raft request' (duration: 478.386345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:25.58155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:25.102678Z","time spent":"478.776409ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:54:25.581725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.962756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:25.581795Z","caller":"traceutil/trace.go:171","msg":"trace[1630071985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1395; }","duration":"410.094867ms","start":"2026-07-21T20:54:25.171686Z","end":"2026-07-21T20:54:25.581781Z","steps":["trace[1630071985] 'agreement among raft nodes before linearized reading' (duration: 409.933715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:25.58183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:25.17166Z","time spent":"410.165408ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T20:54:25.581973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.931428ms","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-21T20:54:25.582105Z","caller":"traceutil/trace.go:171","msg":"trace[371674514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1395; }","duration":"185.103251ms","start":"2026-07-21T20:54:25.396978Z","end":"2026-07-21T20:54:25.582081Z","steps":["trace[371674514] 'agreement among raft nodes before linearized reading' (duration: 184.669715ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:26.06148Z","caller":"traceutil/trace.go:171","msg":"trace[1814876470] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"475.366385ms","start":"2026-07-21T20:54:25.586089Z","end":"2026-07-21T20:54:26.061456Z","steps":["trace[1814876470] 'process raft request' (duration: 474.556365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:26.062005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:25.586078Z","time spent":"475.857181ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:54:26.108191Z","caller":"traceutil/trace.go:171","msg":"trace[1198211452] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"521.651802ms","start":"2026-07-21T20:54:25.586505Z","end":"2026-07-21T20:54:26.108157Z","steps":["trace[1198211452] 'process raft request' (duration: 521.371549ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:26.108152Z","caller":"traceutil/trace.go:171","msg":"trace[353785435] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"240.754615ms","start":"2026-07-21T20:54:25.867367Z","end":"2026-07-21T20:54:26.108121Z","steps":["trace[353785435] 'process raft request' (duration: 240.688024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:26.108357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:25.58648Z","time spent":"521.793094ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:54:26.617149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.215441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T20:54:26.617248Z","caller":"traceutil/trace.go:171","msg":"trace[1987425862] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:1398; }","duration":"425.359472ms","start":"2026-07-21T20:54:26.191866Z","end":"2026-07-21T20:54:26.617226Z","steps":["trace[1987425862] 'range keys from in-memory index tree' (duration: 424.988768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:26.617305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:26.191807Z","time spent":"425.482314ms","remote":"127.0.0.1:59576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"info","ts":"2026-07-21T20:54:26.854082Z","caller":"traceutil/trace.go:171","msg":"trace[1140704562] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"232.480318ms","start":"2026-07-21T20:54:26.62157Z","end":"2026-07-21T20:54:26.854051Z","steps":["trace[1140704562] 'process raft request' (duration: 153.053711ms)","trace[1140704562] 'compare' (duration: 79.122653ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:26.854236Z","caller":"traceutil/trace.go:171","msg":"trace[570740142] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"172.205513ms","start":"2026-07-21T20:54:26.682002Z","end":"2026-07-21T20:54:26.854207Z","steps":["trace[570740142] 'process raft request' (duration: 171.885909ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:27.142239Z","caller":"traceutil/trace.go:171","msg":"trace[317473542] linearizableReadLoop","detail":"{readStateIndex:1463; appliedIndex:1462; }","duration":"282.56827ms","start":"2026-07-21T20:54:26.859646Z","end":"2026-07-21T20:54:27.142214Z","steps":["trace[317473542] 'read index received' (duration: 223.425222ms)","trace[317473542] 'applied index is now lower than readState.Index' (duration: 59.142188ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:27.142348Z","caller":"traceutil/trace.go:171","msg":"trace[761771015] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"283.878027ms","start":"2026-07-21T20:54:26.858433Z","end":"2026-07-21T20:54:27.142311Z","steps":["trace[761771015] 'process raft request' (duration: 224.702337ms)","trace[761771015] 'compare' (duration: 58.979457ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:27.142373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.706292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-21T20:54:27.142432Z","caller":"traceutil/trace.go:171","msg":"trace[1774344777] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1401; }","duration":"282.778383ms","start":"2026-07-21T20:54:26.859641Z","end":"2026-07-21T20:54:27.142419Z","steps":["trace[1774344777] 'agreement among raft nodes before linearized reading' (duration: 282.675842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:27.142834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.013803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:54:27.142878Z","caller":"traceutil/trace.go:171","msg":"trace[527138219] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1401; }","duration":"125.102254ms","start":"2026-07-21T20:54:27.017766Z","end":"2026-07-21T20:54:27.142868Z","steps":["trace[527138219] 'agreement among raft nodes before linearized reading' (duration: 125.025893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:27.143183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.4342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:54:27.143243Z","caller":"traceutil/trace.go:171","msg":"trace[950844764] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1401; }","duration":"208.549781ms","start":"2026-07-21T20:54:26.934678Z","end":"2026-07-21T20:54:27.143228Z","steps":["trace[950844764] 'agreement among raft nodes before linearized reading' (duration: 207.66832ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:28.221527Z","caller":"traceutil/trace.go:171","msg":"trace[787889169] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"108.125337ms","start":"2026-07-21T20:54:28.113382Z","end":"2026-07-21T20:54:28.221507Z","steps":["trace[787889169] 'process raft request' (duration: 102.81552ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:28.221828Z","caller":"traceutil/trace.go:171","msg":"trace[1794010302] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"105.844828ms","start":"2026-07-21T20:54:28.115954Z","end":"2026-07-21T20:54:28.221799Z","steps":["trace[1794010302] 'process raft request' (duration: 105.523214ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:29.379971Z","caller":"traceutil/trace.go:171","msg":"trace[51745059] linearizableReadLoop","detail":"{readStateIndex:1469; appliedIndex:1468; }","duration":"211.44683ms","start":"2026-07-21T20:54:29.168507Z","end":"2026-07-21T20:54:29.379954Z","steps":["trace[51745059] 'read index received' (duration: 211.342989ms)","trace[51745059] 'applied index is now lower than readState.Index' (duration: 103.271µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:29.380047Z","caller":"traceutil/trace.go:171","msg":"trace[1385242347] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"229.609548ms","start":"2026-07-21T20:54:29.150408Z","end":"2026-07-21T20:54:29.380018Z","steps":["trace[1385242347] 'process raft request' (duration: 229.446586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:29.380094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.551081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:29.38014Z","caller":"traceutil/trace.go:171","msg":"trace[177245417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1407; }","duration":"211.659492ms","start":"2026-07-21T20:54:29.168467Z","end":"2026-07-21T20:54:29.380127Z","steps":["trace[177245417] 'agreement among raft nodes before linearized reading' (duration: 211.550461ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:30.208992Z","caller":"traceutil/trace.go:171","msg":"trace[452689923] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"114.695725ms","start":"2026-07-21T20:54:30.094263Z","end":"2026-07-21T20:54:30.208959Z","steps":["trace[452689923] 'process raft request' (duration: 114.491372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:30.468384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.65288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:54:30.468607Z","caller":"traceutil/trace.go:171","msg":"trace[236402665] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"239.119624ms","start":"2026-07-21T20:54:30.229469Z","end":"2026-07-21T20:54:30.468589Z","steps":["trace[236402665] 'process raft request' (duration: 239.027582ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:30.468653Z","caller":"traceutil/trace.go:171","msg":"trace[646959200] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"242.569667ms","start":"2026-07-21T20:54:30.226054Z","end":"2026-07-21T20:54:30.468623Z","steps":["trace[646959200] 'process raft request' (duration: 117.469081ms)","trace[646959200] 'compare' (duration: 124.491209ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:30.701016Z","caller":"traceutil/trace.go:171","msg":"trace[1169672688] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"190.45478ms","start":"2026-07-21T20:54:30.510528Z","end":"2026-07-21T20:54:30.700983Z","steps":["trace[1169672688] 'process raft request' (duration: 117.39727ms)","trace[1169672688] 'compare' (duration: 72.731566ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:32.513013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.244756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:32.513088Z","caller":"traceutil/trace.go:171","msg":"trace[312931743] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1416; }","duration":"141.377577ms","start":"2026-07-21T20:54:32.371693Z","end":"2026-07-21T20:54:32.51307Z","steps":["trace[312931743] 'count revisions from in-memory index tree' (duration: 141.145454ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:32.836891Z","caller":"traceutil/trace.go:171","msg":"trace[1708447277] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"319.657799ms","start":"2026-07-21T20:54:32.517207Z","end":"2026-07-21T20:54:32.836864Z","steps":["trace[1708447277] 'process raft request' (duration: 319.331435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:32.837048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:32.517186Z","time spent":"319.78202ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:54:33.142686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.040596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-21T20:54:33.142686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.477812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-07-21T20:54:33.142745Z","caller":"traceutil/trace.go:171","msg":"trace[1249679458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1418; }","duration":"170.135087ms","start":"2026-07-21T20:54:32.972597Z","end":"2026-07-21T20:54:33.142733Z","steps":["trace[1249679458] 'range keys from in-memory index tree' (duration: 169.899264ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:33.142762Z","caller":"traceutil/trace.go:171","msg":"trace[290909465] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1418; }","duration":"166.609993ms","start":"2026-07-21T20:54:32.976134Z","end":"2026-07-21T20:54:33.142744Z","steps":["trace[290909465] 'range keys from in-memory index tree' (duration: 166.240918ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:34.9546Z","caller":"traceutil/trace.go:171","msg":"trace[1092088357] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"108.022815ms","start":"2026-07-21T20:54:34.846528Z","end":"2026-07-21T20:54:34.954551Z","steps":["trace[1092088357] 'process raft request' (duration: 107.889104ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:40.219581Z","caller":"traceutil/trace.go:171","msg":"trace[1217617478] linearizableReadLoop","detail":"{readStateIndex:1510; appliedIndex:1509; }","duration":"316.027785ms","start":"2026-07-21T20:54:39.903533Z","end":"2026-07-21T20:54:40.219561Z","steps":["trace[1217617478] 'read index received' (duration: 315.836307ms)","trace[1217617478] 'applied index is now lower than readState.Index' (duration: 190.828µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:40.219771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.225803ms","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-21T20:54:40.219802Z","caller":"traceutil/trace.go:171","msg":"trace[1694318637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1446; }","duration":"316.328637ms","start":"2026-07-21T20:54:39.903464Z","end":"2026-07-21T20:54:40.219792Z","steps":["trace[1694318637] 'agreement among raft nodes before linearized reading' (duration: 316.199282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:40.219807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.484684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:54:40.219899Z","caller":"traceutil/trace.go:171","msg":"trace[1205393894] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1446; }","duration":"295.6388ms","start":"2026-07-21T20:54:39.92424Z","end":"2026-07-21T20:54:40.219878Z","steps":["trace[1205393894] 'agreement among raft nodes before linearized reading' (duration: 295.483494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:40.219835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:39.903431Z","time spent":"316.395779ms","remote":"127.0.0.1:48246","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-21T20:54:40.220255Z","caller":"traceutil/trace.go:171","msg":"trace[265740481] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"688.419347ms","start":"2026-07-21T20:54:39.531231Z","end":"2026-07-21T20:54:40.21965Z","steps":["trace[265740481] 'process raft request' (duration: 688.214809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:40.220363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:39.531207Z","time spent":"689.091304ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:54:51.621298Z","caller":"traceutil/trace.go:171","msg":"trace[1510986654] linearizableReadLoop","detail":"{readStateIndex:1558; appliedIndex:1557; }","duration":"109.669197ms","start":"2026-07-21T20:54:51.511596Z","end":"2026-07-21T20:54:51.621265Z","steps":["trace[1510986654] 'read index received' (duration: 25.304066ms)","trace[1510986654] 'applied index is now lower than readState.Index' (duration: 84.363301ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:51.621424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.816722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:952"} {"level":"info","ts":"2026-07-21T20:54:51.621462Z","caller":"traceutil/trace.go:171","msg":"trace[595363740] range","detail":"{range_begin:/registry/clusterroles/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1490; }","duration":"109.904585ms","start":"2026-07-21T20:54:51.511548Z","end":"2026-07-21T20:54:51.621453Z","steps":["trace[595363740] 'agreement among raft nodes before linearized reading' (duration: 109.800281ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:55:16.639645Z","caller":"traceutil/trace.go:171","msg":"trace[1888433091] linearizableReadLoop","detail":"{readStateIndex:1645; appliedIndex:1644; }","duration":"194.379771ms","start":"2026-07-21T20:55:16.445244Z","end":"2026-07-21T20:55:16.639623Z","steps":["trace[1888433091] 'read index received' (duration: 23.463426ms)","trace[1888433091] 'applied index is now lower than readState.Index' (duration: 170.915555ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:55:16.639763Z","caller":"traceutil/trace.go:171","msg":"trace[465806840] transaction","detail":"{read_only:false; response_revision:1572; number_of_response:1; }","duration":"212.353257ms","start":"2026-07-21T20:55:16.427354Z","end":"2026-07-21T20:55:16.639707Z","steps":["trace[465806840] 'process raft request' (duration: 179.645005ms)","trace[465806840] 'compare' (duration: 32.530336ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:55:16.639875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.609718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nhq8b\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-07-21T20:55:16.639907Z","caller":"traceutil/trace.go:171","msg":"trace[1498724545] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nhq8b; range_end:; response_count:1; response_revision:1572; }","duration":"194.681041ms","start":"2026-07-21T20:55:16.445218Z","end":"2026-07-21T20:55:16.639899Z","steps":["trace[1498724545] 'agreement among raft nodes before linearized reading' (duration: 194.521806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:55:16.639927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.791957ms","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-21T20:55:16.640063Z","caller":"traceutil/trace.go:171","msg":"trace[2048212672] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1572; }","duration":"104.924551ms","start":"2026-07-21T20:55:16.535066Z","end":"2026-07-21T20:55:16.639991Z","steps":["trace[2048212672] 'agreement among raft nodes before linearized reading' (duration: 104.717264ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:55:18.781342Z","caller":"traceutil/trace.go:171","msg":"trace[1736829812] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"133.748279ms","start":"2026-07-21T20:55:18.647573Z","end":"2026-07-21T20:55:18.781321Z","steps":["trace[1736829812] 'process raft request' (duration: 133.46933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:55:18.781628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.873203ms","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-21T20:55:18.781736Z","caller":"traceutil/trace.go:171","msg":"trace[1924755205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1578; }","duration":"128.995457ms","start":"2026-07-21T20:55:18.652718Z","end":"2026-07-21T20:55:18.781714Z","steps":["trace[1924755205] 'agreement among raft nodes before linearized reading' (duration: 128.757009ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:55:18.781262Z","caller":"traceutil/trace.go:171","msg":"trace[2050204732] linearizableReadLoop","detail":"{readStateIndex:1651; appliedIndex:1650; }","duration":"128.503291ms","start":"2026-07-21T20:55:18.652731Z","end":"2026-07-21T20:55:18.781234Z","steps":["trace[2050204732] 'read index received' (duration: 128.357406ms)","trace[2050204732] 'applied index is now lower than readState.Index' (duration: 144.665µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:55:18.794771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.403789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-07-21T20:55:18.79485Z","caller":"traceutil/trace.go:171","msg":"trace[2013385371] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1579; }","duration":"121.549334ms","start":"2026-07-21T20:55:18.673283Z","end":"2026-07-21T20:55:18.794832Z","steps":["trace[2013385371] 'agreement among raft nodes before linearized reading' (duration: 121.382478ms)"],"step_count":1}