{"level":"warn","ts":"2026-07-08T15:29:04.889216Z","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-08T15:29:04.889291Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.213: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.19.213.213:2380","--initial-cluster=instance=https://199.19.213.213:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.213:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.213: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-08T15:29:04.889354Z","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-08T15:29:04.889362Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.213:2380"]} {"level":"info","ts":"2026-07-08T15:29:04.88938Z","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-08T15:29:04.889966Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"]} {"level":"info","ts":"2026-07-08T15:29:04.89003Z","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.19.213.213:2380"],"listen-peer-urls":["https://199.19.213.213:2380"],"advertise-client-urls":["https://199.19.213.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.213: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-08T15:29:04.898091Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.873007ms"} {"level":"info","ts":"2026-07-08T15:29:04.908225Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3faf6a3dfbe0100","cluster-id":"4c2e50e35fb92a79"} {"level":"info","ts":"2026-07-08T15:29:04.908315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T15:29:04.908337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became follower at term 0"} {"level":"info","ts":"2026-07-08T15:29:04.90835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3faf6a3dfbe0100 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T15:29:04.908362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became follower at term 1"} {"level":"info","ts":"2026-07-08T15:29:04.908408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=(17580635279088025856)"} {"level":"warn","ts":"2026-07-08T15:29:04.917908Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T15:29:04.919907Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T15:29:04.921088Z","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-08T15:29:04.924435Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3faf6a3dfbe0100","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T15:29:04.926609Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3faf6a3dfbe0100","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T15:29:04.92676Z","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-08T15:29:04.926811Z","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-08T15:29:04.926828Z","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-08T15:29:04.929318Z","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-08T15:29:04.929362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=(17580635279088025856)"} {"level":"info","ts":"2026-07-08T15:29:04.929512Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4c2e50e35fb92a79","local-member-id":"f3faf6a3dfbe0100","added-peer-id":"f3faf6a3dfbe0100","added-peer-peer-urls":["https://199.19.213.213:2380"]} {"level":"info","ts":"2026-07-08T15:29:04.929508Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3faf6a3dfbe0100","initial-advertise-peer-urls":["https://199.19.213.213:2380"],"listen-peer-urls":["https://199.19.213.213:2380"],"advertise-client-urls":["https://199.19.213.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T15:29:04.929552Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T15:29:04.929577Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.213:2380"} {"level":"info","ts":"2026-07-08T15:29:04.929582Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.213:2380"} {"level":"info","ts":"2026-07-08T15:29:05.709045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T15:29:05.709089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T15:29:05.709118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 received MsgPreVoteResp from f3faf6a3dfbe0100 at term 1"} {"level":"info","ts":"2026-07-08T15:29:05.709129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became candidate at term 2"} {"level":"info","ts":"2026-07-08T15:29:05.709134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 received MsgVoteResp from f3faf6a3dfbe0100 at term 2"} {"level":"info","ts":"2026-07-08T15:29:05.709143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became leader at term 2"} {"level":"info","ts":"2026-07-08T15:29:05.709152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3faf6a3dfbe0100 elected leader f3faf6a3dfbe0100 at term 2"} {"level":"info","ts":"2026-07-08T15:29:05.710216Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3faf6a3dfbe0100","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.213:2379]}","request-path":"/0/members/f3faf6a3dfbe0100/attributes","cluster-id":"4c2e50e35fb92a79","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T15:29:05.710359Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T15:29:05.710477Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T15:29:05.710815Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T15:29:05.711463Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T15:29:05.711519Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T15:29:05.71264Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.213:2379"} {"level":"info","ts":"2026-07-08T15:29:05.712715Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4c2e50e35fb92a79","local-member-id":"f3faf6a3dfbe0100","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T15:29:05.712858Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T15:29:05.712925Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T15:29:05.71335Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T15:29:08.475856Z","caller":"traceutil/trace.go:171","msg":"trace[1177539031] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"183.475656ms","start":"2026-07-08T15:29:08.29236Z","end":"2026-07-08T15:29:08.475836Z","steps":["trace[1177539031] 'process raft request' (duration: 178.488513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:09.313412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.633344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:29:09.3136Z","caller":"traceutil/trace.go:171","msg":"trace[1546149653] linearizableReadLoop","detail":"{readStateIndex:140; appliedIndex:138; }","duration":"249.182259ms","start":"2026-07-08T15:29:09.064402Z","end":"2026-07-08T15:29:09.313584Z","steps":["trace[1546149653] 'read index received' (duration: 53.972µs)","trace[1546149653] 'applied index is now lower than readState.Index' (duration: 249.127357ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:29:09.313736Z","caller":"traceutil/trace.go:171","msg":"trace[1714091264] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"753.233031ms","start":"2026-07-08T15:29:08.560414Z","end":"2026-07-08T15:29:09.313647Z","steps":["trace[1714091264] 'process raft request' (duration: 22.816343ms)","trace[1714091264] 'compare' (duration: 729.48429ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:29:09.313929Z","caller":"traceutil/trace.go:171","msg":"trace[1484395923] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"753.273961ms","start":"2026-07-08T15:29:08.560619Z","end":"2026-07-08T15:29:09.313893Z","steps":["trace[1484395923] 'process raft request' (duration: 752.90913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:09.313929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:08.560388Z","time spent":"753.451388ms","remote":"127.0.0.1:36452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:29:09.313936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.520689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T15:29:09.314171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:08.560594Z","time spent":"753.419846ms","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:29:09.314229Z","caller":"traceutil/trace.go:171","msg":"trace[1157715697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:135; }","duration":"249.85365ms","start":"2026-07-08T15:29:09.064352Z","end":"2026-07-08T15:29:09.314206Z","steps":["trace[1157715697] 'agreement among raft nodes before linearized reading' (duration: 249.362694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:10.729702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.318391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:29:10.729948Z","caller":"traceutil/trace.go:171","msg":"trace[813023442] linearizableReadLoop","detail":"{readStateIndex:142; appliedIndex:140; }","duration":"1.104942754s","start":"2026-07-08T15:29:09.624983Z","end":"2026-07-08T15:29:10.729926Z","steps":["trace[813023442] 'read index received' (duration: 183.413946ms)","trace[813023442] 'applied index is now lower than readState.Index' (duration: 921.527608ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:29:10.729978Z","caller":"traceutil/trace.go:171","msg":"trace[1988403061] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"1.411796337s","start":"2026-07-08T15:29:09.318113Z","end":"2026-07-08T15:29:10.72991Z","steps":["trace[1988403061] 'process raft request' (duration: 490.204078ms)","trace[1988403061] 'compare' (duration: 921.194057ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:29:10.73017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:09.318092Z","time spent":"1.411958122s","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:29:10.730251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.416628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T15:29:10.730336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.248226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:29:10.730352Z","caller":"traceutil/trace.go:171","msg":"trace[550122121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:137; }","duration":"233.577974ms","start":"2026-07-08T15:29:10.496756Z","end":"2026-07-08T15:29:10.730334Z","steps":["trace[550122121] 'agreement among raft nodes before linearized reading' (duration: 233.399549ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:29:10.730383Z","caller":"traceutil/trace.go:171","msg":"trace[1480237346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:137; }","duration":"566.37631ms","start":"2026-07-08T15:29:10.163993Z","end":"2026-07-08T15:29:10.73037Z","steps":["trace[1480237346] 'agreement among raft nodes before linearized reading' (duration: 566.094692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:10.730427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:10.163978Z","time spent":"566.440451ms","remote":"127.0.0.1:36060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T15:29:10.730319Z","caller":"traceutil/trace.go:171","msg":"trace[1041978760] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"1.40993818s","start":"2026-07-08T15:29:09.320259Z","end":"2026-07-08T15:29:10.730197Z","steps":["trace[1041978760] 'process raft request' (duration: 1.409563369s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:10.730611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:09.320248Z","time spent":"1.410313952s","remote":"127.0.0.1:36452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:29:10.730731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105741098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:29:10.730777Z","caller":"traceutil/trace.go:171","msg":"trace[1391369076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:137; }","duration":"1.10581345s","start":"2026-07-08T15:29:09.624953Z","end":"2026-07-08T15:29:10.730767Z","steps":["trace[1391369076] 'agreement among raft nodes before linearized reading' (duration: 1.105118869s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:10.730885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:09.624938Z","time spent":"1.105937763s","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-08T15:29:10.953636Z","caller":"traceutil/trace.go:171","msg":"trace[603479819] linearizableReadLoop","detail":"{readStateIndex:144; appliedIndex:142; }","duration":"218.089213ms","start":"2026-07-08T15:29:10.735522Z","end":"2026-07-08T15:29:10.953612Z","steps":["trace[603479819] 'read index received' (duration: 209.904534ms)","trace[603479819] 'applied index is now lower than readState.Index' (duration: 8.183759ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:29:10.953667Z","caller":"traceutil/trace.go:171","msg":"trace[1442167768] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"218.417622ms","start":"2026-07-08T15:29:10.735235Z","end":"2026-07-08T15:29:10.953653Z","steps":["trace[1442167768] 'process raft request' (duration: 218.310359ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:29:10.953709Z","caller":"traceutil/trace.go:171","msg":"trace[1635925787] transaction","detail":"{read_only:false; response_revision:140; number_of_response:1; }","duration":"218.159795ms","start":"2026-07-08T15:29:10.735537Z","end":"2026-07-08T15:29:10.953697Z","steps":["trace[1635925787] 'process raft request' (duration: 218.049051ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:29:10.953681Z","caller":"traceutil/trace.go:171","msg":"trace[139819597] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"218.700801ms","start":"2026-07-08T15:29:10.734921Z","end":"2026-07-08T15:29:10.953622Z","steps":["trace[139819597] 'process raft request' (duration: 210.549463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:10.953868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.32862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:29:10.953911Z","caller":"traceutil/trace.go:171","msg":"trace[12676960] range","detail":"{range_begin:/registry/clusterroles/system:controller:root-ca-cert-publisher; range_end:; response_count:0; response_revision:140; }","duration":"218.381882ms","start":"2026-07-08T15:29:10.735517Z","end":"2026-07-08T15:29:10.953898Z","steps":["trace[12676960] 'agreement among raft nodes before linearized reading' (duration: 218.272388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:11.75661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.664183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:29:11.756845Z","caller":"traceutil/trace.go:171","msg":"trace[2075016892] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"130.466772ms","start":"2026-07-08T15:29:11.626351Z","end":"2026-07-08T15:29:11.756817Z","steps":["trace[2075016892] 'process raft request' (duration: 19.528812ms)","trace[2075016892] 'compare' (duration: 110.480397ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:29:13.048409Z","caller":"traceutil/trace.go:171","msg":"trace[2138212178] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"202.53381ms","start":"2026-07-08T15:29:12.845837Z","end":"2026-07-08T15:29:13.04837Z","steps":["trace[2138212178] 'process raft request' (duration: 202.197429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:13.313682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.532776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:29:13.313783Z","caller":"traceutil/trace.go:171","msg":"trace[463888735] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"261.269867ms","start":"2026-07-08T15:29:13.052499Z","end":"2026-07-08T15:29:13.313768Z","steps":["trace[463888735] 'process raft request' (duration: 96.440102ms)","trace[463888735] 'compare' (duration: 164.342181ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:29:13.314274Z","caller":"traceutil/trace.go:171","msg":"trace[872320589] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:259; }","duration":"260.393312ms","start":"2026-07-08T15:29:13.053855Z","end":"2026-07-08T15:29:13.314249Z","steps":["trace[872320589] 'read index received' (duration: 95.094133ms)","trace[872320589] 'applied index is now lower than readState.Index' (duration: 165.298389ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:29:13.314415Z","caller":"traceutil/trace.go:171","msg":"trace[774592402] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"260.891686ms","start":"2026-07-08T15:29:13.053511Z","end":"2026-07-08T15:29:13.314403Z","steps":["trace[774592402] 'process raft request' (duration: 260.611098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:13.314478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.576588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:29:13.314524Z","caller":"traceutil/trace.go:171","msg":"trace[1105472357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:256; }","duration":"260.698161ms","start":"2026-07-08T15:29:13.053812Z","end":"2026-07-08T15:29:13.31451Z","steps":["trace[1105472357] 'agreement among raft nodes before linearized reading' (duration: 260.551716ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:29:13.314507Z","caller":"traceutil/trace.go:171","msg":"trace[1062179246] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"259.250877ms","start":"2026-07-08T15:29:13.055155Z","end":"2026-07-08T15:29:13.314406Z","steps":["trace[1062179246] 'process raft request' (duration: 259.052961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:36.643001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.936183ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-08T15:29:43.729257Z","caller":"traceutil/trace.go:171","msg":"trace[608797338] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"124.398719ms","start":"2026-07-08T15:29:43.604818Z","end":"2026-07-08T15:29:43.729217Z","steps":["trace[608797338] 'process raft request' (duration: 113.195062ms)","trace[608797338] 'compare' (duration: 10.915468ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:29:43.730255Z","caller":"traceutil/trace.go:171","msg":"trace[1779863892] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"124.944493ms","start":"2026-07-08T15:29:43.605288Z","end":"2026-07-08T15:29:43.730233Z","steps":["trace[1779863892] 'process raft request' (duration: 124.04167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:44.343974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.219471ms","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-08T15:29:44.34417Z","caller":"traceutil/trace.go:171","msg":"trace[1398588552] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:493; }","duration":"348.429436ms","start":"2026-07-08T15:29:43.995718Z","end":"2026-07-08T15:29:44.344148Z","steps":["trace[1398588552] 'range keys from in-memory index tree' (duration: 348.202121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:44.344589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.221345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:29:44.344706Z","caller":"traceutil/trace.go:171","msg":"trace[496839030] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"566.533201ms","start":"2026-07-08T15:29:43.778127Z","end":"2026-07-08T15:29:44.344661Z","steps":["trace[496839030] 'process raft request' (duration: 79.187092ms)","trace[496839030] 'compare' (duration: 486.696202ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:29:44.344882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:43.778096Z","time spent":"566.707085ms","remote":"127.0.0.1:36194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6516,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:29:46.320163Z","caller":"traceutil/trace.go:171","msg":"trace[2089579938] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"369.507018ms","start":"2026-07-08T15:29:45.950632Z","end":"2026-07-08T15:29:46.320139Z","steps":["trace[2089579938] 'process raft request' (duration: 369.363205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:46.320347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:45.950612Z","time spent":"369.629962ms","remote":"127.0.0.1:36140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:29:46.328807Z","caller":"traceutil/trace.go:171","msg":"trace[1373990833] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"362.31769ms","start":"2026-07-08T15:29:45.966471Z","end":"2026-07-08T15:29:46.328789Z","steps":["trace[1373990833] 'process raft request' (duration: 361.983181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:46.328962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:45.966443Z","time spent":"362.420803ms","remote":"127.0.0.1:36140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:29:46.32914Z","caller":"traceutil/trace.go:171","msg":"trace[648894322] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"372.038345ms","start":"2026-07-08T15:29:45.957092Z","end":"2026-07-08T15:29:46.32913Z","steps":["trace[648894322] 'process raft request' (duration: 371.206733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:46.329215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:45.957071Z","time spent":"372.099486ms","remote":"127.0.0.1:36140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:29:46.32931Z","caller":"traceutil/trace.go:171","msg":"trace[1634200887] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"362.845614ms","start":"2026-07-08T15:29:45.966455Z","end":"2026-07-08T15:29:46.3293Z","steps":["trace[1634200887] 'process raft request' (duration: 361.94872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:46.329392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:45.966425Z","time spent":"362.911165ms","remote":"127.0.0.1:36140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:29:46.329412Z","caller":"traceutil/trace.go:171","msg":"trace[729211733] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"361.902749ms","start":"2026-07-08T15:29:45.967488Z","end":"2026-07-08T15:29:46.329391Z","steps":["trace[729211733] 'process raft request' (duration: 361.027615ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:29:46.329499Z","caller":"traceutil/trace.go:171","msg":"trace[1098473207] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"342.395136ms","start":"2026-07-08T15:29:45.987094Z","end":"2026-07-08T15:29:46.329489Z","steps":["trace[1098473207] 'process raft request' (duration: 341.680567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:46.329531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:45.967471Z","time spent":"362.004901ms","remote":"127.0.0.1:36140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:29:46.329562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:45.987075Z","time spent":"342.470619ms","remote":"127.0.0.1:36140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:29:46.329642Z","caller":"traceutil/trace.go:171","msg":"trace[1012235972] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"360.145733ms","start":"2026-07-08T15:29:45.96949Z","end":"2026-07-08T15:29:46.329635Z","steps":["trace[1012235972] 'process raft request' (duration: 359.064764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:46.329719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:45.96948Z","time spent":"360.189084ms","remote":"127.0.0.1:36140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:29:46.329811Z","caller":"traceutil/trace.go:171","msg":"trace[230117060] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"357.131394ms","start":"2026-07-08T15:29:45.972673Z","end":"2026-07-08T15:29:46.329805Z","steps":["trace[230117060] 'process raft request' (duration: 356.025935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:46.329872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:45.972668Z","time spent":"357.163285ms","remote":"127.0.0.1:36140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:29:46.329893Z","caller":"traceutil/trace.go:171","msg":"trace[248175131] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"323.0733ms","start":"2026-07-08T15:29:46.006798Z","end":"2026-07-08T15:29:46.329871Z","steps":["trace[248175131] 'process raft request' (duration: 322.041514ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:29:46.329687Z","caller":"traceutil/trace.go:171","msg":"trace[665617007] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"357.034242ms","start":"2026-07-08T15:29:45.972624Z","end":"2026-07-08T15:29:46.329658Z","steps":["trace[665617007] 'process raft request' (duration: 355.980534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:46.329985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:46.006776Z","time spent":"323.169742ms","remote":"127.0.0.1:36140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:29:46.330047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:45.972615Z","time spent":"357.37204ms","remote":"127.0.0.1:36140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:29:46.330118Z","caller":"traceutil/trace.go:171","msg":"trace[536479923] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"310.584503ms","start":"2026-07-08T15:29:46.019518Z","end":"2026-07-08T15:29:46.330102Z","steps":["trace[536479923] 'process raft request' (duration: 309.391302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:46.330204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:46.019497Z","time spent":"310.672536ms","remote":"127.0.0.1:36140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:29:46.330219Z","caller":"traceutil/trace.go:171","msg":"trace[1444530212] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"203.299957ms","start":"2026-07-08T15:29:46.126891Z","end":"2026-07-08T15:29:46.330191Z","steps":["trace[1444530212] 'process raft request' (duration: 202.213569ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:29:47.393685Z","caller":"traceutil/trace.go:171","msg":"trace[1040255710] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"289.392205ms","start":"2026-07-08T15:29:47.104269Z","end":"2026-07-08T15:29:47.393662Z","steps":["trace[1040255710] 'process raft request' (duration: 289.243341ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:29:47.404867Z","caller":"traceutil/trace.go:171","msg":"trace[332944581] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"210.445357ms","start":"2026-07-08T15:29:47.194408Z","end":"2026-07-08T15:29:47.404853Z","steps":["trace[332944581] 'process raft request' (duration: 209.414999ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:29:52.844523Z","caller":"traceutil/trace.go:171","msg":"trace[420139909] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"210.783436ms","start":"2026-07-08T15:29:52.633718Z","end":"2026-07-08T15:29:52.844501Z","steps":["trace[420139909] 'process raft request' (duration: 202.827172ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:29:53.46025Z","caller":"traceutil/trace.go:171","msg":"trace[1612457299] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"567.759114ms","start":"2026-07-08T15:29:52.892462Z","end":"2026-07-08T15:29:53.460221Z","steps":["trace[1612457299] 'process raft request' (duration: 567.552829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:53.460423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:52.892437Z","time spent":"567.904208ms","remote":"127.0.0.1:36274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:29:53.485515Z","caller":"traceutil/trace.go:171","msg":"trace[327659134] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"174.877141ms","start":"2026-07-08T15:29:53.310613Z","end":"2026-07-08T15:29:53.48549Z","steps":["trace[327659134] 'read index received' (duration: 149.39524ms)","trace[327659134] 'applied index is now lower than readState.Index' (duration: 25.480871ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:29:53.485663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.025345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:29:53.48565Z","caller":"traceutil/trace.go:171","msg":"trace[346792648] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"226.552793ms","start":"2026-07-08T15:29:53.259033Z","end":"2026-07-08T15:29:53.485585Z","steps":["trace[346792648] 'process raft request' (duration: 225.956478ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:29:53.485708Z","caller":"traceutil/trace.go:171","msg":"trace[658844270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"175.086536ms","start":"2026-07-08T15:29:53.310606Z","end":"2026-07-08T15:29:53.485693Z","steps":["trace[658844270] 'agreement among raft nodes before linearized reading' (duration: 174.995304ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:29:54.048138Z","caller":"traceutil/trace.go:171","msg":"trace[112367888] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"378.33691ms","start":"2026-07-08T15:29:53.669776Z","end":"2026-07-08T15:29:54.048113Z","steps":["trace[112367888] 'read index received' (duration: 378.103234ms)","trace[112367888] 'applied index is now lower than readState.Index' (duration: 232.826µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:29:54.048172Z","caller":"traceutil/trace.go:171","msg":"trace[2038096949] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"505.777778ms","start":"2026-07-08T15:29:53.54237Z","end":"2026-07-08T15:29:54.048148Z","steps":["trace[2038096949] 'process raft request' (duration: 505.585453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:54.048325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:53.542323Z","time spent":"505.920302ms","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:29:54.048381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.597056ms","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-08T15:29:54.048425Z","caller":"traceutil/trace.go:171","msg":"trace[106586633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"378.690648ms","start":"2026-07-08T15:29:53.669721Z","end":"2026-07-08T15:29:54.048412Z","steps":["trace[106586633] 'agreement among raft nodes before linearized reading' (duration: 378.510934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:54.048437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.285952ms","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-08T15:29:54.048465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:53.669697Z","time spent":"378.75951ms","remote":"127.0.0.1:36360","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-08T15:29:54.048495Z","caller":"traceutil/trace.go:171","msg":"trace[1329134518] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"350.416696ms","start":"2026-07-08T15:29:53.698064Z","end":"2026-07-08T15:29:54.048481Z","steps":["trace[1329134518] 'agreement among raft nodes before linearized reading' (duration: 350.219431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:54.048498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.382585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-08T15:29:54.048538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:53.698043Z","time spent":"350.487297ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T15:29:54.04857Z","caller":"traceutil/trace.go:171","msg":"trace[1651651995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"228.498568ms","start":"2026-07-08T15:29:53.820054Z","end":"2026-07-08T15:29:54.048553Z","steps":["trace[1651651995] 'agreement among raft nodes before linearized reading' (duration: 228.264152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:54.813777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72232701344715166,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:29:55.314534Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72232701344715166,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:29:55.814673Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72232701344715166,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:29:56.232246Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.08960457s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T15:29:56.252316Z","caller":"traceutil/trace.go:171","msg":"trace[2122778057] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"2.123426759s","start":"2026-07-08T15:29:54.128867Z","end":"2026-07-08T15:29:56.252293Z","steps":["trace[2122778057] 'process raft request' (duration: 2.103698319s)","trace[2122778057] 'compare' (duration: 18.343864ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:29:56.252414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:54.128852Z","time spent":"2.123518051s","remote":"127.0.0.1:36274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:29:56.253583Z","caller":"traceutil/trace.go:171","msg":"trace[16218043] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:670; }","duration":"1.940644728s","start":"2026-07-08T15:29:54.312912Z","end":"2026-07-08T15:29:56.253556Z","steps":["trace[16218043] 'read index received' (duration: 1.919685588s)","trace[16218043] 'applied index is now lower than readState.Index' (duration: 20.95795ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:29:56.253885Z","caller":"traceutil/trace.go:171","msg":"trace[259413287] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.742509259s","start":"2026-07-08T15:29:54.511359Z","end":"2026-07-08T15:29:56.253868Z","steps":["trace[259413287] 'process raft request' (duration: 1.742070938s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:56.254022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:54.511341Z","time spent":"1.742597851s","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:29:56.254272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.941365248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:29:56.254533Z","caller":"traceutil/trace.go:171","msg":"trace[1077457899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"1.941659735s","start":"2026-07-08T15:29:54.312855Z","end":"2026-07-08T15:29:56.254515Z","steps":["trace[1077457899] 'agreement among raft nodes before linearized reading' (duration: 1.941376368s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:56.2546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:54.312835Z","time spent":"1.941749317s","remote":"127.0.0.1:36060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T15:29:56.254648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.788842631s","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-08T15:29:56.254732Z","caller":"traceutil/trace.go:171","msg":"trace[1347840987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"1.788958963s","start":"2026-07-08T15:29:54.465742Z","end":"2026-07-08T15:29:56.254701Z","steps":["trace[1347840987] 'agreement among raft nodes before linearized reading' (duration: 1.788755308s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:56.254781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:54.465724Z","time spent":"1.789047636s","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T15:29:56.255061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.2203ms","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-08T15:29:56.255102Z","caller":"traceutil/trace.go:171","msg":"trace[1306821496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"132.261411ms","start":"2026-07-08T15:29:56.122831Z","end":"2026-07-08T15:29:56.255092Z","steps":["trace[1306821496] 'agreement among raft nodes before linearized reading' (duration: 132.163998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:56.255135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.387624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-08T15:29:56.255149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.753884ms","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-08T15:29:56.255194Z","caller":"traceutil/trace.go:171","msg":"trace[1344170537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"132.488177ms","start":"2026-07-08T15:29:56.122691Z","end":"2026-07-08T15:29:56.255179Z","steps":["trace[1344170537] 'agreement among raft nodes before linearized reading' (duration: 132.318403ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:29:56.255204Z","caller":"traceutil/trace.go:171","msg":"trace[1245068315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:647; }","duration":"162.844376ms","start":"2026-07-08T15:29:56.092343Z","end":"2026-07-08T15:29:56.255188Z","steps":["trace[1245068315] 'agreement among raft nodes before linearized reading' (duration: 162.676942ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:29:58.644013Z","caller":"traceutil/trace.go:171","msg":"trace[407118272] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:712; }","duration":"371.64629ms","start":"2026-07-08T15:29:58.272344Z","end":"2026-07-08T15:29:58.643991Z","steps":["trace[407118272] 'read index received' (duration: 371.572349ms)","trace[407118272] 'applied index is now lower than readState.Index' (duration: 72.861µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:29:58.644115Z","caller":"traceutil/trace.go:171","msg":"trace[477309058] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"435.857217ms","start":"2026-07-08T15:29:58.208248Z","end":"2026-07-08T15:29:58.644105Z","steps":["trace[477309058] 'process raft request' (duration: 435.618461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:58.644213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:58.208229Z","time spent":"435.93183ms","remote":"127.0.0.1:36140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:29:58.644619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.251745ms","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-08T15:29:58.644809Z","caller":"traceutil/trace.go:171","msg":"trace[1291743013] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:687; }","duration":"372.489231ms","start":"2026-07-08T15:29:58.272296Z","end":"2026-07-08T15:29:58.644785Z","steps":["trace[1291743013] 'agreement among raft nodes before linearized reading' (duration: 371.912957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:58.644941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:58.272274Z","time spent":"372.644834ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T15:29:58.647827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.930723ms","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-08T15:29:58.647905Z","caller":"traceutil/trace.go:171","msg":"trace[5776751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:689; }","duration":"375.055816ms","start":"2026-07-08T15:29:58.272833Z","end":"2026-07-08T15:29:58.647889Z","steps":["trace[5776751] 'agreement among raft nodes before linearized reading' (duration: 374.873302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:58.647988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:58.272813Z","time spent":"375.161098ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-08T15:29:58.648137Z","caller":"traceutil/trace.go:171","msg":"trace[919917882] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"238.942627ms","start":"2026-07-08T15:29:58.409183Z","end":"2026-07-08T15:29:58.648125Z","steps":["trace[919917882] 'process raft request' (duration: 238.487246ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:29:58.648197Z","caller":"traceutil/trace.go:171","msg":"trace[1637267741] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"247.641306ms","start":"2026-07-08T15:29:58.400538Z","end":"2026-07-08T15:29:58.64818Z","steps":["trace[1637267741] 'process raft request' (duration: 247.037471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:58.648417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.597615ms","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-08T15:29:58.648522Z","caller":"traceutil/trace.go:171","msg":"trace[452093232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:689; }","duration":"374.766559ms","start":"2026-07-08T15:29:58.273742Z","end":"2026-07-08T15:29:58.648509Z","steps":["trace[452093232] 'agreement among raft nodes before linearized reading' (duration: 374.39879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:58.648582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.036295ms","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-08T15:29:58.648658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:58.273729Z","time spent":"374.860951ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T15:29:58.648612Z","caller":"traceutil/trace.go:171","msg":"trace[737832875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:689; }","duration":"375.084217ms","start":"2026-07-08T15:29:58.27352Z","end":"2026-07-08T15:29:58.648605Z","steps":["trace[737832875] 'agreement among raft nodes before linearized reading' (duration: 374.907273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:58.648793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:29:58.273512Z","time spent":"375.272032ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-08T15:30:00.81603Z","caller":"traceutil/trace.go:171","msg":"trace[1449270058] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:739; }","duration":"155.089454ms","start":"2026-07-08T15:30:00.660924Z","end":"2026-07-08T15:30:00.816013Z","steps":["trace[1449270058] 'read index received' (duration: 154.91951ms)","trace[1449270058] 'applied index is now lower than readState.Index' (duration: 169.254µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:30:00.816105Z","caller":"traceutil/trace.go:171","msg":"trace[1708622948] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"163.258948ms","start":"2026-07-08T15:30:00.652799Z","end":"2026-07-08T15:30:00.816058Z","steps":["trace[1708622948] 'process raft request' (duration: 163.063243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:00.816295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.322519ms","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-08T15:30:00.816364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.010481ms","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-08T15:30:00.816382Z","caller":"traceutil/trace.go:171","msg":"trace[199495083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:714; }","duration":"155.495854ms","start":"2026-07-08T15:30:00.66087Z","end":"2026-07-08T15:30:00.816366Z","steps":["trace[199495083] 'agreement among raft nodes before linearized reading' (duration: 155.252198ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:00.816405Z","caller":"traceutil/trace.go:171","msg":"trace[1281351117] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"155.069903ms","start":"2026-07-08T15:30:00.661323Z","end":"2026-07-08T15:30:00.816393Z","steps":["trace[1281351117] 'agreement among raft nodes before linearized reading' (duration: 154.861228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:00.816654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.607806ms","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-08T15:30:00.816722Z","caller":"traceutil/trace.go:171","msg":"trace[398058371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:714; }","duration":"155.697848ms","start":"2026-07-08T15:30:00.661006Z","end":"2026-07-08T15:30:00.816704Z","steps":["trace[398058371] 'agreement among raft nodes before linearized reading' (duration: 155.159565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:00.816927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.005736ms","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-08T15:30:00.816967Z","caller":"traceutil/trace.go:171","msg":"trace[1211512538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"156.085378ms","start":"2026-07-08T15:30:00.66087Z","end":"2026-07-08T15:30:00.816956Z","steps":["trace[1211512538] 'agreement among raft nodes before linearized reading' (duration: 155.227547ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:01.001551Z","caller":"traceutil/trace.go:171","msg":"trace[1224650877] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"179.961126ms","start":"2026-07-08T15:30:00.821559Z","end":"2026-07-08T15:30:01.00152Z","steps":["trace[1224650877] 'process raft request' (duration: 179.062664ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:01.001986Z","caller":"traceutil/trace.go:171","msg":"trace[2097011122] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"180.326975ms","start":"2026-07-08T15:30:00.821642Z","end":"2026-07-08T15:30:01.001969Z","steps":["trace[2097011122] 'process raft request' (duration: 179.873554ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:01.002317Z","caller":"traceutil/trace.go:171","msg":"trace[695127809] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"180.665343ms","start":"2026-07-08T15:30:00.821608Z","end":"2026-07-08T15:30:01.002273Z","steps":["trace[695127809] 'process raft request' (duration: 179.851513ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:01.00239Z","caller":"traceutil/trace.go:171","msg":"trace[640172512] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"171.469404ms","start":"2026-07-08T15:30:00.830902Z","end":"2026-07-08T15:30:01.002371Z","steps":["trace[640172512] 'process raft request' (duration: 170.663754ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:01.03406Z","caller":"traceutil/trace.go:171","msg":"trace[705437635] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"103.361126ms","start":"2026-07-08T15:30:00.930666Z","end":"2026-07-08T15:30:01.034027Z","steps":["trace[705437635] 'process raft request' (duration: 103.172441ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:01.394342Z","caller":"traceutil/trace.go:171","msg":"trace[803670563] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"157.315028ms","start":"2026-07-08T15:30:01.237001Z","end":"2026-07-08T15:30:01.394316Z","steps":["trace[803670563] 'process raft request' (duration: 157.053412ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:09.139985Z","caller":"traceutil/trace.go:171","msg":"trace[1727414476] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"156.003004ms","start":"2026-07-08T15:30:08.983922Z","end":"2026-07-08T15:30:09.139924Z","steps":["trace[1727414476] 'process raft request' (duration: 155.444361ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:09.141042Z","caller":"traceutil/trace.go:171","msg":"trace[1362018400] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:814; }","duration":"106.377851ms","start":"2026-07-08T15:30:09.034644Z","end":"2026-07-08T15:30:09.141022Z","steps":["trace[1362018400] 'read index received' (duration: 104.645178ms)","trace[1362018400] 'applied index is now lower than readState.Index' (duration: 1.731633ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:30:09.141291Z","caller":"traceutil/trace.go:171","msg":"trace[582020566] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"140.524165ms","start":"2026-07-08T15:30:09.000755Z","end":"2026-07-08T15:30:09.141279Z","steps":["trace[582020566] 'process raft request' (duration: 140.161596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:09.141546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.074974ms","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-08T15:30:09.141623Z","caller":"traceutil/trace.go:171","msg":"trace[1228503578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:787; }","duration":"104.226068ms","start":"2026-07-08T15:30:09.037378Z","end":"2026-07-08T15:30:09.141604Z","steps":["trace[1228503578] 'agreement among raft nodes before linearized reading' (duration: 103.984402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:09.141719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.450369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-08T15:30:09.141766Z","caller":"traceutil/trace.go:171","msg":"trace[404693185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:787; }","duration":"103.53796ms","start":"2026-07-08T15:30:09.038216Z","end":"2026-07-08T15:30:09.141754Z","steps":["trace[404693185] 'agreement among raft nodes before linearized reading' (duration: 103.378357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:09.144512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.844965ms","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-08T15:30:09.144593Z","caller":"traceutil/trace.go:171","msg":"trace[254123478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:787; }","duration":"109.965688ms","start":"2026-07-08T15:30:09.034596Z","end":"2026-07-08T15:30:09.144561Z","steps":["trace[254123478] 'agreement among raft nodes before linearized reading' (duration: 109.733062ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:12.240203Z","caller":"traceutil/trace.go:171","msg":"trace[393634364] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"346.331659ms","start":"2026-07-08T15:30:11.893841Z","end":"2026-07-08T15:30:12.240173Z","steps":["trace[393634364] 'process raft request' (duration: 346.152575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:12.240369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:30:11.893824Z","time spent":"346.461602ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:30:12.434212Z","caller":"traceutil/trace.go:171","msg":"trace[1822902743] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"428.561788ms","start":"2026-07-08T15:30:12.005627Z","end":"2026-07-08T15:30:12.434189Z","steps":["trace[1822902743] 'process raft request' (duration: 428.382303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:12.434412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:30:12.005592Z","time spent":"428.698041ms","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:30:26.494453Z","caller":"traceutil/trace.go:171","msg":"trace[382158924] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"179.88063ms","start":"2026-07-08T15:30:26.31455Z","end":"2026-07-08T15:30:26.49443Z","steps":["trace[382158924] 'read index received' (duration: 179.578313ms)","trace[382158924] 'applied index is now lower than readState.Index' (duration: 301.467µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:30:26.494586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.018464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:30:26.494615Z","caller":"traceutil/trace.go:171","msg":"trace[585562598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"180.104625ms","start":"2026-07-08T15:30:26.314502Z","end":"2026-07-08T15:30:26.494606Z","steps":["trace[585562598] 'agreement among raft nodes before linearized reading' (duration: 180.025254ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:26.494946Z","caller":"traceutil/trace.go:171","msg":"trace[763169785] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"185.970024ms","start":"2026-07-08T15:30:26.308964Z","end":"2026-07-08T15:30:26.494934Z","steps":["trace[763169785] 'process raft request' (duration: 185.224747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:26.698144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.751774ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-08T15:30:26.698389Z","caller":"traceutil/trace.go:171","msg":"trace[101188318] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"169.794904ms","start":"2026-07-08T15:30:26.528574Z","end":"2026-07-08T15:30:26.698369Z","steps":["trace[101188318] 'process raft request' (duration: 169.667001ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:27.260398Z","caller":"traceutil/trace.go:171","msg":"trace[840254792] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"159.142835ms","start":"2026-07-08T15:30:27.101223Z","end":"2026-07-08T15:30:27.260365Z","steps":["trace[840254792] 'process raft request' (duration: 151.626399ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:27.437787Z","caller":"traceutil/trace.go:171","msg":"trace[831896840] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"149.813626ms","start":"2026-07-08T15:30:27.287937Z","end":"2026-07-08T15:30:27.43775Z","steps":["trace[831896840] 'process raft request' (duration: 110.952634ms)","trace[831896840] 'compare' (duration: 38.570716ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:30:27.579143Z","caller":"traceutil/trace.go:171","msg":"trace[248456196] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"137.144099ms","start":"2026-07-08T15:30:27.441966Z","end":"2026-07-08T15:30:27.57911Z","steps":["trace[248456196] 'process raft request' (duration: 126.402047ms)","trace[248456196] 'compare' (duration: 10.550857ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:30:53.514839Z","caller":"traceutil/trace.go:171","msg":"trace[997240266] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1120; }","duration":"199.063155ms","start":"2026-07-08T15:30:53.315751Z","end":"2026-07-08T15:30:53.514814Z","steps":["trace[997240266] 'read index received' (duration: 198.902762ms)","trace[997240266] 'applied index is now lower than readState.Index' (duration: 159.433µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:30:53.514929Z","caller":"traceutil/trace.go:171","msg":"trace[1010651108] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"201.764656ms","start":"2026-07-08T15:30:53.313131Z","end":"2026-07-08T15:30:53.514896Z","steps":["trace[1010651108] 'process raft request' (duration: 201.497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:53.515036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.246109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:30:53.51508Z","caller":"traceutil/trace.go:171","msg":"trace[754093808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1080; }","duration":"199.354302ms","start":"2026-07-08T15:30:53.315715Z","end":"2026-07-08T15:30:53.515069Z","steps":["trace[754093808] 'agreement among raft nodes before linearized reading' (duration: 199.244879ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:55.6974Z","caller":"traceutil/trace.go:171","msg":"trace[1757162075] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1126; }","duration":"171.876534ms","start":"2026-07-08T15:30:55.525497Z","end":"2026-07-08T15:30:55.697373Z","steps":["trace[1757162075] 'read index received' (duration: 171.67009ms)","trace[1757162075] 'applied index is now lower than readState.Index' (duration: 205.484µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:30:55.697484Z","caller":"traceutil/trace.go:171","msg":"trace[1623838094] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"175.466005ms","start":"2026-07-08T15:30:55.521985Z","end":"2026-07-08T15:30:55.697451Z","steps":["trace[1623838094] 'process raft request' (duration: 175.167138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:55.697652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.870659ms","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-08T15:30:55.69769Z","caller":"traceutil/trace.go:171","msg":"trace[1885452021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1086; }","duration":"169.946591ms","start":"2026-07-08T15:30:55.527733Z","end":"2026-07-08T15:30:55.69768Z","steps":["trace[1885452021] 'agreement among raft nodes before linearized reading' (duration: 169.811378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:55.697702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.190321ms","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-08T15:30:55.697766Z","caller":"traceutil/trace.go:171","msg":"trace[206543530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1086; }","duration":"172.279513ms","start":"2026-07-08T15:30:55.525469Z","end":"2026-07-08T15:30:55.697749Z","steps":["trace[206543530] 'agreement among raft nodes before linearized reading' (duration: 172.061389ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:55.87747Z","caller":"traceutil/trace.go:171","msg":"trace[1462009262] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1127; }","duration":"151.821924ms","start":"2026-07-08T15:30:55.72563Z","end":"2026-07-08T15:30:55.877452Z","steps":["trace[1462009262] 'read index received' (duration: 25.093113ms)","trace[1462009262] 'applied index is now lower than readState.Index' (duration: 126.727611ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:30:55.877598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.947217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"info","ts":"2026-07-08T15:30:55.877583Z","caller":"traceutil/trace.go:171","msg":"trace[201538293] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"175.283239ms","start":"2026-07-08T15:30:55.702265Z","end":"2026-07-08T15:30:55.877549Z","steps":["trace[201538293] 'process raft request' (duration: 103.591543ms)","trace[201538293] 'compare' (duration: 71.434881ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:30:55.877648Z","caller":"traceutil/trace.go:171","msg":"trace[1815804545] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"175.377822ms","start":"2026-07-08T15:30:55.702264Z","end":"2026-07-08T15:30:55.877642Z","steps":["trace[1815804545] 'process raft request' (duration: 175.150347ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:55.877622Z","caller":"traceutil/trace.go:171","msg":"trace[553390591] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1089; }","duration":"152.009198ms","start":"2026-07-08T15:30:55.725604Z","end":"2026-07-08T15:30:55.877613Z","steps":["trace[553390591] 'agreement among raft nodes before linearized reading' (duration: 151.927266ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:59.224182Z","caller":"traceutil/trace.go:171","msg":"trace[276878385] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"366.696733ms","start":"2026-07-08T15:30:58.85746Z","end":"2026-07-08T15:30:59.224157Z","steps":["trace[276878385] 'process raft request' (duration: 366.57535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:30:59.224334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:30:58.857443Z","time spent":"366.812896ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:30:59.430741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.879282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:30:59.430825Z","caller":"traceutil/trace.go:171","msg":"trace[1462859128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"118.996504ms","start":"2026-07-08T15:30:59.311808Z","end":"2026-07-08T15:30:59.430804Z","steps":["trace[1462859128] 'range keys from in-memory index tree' (duration: 118.828971ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:07.918217Z","caller":"traceutil/trace.go:171","msg":"trace[1141901505] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"121.686218ms","start":"2026-07-08T15:31:07.796506Z","end":"2026-07-08T15:31:07.918192Z","steps":["trace[1141901505] 'process raft request' (duration: 117.955876ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:14.004913Z","caller":"traceutil/trace.go:171","msg":"trace[286545965] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"186.401182ms","start":"2026-07-08T15:31:13.818477Z","end":"2026-07-08T15:31:14.004878Z","steps":["trace[286545965] 'process raft request' (duration: 186.236129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:14.163229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.086682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:31:14.163387Z","caller":"traceutil/trace.go:171","msg":"trace[1191768335] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1191; }","duration":"131.806881ms","start":"2026-07-08T15:31:14.031558Z","end":"2026-07-08T15:31:14.163365Z","steps":["trace[1191768335] 'read index received' (duration: 7.350941ms)","trace[1191768335] 'applied index is now lower than readState.Index' (duration: 124.4544ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:31:14.163486Z","caller":"traceutil/trace.go:171","msg":"trace[1924111723] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"153.681311ms","start":"2026-07-08T15:31:14.00978Z","end":"2026-07-08T15:31:14.163461Z","steps":["trace[1924111723] 'process raft request' (duration: 29.268442ms)","trace[1924111723] 'compare' (duration: 123.958358ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:31:14.163503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.935423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:31:14.163632Z","caller":"traceutil/trace.go:171","msg":"trace[823132214] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1147; }","duration":"132.117307ms","start":"2026-07-08T15:31:14.031505Z","end":"2026-07-08T15:31:14.163622Z","steps":["trace[823132214] 'agreement among raft nodes before linearized reading' (duration: 131.957424ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:50.752242Z","caller":"traceutil/trace.go:171","msg":"trace[706959285] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"107.860309ms","start":"2026-07-08T15:31:50.644362Z","end":"2026-07-08T15:31:50.752223Z","steps":["trace[706959285] 'process raft request' (duration: 107.724307ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:08.429514Z","caller":"traceutil/trace.go:171","msg":"trace[378074232] linearizableReadLoop","detail":"{readStateIndex:1416; appliedIndex:1415; }","duration":"165.405483ms","start":"2026-07-08T15:32:08.264089Z","end":"2026-07-08T15:32:08.429494Z","steps":["trace[378074232] 'read index received' (duration: 165.23819ms)","trace[378074232] 'applied index is now lower than readState.Index' (duration: 166.353µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:32:08.429627Z","caller":"traceutil/trace.go:171","msg":"trace[572465347] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"712.750007ms","start":"2026-07-08T15:32:07.716846Z","end":"2026-07-08T15:32:08.429596Z","steps":["trace[572465347] 'process raft request' (duration: 712.475702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:08.430005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.546904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T15:32:08.430079Z","caller":"traceutil/trace.go:171","msg":"trace[1654340012] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1359; }","duration":"118.686217ms","start":"2026-07-08T15:32:08.311375Z","end":"2026-07-08T15:32:08.430061Z","steps":["trace[1654340012] 'agreement among raft nodes before linearized reading' (duration: 118.509503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:08.430362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:07.716821Z","time spent":"713.184147ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:32:08.42984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.73013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-08T15:32:08.430974Z","caller":"traceutil/trace.go:171","msg":"trace[808135044] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1359; }","duration":"166.873734ms","start":"2026-07-08T15:32:08.264051Z","end":"2026-07-08T15:32:08.430925Z","steps":["trace[808135044] 'agreement among raft nodes before linearized reading' (duration: 165.662789ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:21.575086Z","caller":"traceutil/trace.go:171","msg":"trace[956986602] linearizableReadLoop","detail":"{readStateIndex:1471; appliedIndex:1470; }","duration":"157.760084ms","start":"2026-07-08T15:32:21.41729Z","end":"2026-07-08T15:32:21.57505Z","steps":["trace[956986602] 'read index received' (duration: 119.858639ms)","trace[956986602] 'applied index is now lower than readState.Index' (duration: 37.899095ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:32:21.575106Z","caller":"traceutil/trace.go:171","msg":"trace[558640894] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"181.087194ms","start":"2026-07-08T15:32:21.393975Z","end":"2026-07-08T15:32:21.575062Z","steps":["trace[558640894] 'process raft request' (duration: 143.248499ms)","trace[558640894] 'compare' (duration: 37.546318ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:32:21.575316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.247572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:698"} {"level":"warn","ts":"2026-07-08T15:32:21.575366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.02361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:certificate-controller\" ","response":"range_response_count:1 size:1142"} {"level":"info","ts":"2026-07-08T15:32:21.575414Z","caller":"traceutil/trace.go:171","msg":"trace[1338943413] range","detail":"{range_begin:/registry/clusterroles/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1410; }","duration":"155.355124ms","start":"2026-07-08T15:32:21.419992Z","end":"2026-07-08T15:32:21.575347Z","steps":["trace[1338943413] 'agreement among raft nodes before linearized reading' (duration: 155.240221ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:21.575472Z","caller":"traceutil/trace.go:171","msg":"trace[2039767726] range","detail":"{range_begin:/registry/clusterroles/system:controller:certificate-controller; range_end:; response_count:1; response_revision:1410; }","duration":"158.215464ms","start":"2026-07-08T15:32:21.417231Z","end":"2026-07-08T15:32:21.575446Z","steps":["trace[2039767726] 'agreement among raft nodes before linearized reading' (duration: 157.917777ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:34.776256Z","caller":"traceutil/trace.go:171","msg":"trace[1173133169] linearizableReadLoop","detail":"{readStateIndex:1514; appliedIndex:1513; }","duration":"108.02966ms","start":"2026-07-08T15:32:34.668201Z","end":"2026-07-08T15:32:34.776231Z","steps":["trace[1173133169] 'read index received' (duration: 107.832796ms)","trace[1173133169] 'applied index is now lower than readState.Index' (duration: 195.944µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:32:34.776331Z","caller":"traceutil/trace.go:171","msg":"trace[1799459488] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"134.240287ms","start":"2026-07-08T15:32:34.642063Z","end":"2026-07-08T15:32:34.776304Z","steps":["trace[1799459488] 'process raft request' (duration: 134.035243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:34.776431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.209094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/\" range_end:\"/registry/roles/kube-public0\" limit:500 ","response":"range_response_count:2 size:1073"} {"level":"info","ts":"2026-07-08T15:32:34.77652Z","caller":"traceutil/trace.go:171","msg":"trace[995341192] range","detail":"{range_begin:/registry/roles/kube-public/; range_end:/registry/roles/kube-public0; response_count:2; response_revision:1451; }","duration":"108.304436ms","start":"2026-07-08T15:32:34.668168Z","end":"2026-07-08T15:32:34.776472Z","steps":["trace[995341192] 'agreement among raft nodes before linearized reading' (duration: 108.153093ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:35.786281Z","caller":"traceutil/trace.go:171","msg":"trace[1988007816] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"101.030313ms","start":"2026-07-08T15:32:35.685206Z","end":"2026-07-08T15:32:35.786237Z","steps":["trace[1988007816] 'process raft request' (duration: 100.751427ms)"],"step_count":1}