{"level":"warn","ts":"2026-07-09T13:07:11.116902Z","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-09T13:07:11.117273Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.162: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.162:2380","--initial-cluster=instance=https://199.19.213.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.162: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-09T13:07:11.117597Z","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-09T13:07:11.11762Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-07-09T13:07:11.117688Z","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-09T13:07:11.119267Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"]} {"level":"info","ts":"2026-07-09T13:07:11.119442Z","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.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.162: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-09T13:07:11.264784Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"144.974758ms"} {"level":"info","ts":"2026-07-09T13:07:11.474705Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8c0a14645b8502c5","cluster-id":"49fe27222e1c6db4"} {"level":"info","ts":"2026-07-09T13:07:11.474846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T13:07:11.474874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 0"} {"level":"info","ts":"2026-07-09T13:07:11.474918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8c0a14645b8502c5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T13:07:11.474943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 1"} {"level":"info","ts":"2026-07-09T13:07:11.474984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"warn","ts":"2026-07-09T13:07:11.5104Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T13:07:11.514175Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T13:07:11.515239Z","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-09T13:07:11.517636Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8c0a14645b8502c5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T13:07:11.517861Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8c0a14645b8502c5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T13:07:11.518758Z","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-09T13:07:11.518919Z","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-09T13:07:11.518947Z","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-09T13:07:11.519612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"info","ts":"2026-07-09T13:07:11.51995Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","added-peer-id":"8c0a14645b8502c5","added-peer-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-07-09T13:07:11.522411Z","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-09T13:07:11.522542Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-07-09T13:07:11.52257Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-07-09T13:07:11.523554Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8c0a14645b8502c5","initial-advertise-peer-urls":["https://199.19.213.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T13:07:11.523604Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T13:07:12.199853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T13:07:12.199915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T13:07:12.199937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgPreVoteResp from 8c0a14645b8502c5 at term 1"} {"level":"info","ts":"2026-07-09T13:07:12.199952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became candidate at term 2"} {"level":"info","ts":"2026-07-09T13:07:12.199968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgVoteResp from 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-07-09T13:07:12.199979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became leader at term 2"} {"level":"info","ts":"2026-07-09T13:07:12.19999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8c0a14645b8502c5 elected leader 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-07-09T13:07:12.201226Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T13:07:12.202659Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T13:07:12.202789Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T13:07:12.202775Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8c0a14645b8502c5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.162:2379]}","request-path":"/0/members/8c0a14645b8502c5/attributes","cluster-id":"49fe27222e1c6db4","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T13:07:12.202826Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T13:07:12.20283Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T13:07:12.202868Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T13:07:12.20308Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T13:07:12.20312Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T13:07:12.206151Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T13:07:12.209321Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.162:2379"} {"level":"info","ts":"2026-07-09T13:07:20.561466Z","caller":"traceutil/trace.go:171","msg":"trace[1667679976] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"100.468999ms","start":"2026-07-09T13:07:20.460947Z","end":"2026-07-09T13:07:20.561416Z","steps":["trace[1667679976] 'process raft request' (duration: 100.206604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:20.976451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.984403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:07:20.977277Z","caller":"traceutil/trace.go:171","msg":"trace[1387542856] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"142.367236ms","start":"2026-07-09T13:07:20.834878Z","end":"2026-07-09T13:07:20.977245Z","steps":["trace[1387542856] 'process raft request' (duration: 17.877805ms)","trace[1387542856] 'compare' (duration: 122.791789ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:07:20.977655Z","caller":"traceutil/trace.go:171","msg":"trace[579204116] linearizableReadLoop","detail":"{readStateIndex:321; appliedIndex:317; }","duration":"125.168925ms","start":"2026-07-09T13:07:20.852444Z","end":"2026-07-09T13:07:20.977613Z","steps":["trace[579204116] 'read index received' (duration: 301.296µs)","trace[579204116] 'applied index is now lower than readState.Index' (duration: 124.866109ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:07:20.977625Z","caller":"traceutil/trace.go:171","msg":"trace[496462006] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"139.948611ms","start":"2026-07-09T13:07:20.837614Z","end":"2026-07-09T13:07:20.977563Z","steps":["trace[496462006] 'process raft request' (duration: 139.599973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:20.977841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.364659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-09T13:07:20.977913Z","caller":"traceutil/trace.go:171","msg":"trace[623757322] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:308; }","duration":"125.498462ms","start":"2026-07-09T13:07:20.852399Z","end":"2026-07-09T13:07:20.977897Z","steps":["trace[623757322] 'agreement among raft nodes before linearized reading' (duration: 125.352299ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:20.977514Z","caller":"traceutil/trace.go:171","msg":"trace[770816825] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"139.764237ms","start":"2026-07-09T13:07:20.837719Z","end":"2026-07-09T13:07:20.977483Z","steps":["trace[770816825] 'process raft request' (duration: 139.659715ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:20.977711Z","caller":"traceutil/trace.go:171","msg":"trace[159039686] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"139.933721ms","start":"2026-07-09T13:07:20.837755Z","end":"2026-07-09T13:07:20.977689Z","steps":["trace[159039686] 'process raft request' (duration: 139.684276ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:21.193889Z","caller":"traceutil/trace.go:171","msg":"trace[2081195470] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"208.71753ms","start":"2026-07-09T13:07:20.985128Z","end":"2026-07-09T13:07:21.193846Z","steps":["trace[2081195470] 'process raft request' (duration: 206.541578ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:21.194032Z","caller":"traceutil/trace.go:171","msg":"trace[313972689] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"205.603462ms","start":"2026-07-09T13:07:20.988403Z","end":"2026-07-09T13:07:21.194006Z","steps":["trace[313972689] 'process raft request' (duration: 205.286205ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:21.194129Z","caller":"traceutil/trace.go:171","msg":"trace[1405569602] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:322; }","duration":"159.001492ms","start":"2026-07-09T13:07:21.03511Z","end":"2026-07-09T13:07:21.194111Z","steps":["trace[1405569602] 'read index received' (duration: 156.579956ms)","trace[1405569602] 'applied index is now lower than readState.Index' (duration: 2.420296ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:07:21.194293Z","caller":"traceutil/trace.go:171","msg":"trace[1951395407] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"203.963999ms","start":"2026-07-09T13:07:20.990317Z","end":"2026-07-09T13:07:21.194281Z","steps":["trace[1951395407] 'process raft request' (duration: 203.745465ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:21.194236Z","caller":"traceutil/trace.go:171","msg":"trace[2029388691] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"204.810455ms","start":"2026-07-09T13:07:20.989315Z","end":"2026-07-09T13:07:21.194125Z","steps":["trace[2029388691] 'process raft request' (duration: 204.615941ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:21.194403Z","caller":"traceutil/trace.go:171","msg":"trace[1670700059] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"204.762334ms","start":"2026-07-09T13:07:20.989613Z","end":"2026-07-09T13:07:21.194375Z","steps":["trace[1670700059] 'process raft request' (duration: 204.394108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:21.194588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.870405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-07-09T13:07:21.194646Z","caller":"traceutil/trace.go:171","msg":"trace[724271287] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:314; }","duration":"192.035967ms","start":"2026-07-09T13:07:21.002596Z","end":"2026-07-09T13:07:21.194632Z","steps":["trace[724271287] 'agreement among raft nodes before linearized reading' (duration: 191.787403ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:21.224575Z","caller":"traceutil/trace.go:171","msg":"trace[922540017] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"185.632156ms","start":"2026-07-09T13:07:21.038922Z","end":"2026-07-09T13:07:21.224554Z","steps":["trace[922540017] 'process raft request' (duration: 185.31849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:21.22462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.058845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-07-09T13:07:21.22467Z","caller":"traceutil/trace.go:171","msg":"trace[501163604] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:315; }","duration":"171.160277ms","start":"2026-07-09T13:07:21.053497Z","end":"2026-07-09T13:07:21.224657Z","steps":["trace[501163604] 'agreement among raft nodes before linearized reading' (duration: 170.966493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:21.224641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.769155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-09T13:07:21.2249Z","caller":"traceutil/trace.go:171","msg":"trace[1543919789] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:315; }","duration":"121.02258ms","start":"2026-07-09T13:07:21.10377Z","end":"2026-07-09T13:07:21.224792Z","steps":["trace[1543919789] 'agreement among raft nodes before linearized reading' (duration: 120.678604ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:25.868691Z","caller":"traceutil/trace.go:171","msg":"trace[1429921325] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"119.967043ms","start":"2026-07-09T13:07:25.748703Z","end":"2026-07-09T13:07:25.86867Z","steps":["trace[1429921325] 'read index received' (duration: 27.494126ms)","trace[1429921325] 'applied index is now lower than readState.Index' (duration: 92.472347ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:07:25.868865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.155296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:07:25.868917Z","caller":"traceutil/trace.go:171","msg":"trace[1423863118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:366; }","duration":"120.250368ms","start":"2026-07-09T13:07:25.748659Z","end":"2026-07-09T13:07:25.86891Z","steps":["trace[1423863118] 'agreement among raft nodes before linearized reading' (duration: 120.089625ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:25.868897Z","caller":"traceutil/trace.go:171","msg":"trace[232363614] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"128.547767ms","start":"2026-07-09T13:07:25.740276Z","end":"2026-07-09T13:07:25.868824Z","steps":["trace[232363614] 'process raft request' (duration: 35.983298ms)","trace[232363614] 'compare' (duration: 92.302554ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:07:27.778474Z","caller":"traceutil/trace.go:171","msg":"trace[627176242] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"192.038174ms","start":"2026-07-09T13:07:27.586393Z","end":"2026-07-09T13:07:27.778431Z","steps":["trace[627176242] 'process raft request' (duration: 191.902741ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:27.77985Z","caller":"traceutil/trace.go:171","msg":"trace[398674043] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:384; }","duration":"149.588778ms","start":"2026-07-09T13:07:27.630231Z","end":"2026-07-09T13:07:27.779819Z","steps":["trace[398674043] 'read index received' (duration: 148.12838ms)","trace[398674043] 'applied index is now lower than readState.Index' (duration: 1.458618ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:07:27.780026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.781131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:07:27.780069Z","caller":"traceutil/trace.go:171","msg":"trace[1696072123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"149.842982ms","start":"2026-07-09T13:07:27.630212Z","end":"2026-07-09T13:07:27.780055Z","steps":["trace[1696072123] 'agreement among raft nodes before linearized reading' (duration: 149.72545ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:27.780447Z","caller":"traceutil/trace.go:171","msg":"trace[1654206181] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"184.38558ms","start":"2026-07-09T13:07:27.596024Z","end":"2026-07-09T13:07:27.78041Z","steps":["trace[1654206181] 'process raft request' (duration: 183.657926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:27.99315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.870023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-09T13:07:27.993191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.771314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:07:27.993243Z","caller":"traceutil/trace.go:171","msg":"trace[1078263643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:371; }","duration":"118.984555ms","start":"2026-07-09T13:07:27.874237Z","end":"2026-07-09T13:07:27.993222Z","steps":["trace[1078263643] 'range keys from in-memory index tree' (duration: 118.72945ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:27.993288Z","caller":"traceutil/trace.go:171","msg":"trace[999664912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:371; }","duration":"104.921417ms","start":"2026-07-09T13:07:27.88834Z","end":"2026-07-09T13:07:27.993261Z","steps":["trace[999664912] 'range keys from in-memory index tree' (duration: 104.630421ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:30.096322Z","caller":"traceutil/trace.go:171","msg":"trace[1674403901] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"374.352715ms","start":"2026-07-09T13:07:29.721948Z","end":"2026-07-09T13:07:30.096301Z","steps":["trace[1674403901] 'process raft request' (duration: 374.169911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:30.09661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:29.721922Z","time spent":"374.461097ms","remote":"127.0.0.1:40606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:07:30.778587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.714993ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T13:07:30.778706Z","caller":"traceutil/trace.go:171","msg":"trace[1443576421] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"148.805056ms","start":"2026-07-09T13:07:30.629881Z","end":"2026-07-09T13:07:30.778687Z","steps":["trace[1443576421] 'read index received' (duration: 39.67µs)","trace[1443576421] 'applied index is now lower than readState.Index' (duration: 148.763966ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:07:30.778793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.909819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:07:30.778826Z","caller":"traceutil/trace.go:171","msg":"trace[891654813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:381; }","duration":"148.979299ms","start":"2026-07-09T13:07:30.629836Z","end":"2026-07-09T13:07:30.778816Z","steps":["trace[891654813] 'agreement among raft nodes before linearized reading' (duration: 148.912528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:32.55103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.919924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:07:32.551131Z","caller":"traceutil/trace.go:171","msg":"trace[1899374195] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:397; }","duration":"363.605827ms","start":"2026-07-09T13:07:32.187506Z","end":"2026-07-09T13:07:32.551112Z","steps":["trace[1899374195] 'read index received' (duration: 69.809277ms)","trace[1899374195] 'applied index is now lower than readState.Index' (duration: 293.79544ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:07:32.551268Z","caller":"traceutil/trace.go:171","msg":"trace[448681134] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"364.231138ms","start":"2026-07-09T13:07:32.187021Z","end":"2026-07-09T13:07:32.551252Z","steps":["trace[448681134] 'process raft request' (duration: 176.993068ms)","trace[448681134] 'compare' (duration: 186.380255ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:07:32.551385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.113137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-09T13:07:32.551415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.888002ms","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-09T13:07:32.551461Z","caller":"traceutil/trace.go:171","msg":"trace[1091360560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:383; }","duration":"363.22134ms","start":"2026-07-09T13:07:32.188222Z","end":"2026-07-09T13:07:32.551443Z","steps":["trace[1091360560] 'agreement among raft nodes before linearized reading' (duration: 363.064267ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:32.551486Z","caller":"traceutil/trace.go:171","msg":"trace[2122383833] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:383; }","duration":"363.983913ms","start":"2026-07-09T13:07:32.187482Z","end":"2026-07-09T13:07:32.551466Z","steps":["trace[2122383833] 'agreement among raft nodes before linearized reading' (duration: 363.861051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:32.551507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:32.188213Z","time spent":"363.286021ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T13:07:32.551538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:32.187474Z","time spent":"364.054434ms","remote":"127.0.0.1:40700","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-09T13:07:32.551653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:32.186995Z","time spent":"364.379061ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:07:32.852662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.170316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:07:32.852752Z","caller":"traceutil/trace.go:171","msg":"trace[607044065] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:400; }","duration":"222.847823ms","start":"2026-07-09T13:07:32.629889Z","end":"2026-07-09T13:07:32.852737Z","steps":["trace[607044065] 'read index received' (duration: 11.564118ms)","trace[607044065] 'applied index is now lower than readState.Index' (duration: 211.282435ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:07:32.852808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.919254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:07:32.852832Z","caller":"traceutil/trace.go:171","msg":"trace[281896469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:385; }","duration":"223.010095ms","start":"2026-07-09T13:07:32.629814Z","end":"2026-07-09T13:07:32.852824Z","steps":["trace[281896469] 'agreement among raft nodes before linearized reading' (duration: 222.964795ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:32.852941Z","caller":"traceutil/trace.go:171","msg":"trace[1869417837] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"297.419558ms","start":"2026-07-09T13:07:32.555496Z","end":"2026-07-09T13:07:32.852916Z","steps":["trace[1869417837] 'process raft request' (duration: 123.806524ms)","trace[1869417837] 'compare' (duration: 173.079894ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:07:34.128113Z","caller":"traceutil/trace.go:171","msg":"trace[161952633] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"219.420226ms","start":"2026-07-09T13:07:33.90867Z","end":"2026-07-09T13:07:34.12809Z","steps":["trace[161952633] 'process raft request' (duration: 219.255343ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:50.932357Z","caller":"traceutil/trace.go:171","msg":"trace[1057029777] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"174.58355ms","start":"2026-07-09T13:07:50.75773Z","end":"2026-07-09T13:07:50.932313Z","steps":["trace[1057029777] 'process raft request' (duration: 174.290974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:58.881034Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199740885733609346,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T13:07:58.999924Z","caller":"traceutil/trace.go:171","msg":"trace[852810818] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"723.617241ms","start":"2026-07-09T13:07:58.276287Z","end":"2026-07-09T13:07:58.999905Z","steps":["trace[852810818] 'process raft request' (duration: 723.415867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:59.00006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:58.276262Z","time spent":"723.721312ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:07:59.014043Z","caller":"traceutil/trace.go:171","msg":"trace[4362089] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"655.184115ms","start":"2026-07-09T13:07:58.358836Z","end":"2026-07-09T13:07:59.01402Z","steps":["trace[4362089] 'process raft request' (duration: 654.992442ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:59.014068Z","caller":"traceutil/trace.go:171","msg":"trace[1919858411] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"651.382338ms","start":"2026-07-09T13:07:58.362659Z","end":"2026-07-09T13:07:59.014041Z","steps":["trace[1919858411] 'process raft request' (duration: 651.276106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:59.014155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:58.362638Z","time spent":"651.471429ms","remote":"127.0.0.1:40458","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-09T13:07:59.014197Z","caller":"traceutil/trace.go:171","msg":"trace[533480718] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"636.50555ms","start":"2026-07-09T13:07:58.377683Z","end":"2026-07-09T13:07:59.014188Z","steps":["trace[533480718] 'process raft request' (duration: 636.350326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:59.014265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:58.377663Z","time spent":"636.583572ms","remote":"127.0.0.1:40458","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-09T13:07:59.014352Z","caller":"traceutil/trace.go:171","msg":"trace[2077475796] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"651.625833ms","start":"2026-07-09T13:07:58.362706Z","end":"2026-07-09T13:07:59.014332Z","steps":["trace[2077475796] 'process raft request' (duration: 651.265865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:59.014477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:58.36269Z","time spent":"651.724644ms","remote":"127.0.0.1:40458","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-09T13:07:59.014437Z","caller":"traceutil/trace.go:171","msg":"trace[1471667505] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"633.316472ms","start":"2026-07-09T13:07:58.381081Z","end":"2026-07-09T13:07:59.014397Z","steps":["trace[1471667505] 'process raft request' (duration: 633.020336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:59.014155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:58.358807Z","time spent":"655.293418ms","remote":"127.0.0.1:40458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T13:07:59.014582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.580378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-09T13:07:59.014602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:58.381071Z","time spent":"633.465355ms","remote":"127.0.0.1:40458","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-09T13:07:59.01462Z","caller":"traceutil/trace.go:171","msg":"trace[1001932944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:519; }","duration":"633.634999ms","start":"2026-07-09T13:07:58.380972Z","end":"2026-07-09T13:07:59.014607Z","steps":["trace[1001932944] 'agreement among raft nodes before linearized reading' (duration: 633.542697ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:59.014491Z","caller":"traceutil/trace.go:171","msg":"trace[413910756] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:528; }","duration":"633.485316ms","start":"2026-07-09T13:07:58.380993Z","end":"2026-07-09T13:07:59.014478Z","steps":["trace[413910756] 'read index received' (duration: 618.75009ms)","trace[413910756] 'applied index is now lower than readState.Index' (duration: 14.734606ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:07:59.014661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:58.380961Z","time spent":"633.688019ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T13:07:59.014682Z","caller":"traceutil/trace.go:171","msg":"trace[1719084192] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"615.965129ms","start":"2026-07-09T13:07:58.398707Z","end":"2026-07-09T13:07:59.014672Z","steps":["trace[1719084192] 'process raft request' (duration: 615.569062ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:59.014493Z","caller":"traceutil/trace.go:171","msg":"trace[695630699] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"635.675885ms","start":"2026-07-09T13:07:58.378789Z","end":"2026-07-09T13:07:59.014465Z","steps":["trace[695630699] 'process raft request' (duration: 635.277687ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:59.014755Z","caller":"traceutil/trace.go:171","msg":"trace[2077358000] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"617.816633ms","start":"2026-07-09T13:07:58.396933Z","end":"2026-07-09T13:07:59.014749Z","steps":["trace[2077358000] 'process raft request' (duration: 617.243042ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:59.014739Z","caller":"traceutil/trace.go:171","msg":"trace[1124725352] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"629.818389ms","start":"2026-07-09T13:07:58.384909Z","end":"2026-07-09T13:07:59.014728Z","steps":["trace[1124725352] 'process raft request' (duration: 629.224327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:59.014785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:58.396913Z","time spent":"617.860474ms","remote":"127.0.0.1:40458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T13:07:59.014732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:58.398689Z","time spent":"616.02202ms","remote":"127.0.0.1:40458","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-09T13:07:59.014756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.602077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:07:59.014857Z","caller":"traceutil/trace.go:171","msg":"trace[1622496018] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"580.544241ms","start":"2026-07-09T13:07:58.434299Z","end":"2026-07-09T13:07:59.014843Z","steps":["trace[1622496018] 'process raft request' (duration: 580.015781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:59.014827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:58.3849Z","time spent":"629.881401ms","remote":"127.0.0.1:40458","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-09T13:07:59.014881Z","caller":"traceutil/trace.go:171","msg":"trace[1734474410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"384.77258ms","start":"2026-07-09T13:07:58.630096Z","end":"2026-07-09T13:07:59.014869Z","steps":["trace[1734474410] 'agreement among raft nodes before linearized reading' (duration: 384.618938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:59.014915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:58.434265Z","time spent":"580.620493ms","remote":"127.0.0.1:40458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T13:07:59.014917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:58.630075Z","time spent":"384.833691ms","remote":"127.0.0.1:40418","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-09T13:07:59.014959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.699087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T13:07:59.014984Z","caller":"traceutil/trace.go:171","msg":"trace[355055367] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"483.323607ms","start":"2026-07-09T13:07:58.531653Z","end":"2026-07-09T13:07:59.014977Z","steps":["trace[355055367] 'process raft request' (duration: 482.767087ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:07:59.014989Z","caller":"traceutil/trace.go:171","msg":"trace[1994058150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:519; }","duration":"314.820828ms","start":"2026-07-09T13:07:58.70016Z","end":"2026-07-09T13:07:59.014981Z","steps":["trace[1994058150] 'agreement among raft nodes before linearized reading' (duration: 314.769787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:59.015013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:58.700139Z","time spent":"314.86868ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T13:07:59.015017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:58.53162Z","time spent":"483.384019ms","remote":"127.0.0.1:40458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T13:07:59.01492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:58.37878Z","time spent":"636.015181ms","remote":"127.0.0.1:40458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T13:07:59.014626Z","caller":"traceutil/trace.go:171","msg":"trace[2121196034] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"638.17902ms","start":"2026-07-09T13:07:58.376398Z","end":"2026-07-09T13:07:59.014577Z","steps":["trace[2121196034] 'process raft request' (duration: 637.602929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:07:59.015262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:07:58.376375Z","time spent":"638.814721ms","remote":"127.0.0.1:40458","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-09T13:08:01.190739Z","caller":"traceutil/trace.go:171","msg":"trace[217362781] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"103.735392ms","start":"2026-07-09T13:08:01.086986Z","end":"2026-07-09T13:08:01.190722Z","steps":["trace[217362781] 'process raft request' (duration: 97.036742ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:06.541996Z","caller":"traceutil/trace.go:171","msg":"trace[195973369] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"433.021235ms","start":"2026-07-09T13:08:06.108951Z","end":"2026-07-09T13:08:06.541972Z","steps":["trace[195973369] 'process raft request' (duration: 432.766181ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:06.541957Z","caller":"traceutil/trace.go:171","msg":"trace[2035944666] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"359.945613ms","start":"2026-07-09T13:08:06.181981Z","end":"2026-07-09T13:08:06.541927Z","steps":["trace[2035944666] 'read index received' (duration: 359.837742ms)","trace[2035944666] 'applied index is now lower than readState.Index' (duration: 106.601µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:08:06.542184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:06.108922Z","time spent":"433.158257ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T13:08:06.542208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.212438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:08:06.542359Z","caller":"traceutil/trace.go:171","msg":"trace[990350054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"360.35836ms","start":"2026-07-09T13:08:06.181955Z","end":"2026-07-09T13:08:06.542313Z","steps":["trace[990350054] 'agreement among raft nodes before linearized reading' (duration: 360.092525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:06.542411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:06.18194Z","time spent":"360.458953ms","remote":"127.0.0.1:40418","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-09T13:08:06.588302Z","caller":"traceutil/trace.go:171","msg":"trace[973729905] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"382.962492ms","start":"2026-07-09T13:08:06.20531Z","end":"2026-07-09T13:08:06.588272Z","steps":["trace[973729905] 'process raft request' (duration: 382.234749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:06.588563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:06.205283Z","time spent":"383.109055ms","remote":"127.0.0.1:40502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:08:06.588681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.260627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-09T13:08:06.589293Z","caller":"traceutil/trace.go:171","msg":"trace[1844027436] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:620; }","duration":"342.38098ms","start":"2026-07-09T13:08:06.246354Z","end":"2026-07-09T13:08:06.588735Z","steps":["trace[1844027436] 'agreement among raft nodes before linearized reading' (duration: 342.166476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:06.58936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:06.246331Z","time spent":"343.015021ms","remote":"127.0.0.1:40518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-09T13:08:06.918075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.596898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:08:06.918162Z","caller":"traceutil/trace.go:171","msg":"trace[1597780192] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"191.275508ms","start":"2026-07-09T13:08:06.726872Z","end":"2026-07-09T13:08:06.918147Z","steps":["trace[1597780192] 'read index received' (duration: 74.480197ms)","trace[1597780192] 'applied index is now lower than readState.Index' (duration: 116.794211ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:08:06.918185Z","caller":"traceutil/trace.go:171","msg":"trace[1334515489] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"253.69064ms","start":"2026-07-09T13:08:06.664476Z","end":"2026-07-09T13:08:06.918167Z","steps":["trace[1334515489] 'process raft request' (duration: 136.95327ms)","trace[1334515489] 'compare' (duration: 116.441755ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:08:06.918261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.38334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9xpmp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-09T13:08:06.918296Z","caller":"traceutil/trace.go:171","msg":"trace[690785408] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9xpmp; range_end:; response_count:1; response_revision:622; }","duration":"191.458941ms","start":"2026-07-09T13:08:06.726828Z","end":"2026-07-09T13:08:06.918287Z","steps":["trace[690785408] 'agreement among raft nodes before linearized reading' (duration: 191.35729ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:07.213184Z","caller":"traceutil/trace.go:171","msg":"trace[869101450] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:652; }","duration":"102.859819ms","start":"2026-07-09T13:08:07.1103Z","end":"2026-07-09T13:08:07.21316Z","steps":["trace[869101450] 'read index received' (duration: 96.490166ms)","trace[869101450] 'applied index is now lower than readState.Index' (duration: 6.368783ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:08:07.213215Z","caller":"traceutil/trace.go:171","msg":"trace[33239797] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"288.396842ms","start":"2026-07-09T13:08:06.924785Z","end":"2026-07-09T13:08:07.213182Z","steps":["trace[33239797] 'process raft request' (duration: 288.191748ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:07.213417Z","caller":"traceutil/trace.go:171","msg":"trace[955396882] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"289.307648ms","start":"2026-07-09T13:08:06.924086Z","end":"2026-07-09T13:08:07.213394Z","steps":["trace[955396882] 'process raft request' (duration: 282.708252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:07.213525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.205195ms","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-09T13:08:07.21357Z","caller":"traceutil/trace.go:171","msg":"trace[2114389346] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"288.492793ms","start":"2026-07-09T13:08:06.925059Z","end":"2026-07-09T13:08:07.213552Z","steps":["trace[2114389346] 'process raft request' (duration: 288.013425ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:07.213654Z","caller":"traceutil/trace.go:171","msg":"trace[1601809570] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"267.130663ms","start":"2026-07-09T13:08:06.946511Z","end":"2026-07-09T13:08:07.213642Z","steps":["trace[1601809570] 'process raft request' (duration: 266.604134ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:07.213604Z","caller":"traceutil/trace.go:171","msg":"trace[657865484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"103.326657ms","start":"2026-07-09T13:08:07.110258Z","end":"2026-07-09T13:08:07.213585Z","steps":["trace[657865484] 'agreement among raft nodes before linearized reading' (duration: 103.038932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:08.279674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.41085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:08:08.279752Z","caller":"traceutil/trace.go:171","msg":"trace[1171008325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"651.542973ms","start":"2026-07-09T13:08:07.628195Z","end":"2026-07-09T13:08:08.279738Z","steps":["trace[1171008325] 'range keys from in-memory index tree' (duration: 651.314059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:08.279781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:07.628174Z","time spent":"651.602194ms","remote":"127.0.0.1:40418","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-09T13:08:09.207159Z","caller":"traceutil/trace.go:171","msg":"trace[1844350671] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"151.252033ms","start":"2026-07-09T13:08:09.055884Z","end":"2026-07-09T13:08:09.207136Z","steps":["trace[1844350671] 'process raft request' (duration: 151.066769ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:11.189342Z","caller":"traceutil/trace.go:171","msg":"trace[114357331] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"231.256631ms","start":"2026-07-09T13:08:10.95806Z","end":"2026-07-09T13:08:11.189317Z","steps":["trace[114357331] 'process raft request' (duration: 231.105708ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:21.553549Z","caller":"traceutil/trace.go:171","msg":"trace[1197396804] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"181.073786ms","start":"2026-07-09T13:08:21.372456Z","end":"2026-07-09T13:08:21.553529Z","steps":["trace[1197396804] 'read index received' (duration: 180.898543ms)","trace[1197396804] 'applied index is now lower than readState.Index' (duration: 174.413µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:08:21.553855Z","caller":"traceutil/trace.go:171","msg":"trace[2051552854] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"200.884962ms","start":"2026-07-09T13:08:21.352899Z","end":"2026-07-09T13:08:21.553784Z","steps":["trace[2051552854] 'process raft request' (duration: 200.502145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:21.553985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.443084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:08:21.554033Z","caller":"traceutil/trace.go:171","msg":"trace[991119907] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:767; }","duration":"181.540996ms","start":"2026-07-09T13:08:21.372482Z","end":"2026-07-09T13:08:21.554023Z","steps":["trace[991119907] 'agreement among raft nodes before linearized reading' (duration: 181.413613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:21.554032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.538685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:08:21.55413Z","caller":"traceutil/trace.go:171","msg":"trace[733925507] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:767; }","duration":"181.686028ms","start":"2026-07-09T13:08:21.37242Z","end":"2026-07-09T13:08:21.554106Z","steps":["trace[733925507] 'agreement among raft nodes before linearized reading' (duration: 181.490814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:21.554204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.153844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:08:21.554239Z","caller":"traceutil/trace.go:171","msg":"trace[1527040594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:767; }","duration":"175.248226ms","start":"2026-07-09T13:08:21.378981Z","end":"2026-07-09T13:08:21.554229Z","steps":["trace[1527040594] 'agreement among raft nodes before linearized reading' (duration: 175.056103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:21.554356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.734962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:08:21.554405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.723007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T13:08:21.554481Z","caller":"traceutil/trace.go:171","msg":"trace[623233175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:767; }","duration":"174.798478ms","start":"2026-07-09T13:08:21.379671Z","end":"2026-07-09T13:08:21.55447Z","steps":["trace[623233175] 'agreement among raft nodes before linearized reading' (duration: 174.626656ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:21.554416Z","caller":"traceutil/trace.go:171","msg":"trace[1491858418] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:767; }","duration":"180.818103ms","start":"2026-07-09T13:08:21.373585Z","end":"2026-07-09T13:08:21.554403Z","steps":["trace[1491858418] 'agreement among raft nodes before linearized reading' (duration: 180.736242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:21.554512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.631072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T13:08:21.554576Z","caller":"traceutil/trace.go:171","msg":"trace[2099770441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:767; }","duration":"175.707114ms","start":"2026-07-09T13:08:21.378861Z","end":"2026-07-09T13:08:21.554568Z","steps":["trace[2099770441] 'agreement among raft nodes before linearized reading' (duration: 175.575002ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:25.031813Z","caller":"traceutil/trace.go:171","msg":"trace[1955029227] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"172.752969ms","start":"2026-07-09T13:08:24.859035Z","end":"2026-07-09T13:08:25.031788Z","steps":["trace[1955029227] 'read index received' (duration: 172.478555ms)","trace[1955029227] 'applied index is now lower than readState.Index' (duration: 273.354µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:08:25.031855Z","caller":"traceutil/trace.go:171","msg":"trace[1628209777] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"185.536771ms","start":"2026-07-09T13:08:24.846286Z","end":"2026-07-09T13:08:25.031822Z","steps":["trace[1628209777] 'process raft request' (duration: 185.287787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:25.032077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.973643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T13:08:25.032163Z","caller":"traceutil/trace.go:171","msg":"trace[1034660102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:785; }","duration":"173.119966ms","start":"2026-07-09T13:08:24.858989Z","end":"2026-07-09T13:08:25.032109Z","steps":["trace[1034660102] 'agreement among raft nodes before linearized reading' (duration: 172.907712ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:25.142308Z","caller":"traceutil/trace.go:171","msg":"trace[1616367791] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"105.993857ms","start":"2026-07-09T13:08:25.036288Z","end":"2026-07-09T13:08:25.142282Z","steps":["trace[1616367791] 'process raft request' (duration: 105.817014ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:39.641551Z","caller":"traceutil/trace.go:171","msg":"trace[1593375280] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"407.874708ms","start":"2026-07-09T13:08:39.233648Z","end":"2026-07-09T13:08:39.641523Z","steps":["trace[1593375280] 'read index received' (duration: 407.638064ms)","trace[1593375280] 'applied index is now lower than readState.Index' (duration: 235.224µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:08:39.641812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.126503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-09T13:08:39.641857Z","caller":"traceutil/trace.go:171","msg":"trace[1326389481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:948; }","duration":"408.231305ms","start":"2026-07-09T13:08:39.233614Z","end":"2026-07-09T13:08:39.641845Z","steps":["trace[1326389481] 'agreement among raft nodes before linearized reading' (duration: 408.029371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:39.641893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:39.233594Z","time spent":"408.290365ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T13:08:39.642541Z","caller":"traceutil/trace.go:171","msg":"trace[1283581374] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"575.77842ms","start":"2026-07-09T13:08:39.06675Z","end":"2026-07-09T13:08:39.642528Z","steps":["trace[1283581374] 'process raft request' (duration: 574.62438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:39.642653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:39.066732Z","time spent":"575.855261ms","remote":"127.0.0.1:40606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5469,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:08:40.519958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.542683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:08:40.520077Z","caller":"traceutil/trace.go:171","msg":"trace[1800191942] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"870.848156ms","start":"2026-07-09T13:08:39.649207Z","end":"2026-07-09T13:08:40.520055Z","steps":["trace[1800191942] 'process raft request' (duration: 555.986648ms)","trace[1800191942] 'compare' (duration: 314.443622ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:08:40.520145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:39.649179Z","time spent":"870.933578ms","remote":"127.0.0.1:40586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1104,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:08:41.13752Z","caller":"traceutil/trace.go:171","msg":"trace[1548264693] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"1.487514248s","start":"2026-07-09T13:08:39.649975Z","end":"2026-07-09T13:08:41.137489Z","steps":["trace[1548264693] 'process raft request' (duration: 1.487311405s)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:41.137502Z","caller":"traceutil/trace.go:171","msg":"trace[46564190] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"1.487764602s","start":"2026-07-09T13:08:39.649707Z","end":"2026-07-09T13:08:41.137472Z","steps":["trace[46564190] 'process raft request' (duration: 1.487451697s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:41.137685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:39.649966Z","time spent":"1.48763477s","remote":"127.0.0.1:40710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:08:41.137724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:39.64968Z","time spent":"1.487943614s","remote":"127.0.0.1:40870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5192,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:08:41.137504Z","caller":"traceutil/trace.go:171","msg":"trace[1923716498] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"1.487898154s","start":"2026-07-09T13:08:39.649577Z","end":"2026-07-09T13:08:41.137475Z","steps":["trace[1923716498] 'process raft request' (duration: 1.484525927s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:41.13787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:39.649554Z","time spent":"1.48822653s","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:08:41.137961Z","caller":"traceutil/trace.go:171","msg":"trace[1116011919] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"1.380426293s","start":"2026-07-09T13:08:39.757509Z","end":"2026-07-09T13:08:41.137936Z","steps":["trace[1116011919] 'process raft request' (duration: 1.379844353s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:41.138118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:39.757495Z","time spent":"1.380536754s","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:08:41.138364Z","caller":"traceutil/trace.go:171","msg":"trace[1171892816] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:985; }","duration":"1.418573373s","start":"2026-07-09T13:08:39.719771Z","end":"2026-07-09T13:08:41.138344Z","steps":["trace[1171892816] 'read index received' (duration: 485.536528ms)","trace[1171892816] 'applied index is now lower than readState.Index' (duration: 933.035635ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:08:41.138385Z","caller":"traceutil/trace.go:171","msg":"trace[1585803356] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"1.136474657s","start":"2026-07-09T13:08:40.001892Z","end":"2026-07-09T13:08:41.138367Z","steps":["trace[1585803356] 'process raft request' (duration: 1.136369935s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:41.138615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:40.001867Z","time spent":"1.136684021s","remote":"127.0.0.1:40700","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-09T13:08:41.138642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418867828s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T13:08:41.138696Z","caller":"traceutil/trace.go:171","msg":"trace[1164542992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"1.41895765s","start":"2026-07-09T13:08:39.719722Z","end":"2026-07-09T13:08:41.13868Z","steps":["trace[1164542992] 'agreement among raft nodes before linearized reading' (duration: 1.418790477s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:41.13874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:39.719702Z","time spent":"1.419027031s","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T13:08:41.316664Z","caller":"traceutil/trace.go:171","msg":"trace[1404102463] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"178.154867ms","start":"2026-07-09T13:08:41.138479Z","end":"2026-07-09T13:08:41.316634Z","steps":["trace[1404102463] 'read index received' (duration: 158.560594ms)","trace[1404102463] 'applied index is now lower than readState.Index' (duration: 19.591383ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:08:41.316832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.595453089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T13:08:41.316811Z","caller":"traceutil/trace.go:171","msg":"trace[1972481449] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"170.996057ms","start":"2026-07-09T13:08:41.145792Z","end":"2026-07-09T13:08:41.316788Z","steps":["trace[1972481449] 'process raft request' (duration: 170.918215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:41.316897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.342049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:08:41.316887Z","caller":"traceutil/trace.go:171","msg":"trace[88170370] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"173.396087ms","start":"2026-07-09T13:08:41.143473Z","end":"2026-07-09T13:08:41.316869Z","steps":["trace[88170370] 'process raft request' (duration: 173.094452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:41.316927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.59320171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T13:08:41.316947Z","caller":"traceutil/trace.go:171","msg":"trace[656421365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:956; }","duration":"1.59323211s","start":"2026-07-09T13:08:39.723709Z","end":"2026-07-09T13:08:41.316942Z","steps":["trace[656421365] 'agreement among raft nodes before linearized reading' (duration: 1.593163879s)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:41.316956Z","caller":"traceutil/trace.go:171","msg":"trace[2066768348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"687.405721ms","start":"2026-07-09T13:08:40.62952Z","end":"2026-07-09T13:08:41.316926Z","steps":["trace[2066768348] 'agreement among raft nodes before linearized reading' (duration: 687.33343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:41.316969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:39.723701Z","time spent":"1.593261982s","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T13:08:41.317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:40.629501Z","time spent":"687.490922ms","remote":"127.0.0.1:40418","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-09T13:08:41.317061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.594219628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:08:41.317079Z","caller":"traceutil/trace.go:171","msg":"trace[380821919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:956; }","duration":"1.594263249s","start":"2026-07-09T13:08:39.722812Z","end":"2026-07-09T13:08:41.317075Z","steps":["trace[380821919] 'agreement among raft nodes before linearized reading' (duration: 1.594211398s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:41.317095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:39.722796Z","time spent":"1.59429609s","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T13:08:41.31686Z","caller":"traceutil/trace.go:171","msg":"trace[1492629302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"1.59552044s","start":"2026-07-09T13:08:39.721332Z","end":"2026-07-09T13:08:41.316852Z","steps":["trace[1492629302] 'agreement among raft nodes before linearized reading' (duration: 1.595394778s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:41.317392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:39.721315Z","time spent":"1.596070229s","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T13:08:41.316801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.0981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T13:08:41.317453Z","caller":"traceutil/trace.go:171","msg":"trace[1945975076] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:955; }","duration":"907.784492ms","start":"2026-07-09T13:08:40.409661Z","end":"2026-07-09T13:08:41.317445Z","steps":["trace[1945975076] 'agreement among raft nodes before linearized reading' (duration: 907.002469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:41.317478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:08:40.409605Z","time spent":"907.862733ms","remote":"127.0.0.1:40798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-07-09T13:08:51.266714Z","caller":"traceutil/trace.go:171","msg":"trace[161002386] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"112.672123ms","start":"2026-07-09T13:08:51.154019Z","end":"2026-07-09T13:08:51.266691Z","steps":["trace[161002386] 'process raft request' (duration: 112.49338ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:51.551751Z","caller":"traceutil/trace.go:171","msg":"trace[295681005] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"141.894594ms","start":"2026-07-09T13:08:51.409838Z","end":"2026-07-09T13:08:51.551733Z","steps":["trace[295681005] 'read index received' (duration: 94.835893ms)","trace[295681005] 'applied index is now lower than readState.Index' (duration: 47.057701ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:08:51.551996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.142578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:08:51.552054Z","caller":"traceutil/trace.go:171","msg":"trace[580825220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1001; }","duration":"142.23659ms","start":"2026-07-09T13:08:51.409803Z","end":"2026-07-09T13:08:51.552039Z","steps":["trace[580825220] 'agreement among raft nodes before linearized reading' (duration: 142.064927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:08:51.552057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.156239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T13:08:51.552094Z","caller":"traceutil/trace.go:171","msg":"trace[1325950143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1001; }","duration":"142.21209ms","start":"2026-07-09T13:08:51.409871Z","end":"2026-07-09T13:08:51.552083Z","steps":["trace[1325950143] 'agreement among raft nodes before linearized reading' (duration: 142.055857ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:51.5522Z","caller":"traceutil/trace.go:171","msg":"trace[1108557273] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"143.981918ms","start":"2026-07-09T13:08:51.408208Z","end":"2026-07-09T13:08:51.55219Z","steps":["trace[1108557273] 'process raft request' (duration: 96.534461ms)","trace[1108557273] 'compare' (duration: 46.903688ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:08:51.552202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.452027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T13:08:51.552238Z","caller":"traceutil/trace.go:171","msg":"trace[1608400267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1001; }","duration":"141.504428ms","start":"2026-07-09T13:08:51.410724Z","end":"2026-07-09T13:08:51.552229Z","steps":["trace[1608400267] 'agreement among raft nodes before linearized reading' (duration: 141.406036ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:51.738142Z","caller":"traceutil/trace.go:171","msg":"trace[1679235597] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"181.750094ms","start":"2026-07-09T13:08:51.556371Z","end":"2026-07-09T13:08:51.738121Z","steps":["trace[1679235597] 'process raft request' (duration: 165.3866ms)","trace[1679235597] 'compare' (duration: 16.230282ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:08:51.738289Z","caller":"traceutil/trace.go:171","msg":"trace[999287530] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"181.814745ms","start":"2026-07-09T13:08:51.556449Z","end":"2026-07-09T13:08:51.738264Z","steps":["trace[999287530] 'process raft request' (duration: 181.644773ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:08:59.485957Z","caller":"traceutil/trace.go:171","msg":"trace[166771645] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"129.002629ms","start":"2026-07-09T13:08:59.356919Z","end":"2026-07-09T13:08:59.485922Z","steps":["trace[166771645] 'process raft request' (duration: 101.141734ms)","trace[166771645] 'compare' (duration: 27.719042ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:09:27.848794Z","caller":"traceutil/trace.go:171","msg":"trace[229865116] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"175.367293ms","start":"2026-07-09T13:09:27.673409Z","end":"2026-07-09T13:09:27.848776Z","steps":["trace[229865116] 'process raft request' (duration: 175.260012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:24.64832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.277128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:10:24.648429Z","caller":"traceutil/trace.go:171","msg":"trace[668202824] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1407; }","duration":"327.0996ms","start":"2026-07-09T13:10:24.321315Z","end":"2026-07-09T13:10:24.648414Z","steps":["trace[668202824] 'read index received' (duration: 18.69221ms)","trace[668202824] 'applied index is now lower than readState.Index' (duration: 308.40595ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:10:24.648587Z","caller":"traceutil/trace.go:171","msg":"trace[142948382] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"360.495351ms","start":"2026-07-09T13:10:24.288002Z","end":"2026-07-09T13:10:24.648497Z","steps":["trace[142948382] 'process raft request' (duration: 51.971189ms)","trace[142948382] 'compare' (duration: 308.110004ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:10:24.648747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:24.287985Z","time spent":"360.634922ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:10:24.648743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.411095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T13:10:24.648802Z","caller":"traceutil/trace.go:171","msg":"trace[2110226954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1347; }","duration":"327.518497ms","start":"2026-07-09T13:10:24.321269Z","end":"2026-07-09T13:10:24.648787Z","steps":["trace[2110226954] 'agreement among raft nodes before linearized reading' (duration: 327.368425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:24.648836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:24.321246Z","time spent":"327.583998ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T13:10:24.649003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.417019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:10:24.649218Z","caller":"traceutil/trace.go:171","msg":"trace[1608769624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1347; }","duration":"146.638993ms","start":"2026-07-09T13:10:24.502527Z","end":"2026-07-09T13:10:24.649166Z","steps":["trace[1608769624] 'agreement among raft nodes before linearized reading' (duration: 146.197536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:24.649734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.215898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:10:24.650009Z","caller":"traceutil/trace.go:171","msg":"trace[1293235582] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1347; }","duration":"116.531403ms","start":"2026-07-09T13:10:24.533461Z","end":"2026-07-09T13:10:24.649993Z","steps":["trace[1293235582] 'agreement among raft nodes before linearized reading' (duration: 116.228999ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:30.845075Z","caller":"traceutil/trace.go:171","msg":"trace[1731379039] linearizableReadLoop","detail":"{readStateIndex:1434; appliedIndex:1433; }","duration":"153.208956ms","start":"2026-07-09T13:10:30.691841Z","end":"2026-07-09T13:10:30.84505Z","steps":["trace[1731379039] 'read index received' (duration: 153.132295ms)","trace[1731379039] 'applied index is now lower than readState.Index' (duration: 75.601µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:10:30.845399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.521921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:10:30.845481Z","caller":"traceutil/trace.go:171","msg":"trace[1515881539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1371; }","duration":"153.661084ms","start":"2026-07-09T13:10:30.691804Z","end":"2026-07-09T13:10:30.845465Z","steps":["trace[1515881539] 'agreement among raft nodes before linearized reading' (duration: 153.382609ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:30.845676Z","caller":"traceutil/trace.go:171","msg":"trace[221278476] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"168.055056ms","start":"2026-07-09T13:10:30.677059Z","end":"2026-07-09T13:10:30.845114Z","steps":["trace[221278476] 'process raft request' (duration: 167.830133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:30.849373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.591657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:10:30.849434Z","caller":"traceutil/trace.go:171","msg":"trace[1881092111] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1371; }","duration":"123.658278ms","start":"2026-07-09T13:10:30.725761Z","end":"2026-07-09T13:10:30.849419Z","steps":["trace[1881092111] 'agreement among raft nodes before linearized reading' (duration: 123.561486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:30.849489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.459084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T13:10:30.849723Z","caller":"traceutil/trace.go:171","msg":"trace[63921175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1371; }","duration":"152.601396ms","start":"2026-07-09T13:10:30.696996Z","end":"2026-07-09T13:10:30.849598Z","steps":["trace[63921175] 'agreement among raft nodes before linearized reading' (duration: 152.318352ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:32.980708Z","caller":"traceutil/trace.go:171","msg":"trace[1181821275] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1440; }","duration":"119.292139ms","start":"2026-07-09T13:10:32.861388Z","end":"2026-07-09T13:10:32.98068Z","steps":["trace[1181821275] 'read index received' (duration: 119.029855ms)","trace[1181821275] 'applied index is now lower than readState.Index' (duration: 261.063µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:10:32.980855Z","caller":"traceutil/trace.go:171","msg":"trace[1099812692] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"127.167999ms","start":"2026-07-09T13:10:32.853667Z","end":"2026-07-09T13:10:32.980835Z","steps":["trace[1099812692] 'process raft request' (duration: 126.847764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:32.980994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.764202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver\" ","response":"range_response_count:1 size:724"} {"level":"info","ts":"2026-07-09T13:10:32.981094Z","caller":"traceutil/trace.go:171","msg":"trace[24424167] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver; range_end:; response_count:1; response_revision:1377; }","duration":"102.887214ms","start":"2026-07-09T13:10:32.878181Z","end":"2026-07-09T13:10:32.981068Z","steps":["trace[24424167] 'agreement among raft nodes before linearized reading' (duration: 102.737182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:32.981103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.42653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-09T13:10:32.981098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.595145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cilium\" ","response":"range_response_count:1 size:749"} {"level":"warn","ts":"2026-07-09T13:10:32.981144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.746675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:10:32.981173Z","caller":"traceutil/trace.go:171","msg":"trace[986154118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1377; }","duration":"119.543162ms","start":"2026-07-09T13:10:32.86161Z","end":"2026-07-09T13:10:32.981153Z","steps":["trace[986154118] 'agreement among raft nodes before linearized reading' (duration: 119.312038ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:32.981178Z","caller":"traceutil/trace.go:171","msg":"trace[1841724876] range","detail":"{range_begin:/registry/clusterrolebindings/cilium; range_end:; response_count:1; response_revision:1377; }","duration":"105.728428ms","start":"2026-07-09T13:10:32.875431Z","end":"2026-07-09T13:10:32.981159Z","steps":["trace[1841724876] 'agreement among raft nodes before linearized reading' (duration: 105.512595ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:32.981186Z","caller":"traceutil/trace.go:171","msg":"trace[846865630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1377; }","duration":"119.832866ms","start":"2026-07-09T13:10:32.861341Z","end":"2026-07-09T13:10:32.981174Z","steps":["trace[846865630] 'agreement among raft nodes before linearized reading' (duration: 119.675644ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:34.478902Z","caller":"traceutil/trace.go:171","msg":"trace[1473218248] linearizableReadLoop","detail":"{readStateIndex:1446; appliedIndex:1445; }","duration":"130.979577ms","start":"2026-07-09T13:10:34.347892Z","end":"2026-07-09T13:10:34.478872Z","steps":["trace[1473218248] 'read index received' (duration: 130.754703ms)","trace[1473218248] 'applied index is now lower than readState.Index' (duration: 223.574µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:10:34.478961Z","caller":"traceutil/trace.go:171","msg":"trace[338975328] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"156.285949ms","start":"2026-07-09T13:10:34.322652Z","end":"2026-07-09T13:10:34.478938Z","steps":["trace[338975328] 'process raft request' (duration: 156.068576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:34.47924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.778621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-07-09T13:10:34.479298Z","caller":"traceutil/trace.go:171","msg":"trace[1905600210] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation; range_end:; response_count:1; response_revision:1382; }","duration":"124.886703ms","start":"2026-07-09T13:10:34.354395Z","end":"2026-07-09T13:10:34.479282Z","steps":["trace[1905600210] 'agreement among raft nodes before linearized reading' (duration: 124.732811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:34.479294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.394223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:deployment-controller\" ","response":"range_response_count:1 size:913"} {"level":"info","ts":"2026-07-09T13:10:34.479351Z","caller":"traceutil/trace.go:171","msg":"trace[776179075] range","detail":"{range_begin:/registry/clusterroles/system:controller:deployment-controller; range_end:; response_count:1; response_revision:1382; }","duration":"131.454814ms","start":"2026-07-09T13:10:34.347879Z","end":"2026-07-09T13:10:34.479333Z","steps":["trace[776179075] 'agreement among raft nodes before linearized reading' (duration: 131.144569ms)"],"step_count":1}