{"level":"warn","ts":"2026-04-19T13:25:22.332051Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-19T13:25:22.332154Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.44: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.44:2380","--initial-cluster=instance=https://199.19.213.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.44: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-04-19T13:25:22.332221Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-19T13:25:22.332233Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.44:2380"]} {"level":"info","ts":"2026-04-19T13:25:22.332254Z","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-04-19T13:25:22.332812Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.44:2379"]} {"level":"info","ts":"2026-04-19T13:25:22.332894Z","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.44:2380"],"listen-peer-urls":["https://199.19.213.44:2380"],"advertise-client-urls":["https://199.19.213.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.44: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-04-19T13:25:22.341679Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.569802ms"} {"level":"info","ts":"2026-04-19T13:25:22.350738Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4f2979fc0f638003","cluster-id":"ce06e7bc3b23612b"} {"level":"info","ts":"2026-04-19T13:25:22.350843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 switched to configuration voters=()"} {"level":"info","ts":"2026-04-19T13:25:22.350866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became follower at term 0"} {"level":"info","ts":"2026-04-19T13:25:22.350876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4f2979fc0f638003 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-19T13:25:22.350894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became follower at term 1"} {"level":"info","ts":"2026-04-19T13:25:22.350935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 switched to configuration voters=(5704224526538342403)"} {"level":"warn","ts":"2026-04-19T13:25:22.361961Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-19T13:25:22.363479Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-19T13:25:22.364656Z","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-04-19T13:25:22.367799Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4f2979fc0f638003","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-19T13:25:22.368096Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4f2979fc0f638003","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-19T13:25:22.368258Z","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-04-19T13:25:22.368488Z","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-04-19T13:25:22.368503Z","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-04-19T13:25:22.369432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 switched to configuration voters=(5704224526538342403)"} {"level":"info","ts":"2026-04-19T13:25:22.369573Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ce06e7bc3b23612b","local-member-id":"4f2979fc0f638003","added-peer-id":"4f2979fc0f638003","added-peer-peer-urls":["https://199.19.213.44:2380"]} {"level":"info","ts":"2026-04-19T13:25:22.374306Z","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-04-19T13:25:22.37442Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.44:2380"} {"level":"info","ts":"2026-04-19T13:25:22.374485Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.44:2380"} {"level":"info","ts":"2026-04-19T13:25:22.374753Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4f2979fc0f638003","initial-advertise-peer-urls":["https://199.19.213.44:2380"],"listen-peer-urls":["https://199.19.213.44:2380"],"advertise-client-urls":["https://199.19.213.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-19T13:25:22.374839Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-19T13:25:22.651516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 is starting a new election at term 1"} {"level":"info","ts":"2026-04-19T13:25:22.65158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-19T13:25:22.6516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 received MsgPreVoteResp from 4f2979fc0f638003 at term 1"} {"level":"info","ts":"2026-04-19T13:25:22.65162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became candidate at term 2"} {"level":"info","ts":"2026-04-19T13:25:22.651631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 received MsgVoteResp from 4f2979fc0f638003 at term 2"} {"level":"info","ts":"2026-04-19T13:25:22.651646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became leader at term 2"} {"level":"info","ts":"2026-04-19T13:25:22.651658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4f2979fc0f638003 elected leader 4f2979fc0f638003 at term 2"} {"level":"info","ts":"2026-04-19T13:25:22.686119Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4f2979fc0f638003","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.44:2379]}","request-path":"/0/members/4f2979fc0f638003/attributes","cluster-id":"ce06e7bc3b23612b","publish-timeout":"7s"} {"level":"info","ts":"2026-04-19T13:25:22.686225Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T13:25:22.6865Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:25:22.686608Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T13:25:22.687967Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-19T13:25:22.692456Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.44:2379"} {"level":"info","ts":"2026-04-19T13:25:22.692519Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-19T13:25:22.69255Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-19T13:25:22.736915Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ce06e7bc3b23612b","local-member-id":"4f2979fc0f638003","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:25:22.737072Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:25:22.737107Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:25:28.60777Z","caller":"traceutil/trace.go:171","msg":"trace[834750102] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"130.023817ms","start":"2026-04-19T13:25:28.477721Z","end":"2026-04-19T13:25:28.607745Z","steps":["trace[834750102] 'process raft request' (duration: 129.873195ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:28.764883Z","caller":"traceutil/trace.go:171","msg":"trace[1012817069] linearizableReadLoop","detail":"{readStateIndex:289; appliedIndex:288; }","duration":"112.103598ms","start":"2026-04-19T13:25:28.652755Z","end":"2026-04-19T13:25:28.764858Z","steps":["trace[1012817069] 'read index received' (duration: 103.718873ms)","trace[1012817069] 'applied index is now lower than readState.Index' (duration: 8.383665ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:25:28.765076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.225499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-04-19T13:25:28.765027Z","caller":"traceutil/trace.go:171","msg":"trace[420867275] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"137.551527ms","start":"2026-04-19T13:25:28.62743Z","end":"2026-04-19T13:25:28.764981Z","steps":["trace[420867275] 'process raft request' (duration: 129.094162ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:28.765137Z","caller":"traceutil/trace.go:171","msg":"trace[947382485] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:275; }","duration":"112.362973ms","start":"2026-04-19T13:25:28.652762Z","end":"2026-04-19T13:25:28.765125Z","steps":["trace[947382485] 'agreement among raft nodes before linearized reading' (duration: 112.21488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:28.765176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.379753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"warn","ts":"2026-04-19T13:25:28.765272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.014024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:25:28.765279Z","caller":"traceutil/trace.go:171","msg":"trace[1822414495] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:275; }","duration":"112.524975ms","start":"2026-04-19T13:25:28.65273Z","end":"2026-04-19T13:25:28.765254Z","steps":["trace[1822414495] 'agreement among raft nodes before linearized reading' (duration: 112.26227ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:28.765327Z","caller":"traceutil/trace.go:171","msg":"trace[1127006301] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:275; }","duration":"109.054625ms","start":"2026-04-19T13:25:28.656238Z","end":"2026-04-19T13:25:28.765293Z","steps":["trace[1127006301] 'agreement among raft nodes before linearized reading' (duration: 109.016654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:28.765345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.504145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"info","ts":"2026-04-19T13:25:28.765388Z","caller":"traceutil/trace.go:171","msg":"trace[1974466686] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:275; }","duration":"112.579285ms","start":"2026-04-19T13:25:28.652798Z","end":"2026-04-19T13:25:28.765376Z","steps":["trace[1974466686] 'agreement among raft nodes before linearized reading' (duration: 112.463783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:28.765561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.744179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4659"} {"level":"warn","ts":"2026-04-19T13:25:28.765206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.332992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-04-19T13:25:28.765637Z","caller":"traceutil/trace.go:171","msg":"trace[935598001] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:275; }","duration":"112.84108ms","start":"2026-04-19T13:25:28.652783Z","end":"2026-04-19T13:25:28.765624Z","steps":["trace[935598001] 'agreement among raft nodes before linearized reading' (duration: 112.695538ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:28.765665Z","caller":"traceutil/trace.go:171","msg":"trace[704115187] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:275; }","duration":"112.81807ms","start":"2026-04-19T13:25:28.65284Z","end":"2026-04-19T13:25:28.765658Z","steps":["trace[704115187] 'agreement among raft nodes before linearized reading' (duration: 112.305941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:29.016051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.371262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:25:29.016211Z","caller":"traceutil/trace.go:171","msg":"trace[1032051616] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"198.951557ms","start":"2026-04-19T13:25:28.817246Z","end":"2026-04-19T13:25:29.016197Z","steps":["trace[1032051616] 'process raft request' (duration: 198.896436ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:29.016211Z","caller":"traceutil/trace.go:171","msg":"trace[1606260851] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"238.228296ms","start":"2026-04-19T13:25:28.777941Z","end":"2026-04-19T13:25:29.016169Z","steps":["trace[1606260851] 'process raft request' (duration: 129.188332ms)","trace[1606260851] 'compare' (duration: 108.25982ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:25:29.01635Z","caller":"traceutil/trace.go:171","msg":"trace[896538924] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"199.10979ms","start":"2026-04-19T13:25:28.817196Z","end":"2026-04-19T13:25:29.016306Z","steps":["trace[896538924] 'read index received' (duration: 89.914394ms)","trace[896538924] 'applied index is now lower than readState.Index' (duration: 109.191316ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:25:29.0166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.383194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:25:29.018441Z","caller":"traceutil/trace.go:171","msg":"trace[1014588958] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:0; response_revision:278; }","duration":"200.439503ms","start":"2026-04-19T13:25:28.817147Z","end":"2026-04-19T13:25:29.017586Z","steps":["trace[1014588958] 'agreement among raft nodes before linearized reading' (duration: 199.398844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:29.321921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.95035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:25:29.322184Z","caller":"traceutil/trace.go:171","msg":"trace[1365396914] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"215.675282ms","start":"2026-04-19T13:25:29.106486Z","end":"2026-04-19T13:25:29.322162Z","steps":["trace[1365396914] 'process raft request' (duration: 215.59763ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:29.322192Z","caller":"traceutil/trace.go:171","msg":"trace[1537887105] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"218.761465ms","start":"2026-04-19T13:25:29.103388Z","end":"2026-04-19T13:25:29.32215Z","steps":["trace[1537887105] 'process raft request' (duration: 32.515569ms)","trace[1537887105] 'compare' (duration: 185.786378ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:25:32.251202Z","caller":"traceutil/trace.go:171","msg":"trace[53912783] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:351; }","duration":"150.163871ms","start":"2026-04-19T13:25:32.100961Z","end":"2026-04-19T13:25:32.251125Z","steps":["trace[53912783] 'read index received' (duration: 1.811471ms)","trace[53912783] 'applied index is now lower than readState.Index' (duration: 148.35105ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:25:32.251676Z","caller":"traceutil/trace.go:171","msg":"trace[1997508280] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"153.951507ms","start":"2026-04-19T13:25:32.09766Z","end":"2026-04-19T13:25:32.251612Z","steps":["trace[1997508280] 'process raft request' (duration: 153.249575ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:32.251987Z","caller":"traceutil/trace.go:171","msg":"trace[139088297] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"153.080912ms","start":"2026-04-19T13:25:32.098607Z","end":"2026-04-19T13:25:32.251688Z","steps":["trace[139088297] 'process raft request' (duration: 152.37621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:32.252386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.399183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-19T13:25:32.252455Z","caller":"traceutil/trace.go:171","msg":"trace[626407108] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:341; }","duration":"151.503356ms","start":"2026-04-19T13:25:32.10092Z","end":"2026-04-19T13:25:32.252423Z","steps":["trace[626407108] 'agreement among raft nodes before linearized reading' (duration: 150.367527ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:32.255693Z","caller":"traceutil/trace.go:171","msg":"trace[1859987204] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"157.760152ms","start":"2026-04-19T13:25:32.097661Z","end":"2026-04-19T13:25:32.255421Z","steps":["trace[1859987204] 'process raft request' (duration: 150.811334ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:32.278229Z","caller":"traceutil/trace.go:171","msg":"trace[472805924] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"173.371197ms","start":"2026-04-19T13:25:32.104844Z","end":"2026-04-19T13:25:32.278215Z","steps":["trace[472805924] 'process raft request' (duration: 173.334446ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:32.278529Z","caller":"traceutil/trace.go:171","msg":"trace[180995131] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"174.16774ms","start":"2026-04-19T13:25:32.104077Z","end":"2026-04-19T13:25:32.278244Z","steps":["trace[180995131] 'process raft request' (duration: 174.006906ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:32.622906Z","caller":"traceutil/trace.go:171","msg":"trace[1942270143] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:362; }","duration":"224.041628ms","start":"2026-04-19T13:25:32.398839Z","end":"2026-04-19T13:25:32.62288Z","steps":["trace[1942270143] 'read index received' (duration: 223.933316ms)","trace[1942270143] 'applied index is now lower than readState.Index' (duration: 107.392µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:25:32.62303Z","caller":"traceutil/trace.go:171","msg":"trace[750116722] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"268.84739ms","start":"2026-04-19T13:25:32.35417Z","end":"2026-04-19T13:25:32.623018Z","steps":["trace[750116722] 'process raft request' (duration: 268.556676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:32.623282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.421795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-19T13:25:32.62335Z","caller":"traceutil/trace.go:171","msg":"trace[96319270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:349; }","duration":"224.525526ms","start":"2026-04-19T13:25:32.398812Z","end":"2026-04-19T13:25:32.623337Z","steps":["trace[96319270] 'agreement among raft nodes before linearized reading' (duration: 224.379423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:32.623429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.105103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-19T13:25:32.623508Z","caller":"traceutil/trace.go:171","msg":"trace[604784398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:349; }","duration":"223.214575ms","start":"2026-04-19T13:25:32.400275Z","end":"2026-04-19T13:25:32.623489Z","steps":["trace[604784398] 'agreement among raft nodes before linearized reading' (duration: 223.087493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:32.623655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.182757ms","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-04-19T13:25:32.623744Z","caller":"traceutil/trace.go:171","msg":"trace[1467357880] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:349; }","duration":"172.32355ms","start":"2026-04-19T13:25:32.451406Z","end":"2026-04-19T13:25:32.623729Z","steps":["trace[1467357880] 'agreement among raft nodes before linearized reading' (duration: 172.194858ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:32.801915Z","caller":"traceutil/trace.go:171","msg":"trace[609169125] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"173.504859ms","start":"2026-04-19T13:25:32.628384Z","end":"2026-04-19T13:25:32.801889Z","steps":["trace[609169125] 'process raft request' (duration: 107.283564ms)","trace[609169125] 'compare' (duration: 66.018722ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:25:32.802168Z","caller":"traceutil/trace.go:171","msg":"trace[1363946557] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"172.756256ms","start":"2026-04-19T13:25:32.629397Z","end":"2026-04-19T13:25:32.802153Z","steps":["trace[1363946557] 'process raft request' (duration: 172.421981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:34.205585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.036751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-04-19T13:25:34.2057Z","caller":"traceutil/trace.go:171","msg":"trace[1070419857] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:355; }","duration":"243.196334ms","start":"2026-04-19T13:25:33.96248Z","end":"2026-04-19T13:25:34.205676Z","steps":["trace[1070419857] 'range keys from in-memory index tree' (duration: 242.91276ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:34.381185Z","caller":"traceutil/trace.go:171","msg":"trace[776164186] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"118.58705ms","start":"2026-04-19T13:25:34.262574Z","end":"2026-04-19T13:25:34.381161Z","steps":["trace[776164186] 'process raft request' (duration: 118.388776ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:34.872091Z","caller":"traceutil/trace.go:171","msg":"trace[2064887426] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:373; }","duration":"201.947246ms","start":"2026-04-19T13:25:34.67012Z","end":"2026-04-19T13:25:34.872067Z","steps":["trace[2064887426] 'read index received' (duration: 201.798684ms)","trace[2064887426] 'applied index is now lower than readState.Index' (duration: 147.572µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:25:34.872175Z","caller":"traceutil/trace.go:171","msg":"trace[802616845] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"276.289322ms","start":"2026-04-19T13:25:34.595858Z","end":"2026-04-19T13:25:34.872147Z","steps":["trace[802616845] 'process raft request' (duration: 276.054558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:34.872889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.751151ms","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-04-19T13:25:34.872956Z","caller":"traceutil/trace.go:171","msg":"trace[88124713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:359; }","duration":"202.870493ms","start":"2026-04-19T13:25:34.670069Z","end":"2026-04-19T13:25:34.87294Z","steps":["trace[88124713] 'agreement among raft nodes before linearized reading' (duration: 202.552357ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:35.003978Z","caller":"traceutil/trace.go:171","msg":"trace[368242498] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"126.447753ms","start":"2026-04-19T13:25:34.877516Z","end":"2026-04-19T13:25:35.003964Z","steps":["trace[368242498] 'process raft request' (duration: 126.396582ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:35.003974Z","caller":"traceutil/trace.go:171","msg":"trace[993154552] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"126.548365ms","start":"2026-04-19T13:25:34.8774Z","end":"2026-04-19T13:25:35.003948Z","steps":["trace[993154552] 'process raft request' (duration: 110.063467ms)","trace[993154552] 'compare' (duration: 16.296715ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:25:35.004126Z","caller":"traceutil/trace.go:171","msg":"trace[1610096039] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"126.720117ms","start":"2026-04-19T13:25:34.877395Z","end":"2026-04-19T13:25:35.004116Z","steps":["trace[1610096039] 'process raft request' (duration: 126.471723ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:37.110508Z","caller":"traceutil/trace.go:171","msg":"trace[2095981369] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"174.635207ms","start":"2026-04-19T13:25:36.935847Z","end":"2026-04-19T13:25:37.110483Z","steps":["trace[2095981369] 'read index received' (duration: 174.424484ms)","trace[2095981369] 'applied index is now lower than readState.Index' (duration: 209.743µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:25:37.110549Z","caller":"traceutil/trace.go:171","msg":"trace[1458965076] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"200.867168ms","start":"2026-04-19T13:25:36.909668Z","end":"2026-04-19T13:25:37.110535Z","steps":["trace[1458965076] 'process raft request' (duration: 200.686134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:37.110671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.818411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-19T13:25:37.110722Z","caller":"traceutil/trace.go:171","msg":"trace[1425022537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:366; }","duration":"174.916522ms","start":"2026-04-19T13:25:36.935791Z","end":"2026-04-19T13:25:37.110707Z","steps":["trace[1425022537] 'agreement among raft nodes before linearized reading' (duration: 174.81428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:37.110804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.884526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-19T13:25:37.11083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.739405ms","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-04-19T13:25:37.110884Z","caller":"traceutil/trace.go:171","msg":"trace[2042621345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:366; }","duration":"103.011179ms","start":"2026-04-19T13:25:37.007852Z","end":"2026-04-19T13:25:37.110863Z","steps":["trace[2042621345] 'agreement among raft nodes before linearized reading' (duration: 102.843466ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:37.110925Z","caller":"traceutil/trace.go:171","msg":"trace[535582699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:366; }","duration":"102.886576ms","start":"2026-04-19T13:25:37.008018Z","end":"2026-04-19T13:25:37.110904Z","steps":["trace[535582699] 'agreement among raft nodes before linearized reading' (duration: 102.727964ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:37.293922Z","caller":"traceutil/trace.go:171","msg":"trace[2094348930] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"177.230627ms","start":"2026-04-19T13:25:37.116668Z","end":"2026-04-19T13:25:37.293899Z","steps":["trace[2094348930] 'process raft request' (duration: 177.187707ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:37.294025Z","caller":"traceutil/trace.go:171","msg":"trace[1333725753] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"177.893479ms","start":"2026-04-19T13:25:37.116109Z","end":"2026-04-19T13:25:37.294002Z","steps":["trace[1333725753] 'process raft request' (duration: 177.711056ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:37.293948Z","caller":"traceutil/trace.go:171","msg":"trace[702797516] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"178.814643ms","start":"2026-04-19T13:25:37.1151Z","end":"2026-04-19T13:25:37.293915Z","steps":["trace[702797516] 'process raft request' (duration: 106.890684ms)","trace[702797516] 'compare' (duration: 71.681015ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:25:38.919866Z","caller":"traceutil/trace.go:171","msg":"trace[273737643] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"225.007552ms","start":"2026-04-19T13:25:38.694822Z","end":"2026-04-19T13:25:38.919829Z","steps":["trace[273737643] 'process raft request' (duration: 224.684436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:39.785802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.922451ms","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-04-19T13:25:39.785858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.021162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-19T13:25:39.785919Z","caller":"traceutil/trace.go:171","msg":"trace[1004180579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:375; }","duration":"487.139213ms","start":"2026-04-19T13:25:39.298765Z","end":"2026-04-19T13:25:39.785905Z","steps":["trace[1004180579] 'range keys from in-memory index tree' (duration: 486.89157ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:39.785909Z","caller":"traceutil/trace.go:171","msg":"trace[510743217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:375; }","duration":"487.080823ms","start":"2026-04-19T13:25:39.298804Z","end":"2026-04-19T13:25:39.785885Z","steps":["trace[510743217] 'range keys from in-memory index tree' (duration: 485.943484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:39.78592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.92945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-19T13:25:39.785947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:39.298743Z","time spent":"487.199215ms","remote":"127.0.0.1:42432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-19T13:25:39.785996Z","caller":"traceutil/trace.go:171","msg":"trace[1293512400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:375; }","duration":"630.048713ms","start":"2026-04-19T13:25:39.155923Z","end":"2026-04-19T13:25:39.785971Z","steps":["trace[1293512400] 'range keys from in-memory index tree' (duration: 629.797418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:39.785974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:39.298781Z","time spent":"487.172255ms","remote":"127.0.0.1:42432","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":"warn","ts":"2026-04-19T13:25:39.786045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:39.155865Z","time spent":"630.169054ms","remote":"127.0.0.1:42432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-19T13:25:39.78617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.320567ms","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-04-19T13:25:39.786222Z","caller":"traceutil/trace.go:171","msg":"trace[469183239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:375; }","duration":"487.387429ms","start":"2026-04-19T13:25:39.298804Z","end":"2026-04-19T13:25:39.786192Z","steps":["trace[469183239] 'range keys from in-memory index tree' (duration: 487.228305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:39.786253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:39.298791Z","time spent":"487.455509ms","remote":"127.0.0.1:42432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-19T13:25:42.313759Z","caller":"traceutil/trace.go:171","msg":"trace[1283001984] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:402; }","duration":"244.141029ms","start":"2026-04-19T13:25:42.069593Z","end":"2026-04-19T13:25:42.313734Z","steps":["trace[1283001984] 'read index received' (duration: 146.342483ms)","trace[1283001984] 'applied index is now lower than readState.Index' (duration: 97.797426ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:25:42.313872Z","caller":"traceutil/trace.go:171","msg":"trace[985663909] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"363.088285ms","start":"2026-04-19T13:25:41.950759Z","end":"2026-04-19T13:25:42.313848Z","steps":["trace[985663909] 'process raft request' (duration: 265.254678ms)","trace[985663909] 'compare' (duration: 97.598642ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:25:42.313893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.278062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:25:42.314068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:41.950729Z","time spent":"363.242827ms","remote":"127.0.0.1:42356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:25:42.314105Z","caller":"traceutil/trace.go:171","msg":"trace[406833226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:386; }","duration":"244.524075ms","start":"2026-04-19T13:25:42.06956Z","end":"2026-04-19T13:25:42.314084Z","steps":["trace[406833226] 'agreement among raft nodes before linearized reading' (duration: 244.277491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:44.080293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.030565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:25:44.08051Z","caller":"traceutil/trace.go:171","msg":"trace[1775697905] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"165.055299ms","start":"2026-04-19T13:25:43.915434Z","end":"2026-04-19T13:25:44.08049Z","steps":["trace[1775697905] 'process raft request' (duration: 164.970667ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:44.080515Z","caller":"traceutil/trace.go:171","msg":"trace[2131600340] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"167.192104ms","start":"2026-04-19T13:25:43.913292Z","end":"2026-04-19T13:25:44.080484Z","steps":["trace[2131600340] 'process raft request' (duration: 47.867504ms)","trace[2131600340] 'compare' (duration: 118.933153ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:25:44.212011Z","caller":"traceutil/trace.go:171","msg":"trace[1827596168] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"106.961486ms","start":"2026-04-19T13:25:44.105014Z","end":"2026-04-19T13:25:44.211976Z","steps":["trace[1827596168] 'process raft request' (duration: 104.79441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:44.662236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.67274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:25:44.662458Z","caller":"traceutil/trace.go:171","msg":"trace[876508091] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:412; }","duration":"188.235683ms","start":"2026-04-19T13:25:44.474168Z","end":"2026-04-19T13:25:44.662404Z","steps":["trace[876508091] 'read index received' (duration: 67.192µs)","trace[876508091] 'applied index is now lower than readState.Index' (duration: 188.167471ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:25:44.662539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.376075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:25:44.662565Z","caller":"traceutil/trace.go:171","msg":"trace[231065556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:397; }","duration":"188.456836ms","start":"2026-04-19T13:25:44.474097Z","end":"2026-04-19T13:25:44.662554Z","steps":["trace[231065556] 'agreement among raft nodes before linearized reading' (duration: 188.393225ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:44.662769Z","caller":"traceutil/trace.go:171","msg":"trace[1484608470] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"426.669436ms","start":"2026-04-19T13:25:44.236086Z","end":"2026-04-19T13:25:44.662755Z","steps":["trace[1484608470] 'process raft request' (duration: 426.245419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:44.662835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:44.236072Z","time spent":"426.722576ms","remote":"127.0.0.1:42592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:25:44.662893Z","caller":"traceutil/trace.go:171","msg":"trace[887254386] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"430.355336ms","start":"2026-04-19T13:25:44.232484Z","end":"2026-04-19T13:25:44.66284Z","steps":["trace[887254386] 'process raft request' (duration: 222.008675ms)","trace[887254386] 'compare' (duration: 207.528788ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:25:44.663369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:44.232457Z","time spent":"430.538289ms","remote":"127.0.0.1:42356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:25:44.874734Z","caller":"traceutil/trace.go:171","msg":"trace[204573496] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"113.28509ms","start":"2026-04-19T13:25:44.761407Z","end":"2026-04-19T13:25:44.874692Z","steps":["trace[204573496] 'process raft request' (duration: 113.137367ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:45.811647Z","caller":"traceutil/trace.go:171","msg":"trace[1359577558] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"127.432025ms","start":"2026-04-19T13:25:45.684175Z","end":"2026-04-19T13:25:45.811607Z","steps":["trace[1359577558] 'process raft request' (duration: 58.047345ms)","trace[1359577558] 'compare' (duration: 69.178936ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:25:46.034282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.559508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:25:46.034399Z","caller":"traceutil/trace.go:171","msg":"trace[1149492321] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"109.907938ms","start":"2026-04-19T13:25:45.924471Z","end":"2026-04-19T13:25:46.034379Z","steps":["trace[1149492321] 'read index received' (duration: 43.642µs)","trace[1149492321] 'applied index is now lower than readState.Index' (duration: 109.862896ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:25:46.034471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.00664ms","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-04-19T13:25:46.034461Z","caller":"traceutil/trace.go:171","msg":"trace[504556645] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"172.870823ms","start":"2026-04-19T13:25:45.861542Z","end":"2026-04-19T13:25:46.034413Z","steps":["trace[504556645] 'process raft request' (duration: 51.096661ms)","trace[504556645] 'compare' (duration: 121.416616ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:25:46.034502Z","caller":"traceutil/trace.go:171","msg":"trace[990604390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:425; }","duration":"110.08277ms","start":"2026-04-19T13:25:45.924411Z","end":"2026-04-19T13:25:46.034494Z","steps":["trace[990604390] 'agreement among raft nodes before linearized reading' (duration: 110.018779ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:55.63659Z","caller":"traceutil/trace.go:171","msg":"trace[788712252] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"473.386946ms","start":"2026-04-19T13:25:55.163181Z","end":"2026-04-19T13:25:55.636568Z","steps":["trace[788712252] 'process raft request' (duration: 473.262564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:55.636727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:55.163158Z","time spent":"473.496657ms","remote":"127.0.0.1:42432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:25:58.733481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.156515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:25:58.73369Z","caller":"traceutil/trace.go:171","msg":"trace[1854303159] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:507; }","duration":"200.285636ms","start":"2026-04-19T13:25:58.533387Z","end":"2026-04-19T13:25:58.733673Z","steps":["trace[1854303159] 'read index received' (duration: 153.112469ms)","trace[1854303159] 'applied index is now lower than readState.Index' (duration: 47.171986ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:25:58.733802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.419257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:25:58.733832Z","caller":"traceutil/trace.go:171","msg":"trace[1929461382] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:488; }","duration":"200.53501ms","start":"2026-04-19T13:25:58.533285Z","end":"2026-04-19T13:25:58.73382Z","steps":["trace[1929461382] 'agreement among raft nodes before linearized reading' (duration: 200.435668ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:58.734022Z","caller":"traceutil/trace.go:171","msg":"trace[1580378317] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"350.261167ms","start":"2026-04-19T13:25:58.383751Z","end":"2026-04-19T13:25:58.734012Z","steps":["trace[1580378317] 'process raft request' (duration: 349.84118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:58.734078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:58.383712Z","time spent":"350.333317ms","remote":"127.0.0.1:42432","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-04-19T13:25:58.734337Z","caller":"traceutil/trace.go:171","msg":"trace[203228022] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"543.298037ms","start":"2026-04-19T13:25:58.190741Z","end":"2026-04-19T13:25:58.734039Z","steps":["trace[203228022] 'process raft request' (duration: 99.491562ms)","trace[203228022] 'compare' (duration: 442.989592ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:25:58.734563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:58.190719Z","time spent":"543.712173ms","remote":"127.0.0.1:42432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:25:59.676005Z","caller":"traceutil/trace.go:171","msg":"trace[1734109886] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"147.678089ms","start":"2026-04-19T13:25:59.528309Z","end":"2026-04-19T13:25:59.675987Z","steps":["trace[1734109886] 'process raft request' (duration: 145.292972ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:59.681118Z","caller":"traceutil/trace.go:171","msg":"trace[1438714886] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"119.518206ms","start":"2026-04-19T13:25:59.561569Z","end":"2026-04-19T13:25:59.681087Z","steps":["trace[1438714886] 'process raft request' (duration: 118.925336ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:00.224191Z","caller":"traceutil/trace.go:171","msg":"trace[1483618905] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"504.099597ms","start":"2026-04-19T13:25:59.720066Z","end":"2026-04-19T13:26:00.224165Z","steps":["trace[1483618905] 'process raft request' (duration: 503.004709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:00.224345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:59.720043Z","time spent":"504.230269ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:26:00.23026Z","caller":"traceutil/trace.go:171","msg":"trace[271991031] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"482.783961ms","start":"2026-04-19T13:25:59.747453Z","end":"2026-04-19T13:26:00.230237Z","steps":["trace[271991031] 'process raft request' (duration: 482.518157ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:00.2303Z","caller":"traceutil/trace.go:171","msg":"trace[577979982] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:569; }","duration":"162.226605ms","start":"2026-04-19T13:26:00.068052Z","end":"2026-04-19T13:26:00.230279Z","steps":["trace[577979982] 'read index received' (duration: 155.102213ms)","trace[577979982] 'applied index is now lower than readState.Index' (duration: 7.123382ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:26:00.230369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:59.747425Z","time spent":"482.894932ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T13:26:00.230835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.773213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:26:00.230895Z","caller":"traceutil/trace.go:171","msg":"trace[928587277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"162.862325ms","start":"2026-04-19T13:26:00.068017Z","end":"2026-04-19T13:26:00.23088Z","steps":["trace[928587277] 'agreement among raft nodes before linearized reading' (duration: 162.783423ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:00.2304Z","caller":"traceutil/trace.go:171","msg":"trace[541246404] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"478.549762ms","start":"2026-04-19T13:25:59.751829Z","end":"2026-04-19T13:26:00.230379Z","steps":["trace[541246404] 'process raft request' (duration: 478.277849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:00.231335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:59.751794Z","time spent":"479.337286ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:26:00.881159Z","caller":"traceutil/trace.go:171","msg":"trace[1240239392] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"141.499149ms","start":"2026-04-19T13:26:00.739632Z","end":"2026-04-19T13:26:00.881131Z","steps":["trace[1240239392] 'read index received' (duration: 141.098622ms)","trace[1240239392] 'applied index is now lower than readState.Index' (duration: 399.347µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:26:00.88127Z","caller":"traceutil/trace.go:171","msg":"trace[499677658] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"337.894511ms","start":"2026-04-19T13:26:00.543337Z","end":"2026-04-19T13:26:00.881231Z","steps":["trace[499677658] 'process raft request' (duration: 337.459815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:00.881407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.754502ms","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-04-19T13:26:00.881445Z","caller":"traceutil/trace.go:171","msg":"trace[1158137939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"141.815384ms","start":"2026-04-19T13:26:00.739617Z","end":"2026-04-19T13:26:00.881432Z","steps":["trace[1158137939] 'agreement among raft nodes before linearized reading' (duration: 141.62094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:00.881429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:00.543301Z","time spent":"338.065614ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T13:26:06.401168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.447572ms","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-04-19T13:26:06.401256Z","caller":"traceutil/trace.go:171","msg":"trace[1673206831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"137.591198ms","start":"2026-04-19T13:26:06.263645Z","end":"2026-04-19T13:26:06.401236Z","steps":["trace[1673206831] 'range keys from in-memory index tree' (duration: 137.262505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:06.401427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.545773ms","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-04-19T13:26:06.401486Z","caller":"traceutil/trace.go:171","msg":"trace[703872956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"135.618926ms","start":"2026-04-19T13:26:06.265851Z","end":"2026-04-19T13:26:06.40147Z","steps":["trace[703872956] 'range keys from in-memory index tree' (duration: 135.43426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:06.401618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.164884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-19T13:26:06.401657Z","caller":"traceutil/trace.go:171","msg":"trace[398662959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"140.229618ms","start":"2026-04-19T13:26:06.261417Z","end":"2026-04-19T13:26:06.401647Z","steps":["trace[398662959] 'range keys from in-memory index tree' (duration: 140.071722ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:06.492845Z","caller":"traceutil/trace.go:171","msg":"trace[2029219951] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"200.901309ms","start":"2026-04-19T13:26:06.291917Z","end":"2026-04-19T13:26:06.492818Z","steps":["trace[2029219951] 'process raft request' (duration: 200.370367ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:06.580593Z","caller":"traceutil/trace.go:171","msg":"trace[383674385] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"175.198432ms","start":"2026-04-19T13:26:06.405374Z","end":"2026-04-19T13:26:06.580572Z","steps":["trace[383674385] 'process raft request' (duration: 175.099917ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:06.580595Z","caller":"traceutil/trace.go:171","msg":"trace[1892398956] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"175.560316ms","start":"2026-04-19T13:26:06.404993Z","end":"2026-04-19T13:26:06.580553Z","steps":["trace[1892398956] 'process raft request' (duration: 128.25035ms)","trace[1892398956] 'compare' (duration: 47.127998ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:26:06.581075Z","caller":"traceutil/trace.go:171","msg":"trace[1506896150] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"174.9331ms","start":"2026-04-19T13:26:06.406096Z","end":"2026-04-19T13:26:06.581029Z","steps":["trace[1506896150] 'process raft request' (duration: 174.43521ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:07.229404Z","caller":"traceutil/trace.go:171","msg":"trace[209097375] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"323.313713ms","start":"2026-04-19T13:26:06.906051Z","end":"2026-04-19T13:26:07.229365Z","steps":["trace[209097375] 'read index received' (duration: 300.079796ms)","trace[209097375] 'applied index is now lower than readState.Index' (duration: 23.232667ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:26:07.231178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.094366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-19T13:26:07.231353Z","caller":"traceutil/trace.go:171","msg":"trace[1835842106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"325.289234ms","start":"2026-04-19T13:26:06.906018Z","end":"2026-04-19T13:26:07.231307Z","steps":["trace[1835842106] 'agreement among raft nodes before linearized reading' (duration: 324.733751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:07.231456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:06.906Z","time spent":"325.438791ms","remote":"127.0.0.1:42432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-19T13:26:07.232216Z","caller":"traceutil/trace.go:171","msg":"trace[1501580361] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"547.220627ms","start":"2026-04-19T13:26:06.684947Z","end":"2026-04-19T13:26:07.232168Z","steps":["trace[1501580361] 'process raft request' (duration: 521.131233ms)","trace[1501580361] 'compare' (duration: 22.136802ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:26:07.232382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:06.684896Z","time spent":"547.394795ms","remote":"127.0.0.1:42356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:26:07.277363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.928513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:26:07.277405Z","caller":"traceutil/trace.go:171","msg":"trace[1194913621] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"279.312866ms","start":"2026-04-19T13:26:06.998054Z","end":"2026-04-19T13:26:07.277367Z","steps":["trace[1194913621] 'process raft request' (duration: 278.43675ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:07.277477Z","caller":"traceutil/trace.go:171","msg":"trace[346226299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"207.138353ms","start":"2026-04-19T13:26:07.070313Z","end":"2026-04-19T13:26:07.277451Z","steps":["trace[346226299] 'agreement among raft nodes before linearized reading' (duration: 206.909124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:07.946725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.713188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:26:07.946947Z","caller":"traceutil/trace.go:171","msg":"trace[1825409086] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"617.164581ms","start":"2026-04-19T13:26:07.329757Z","end":"2026-04-19T13:26:07.946922Z","steps":["trace[1825409086] 'process raft request' (duration: 617.087738ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:07.946971Z","caller":"traceutil/trace.go:171","msg":"trace[1374196452] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"667.89348ms","start":"2026-04-19T13:26:07.27904Z","end":"2026-04-19T13:26:07.946933Z","steps":["trace[1374196452] 'read index received' (duration: 473.88574ms)","trace[1374196452] 'applied index is now lower than readState.Index' (duration: 194.0046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:26:07.94708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:07.329736Z","time spent":"617.276807ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:26:07.947124Z","caller":"traceutil/trace.go:171","msg":"trace[378445052] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"707.111767ms","start":"2026-04-19T13:26:07.239992Z","end":"2026-04-19T13:26:07.947104Z","steps":["trace[378445052] 'process raft request' (duration: 512.910898ms)","trace[378445052] 'compare' (duration: 193.564463ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:26:07.947206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.143572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:26:07.947222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:07.239964Z","time spent":"707.2059ms","remote":"127.0.0.1:42432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:26:07.947248Z","caller":"traceutil/trace.go:171","msg":"trace[1691118081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"668.207695ms","start":"2026-04-19T13:26:07.279024Z","end":"2026-04-19T13:26:07.947232Z","steps":["trace[1691118081] 'agreement among raft nodes before linearized reading' (duration: 668.06725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:07.947288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:07.278948Z","time spent":"668.332539ms","remote":"127.0.0.1:42158","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-04-19T13:26:09.085121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389797719520332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T13:26:09.585456Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389797719520332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T13:26:09.87192Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.499668536s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T13:26:09.875442Z","caller":"traceutil/trace.go:171","msg":"trace[1755713189] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.504429369s","start":"2026-04-19T13:26:08.370969Z","end":"2026-04-19T13:26:09.875399Z","steps":["trace[1755713189] 'process raft request' (duration: 1.501351924s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:09.87571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:08.370937Z","time spent":"1.504639307s","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:26:09.876445Z","caller":"traceutil/trace.go:171","msg":"trace[770437864] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:623; }","duration":"1.291909762s","start":"2026-04-19T13:26:08.584516Z","end":"2026-04-19T13:26:09.876425Z","steps":["trace[770437864] 'read index received' (duration: 1.287703892s)","trace[770437864] 'applied index is now lower than readState.Index' (duration: 4.20465ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:26:09.876528Z","caller":"traceutil/trace.go:171","msg":"trace[937079799] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"789.447868ms","start":"2026-04-19T13:26:09.087049Z","end":"2026-04-19T13:26:09.876497Z","steps":["trace[937079799] 'process raft request' (duration: 789.293082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:09.876704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:09.087003Z","time spent":"789.580474ms","remote":"127.0.0.1:42432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:26:09.876744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292074588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-19T13:26:09.876695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.258819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:26:09.876882Z","caller":"traceutil/trace.go:171","msg":"trace[1755623251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"807.456367ms","start":"2026-04-19T13:26:09.069404Z","end":"2026-04-19T13:26:09.876861Z","steps":["trace[1755623251] 'agreement among raft nodes before linearized reading' (duration: 807.214487ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:09.876823Z","caller":"traceutil/trace.go:171","msg":"trace[245653275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"1.292199274s","start":"2026-04-19T13:26:08.584605Z","end":"2026-04-19T13:26:09.876804Z","steps":["trace[245653275] 'agreement among raft nodes before linearized reading' (duration: 1.291988765s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:09.876978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:08.584584Z","time spent":"1.292385121s","remote":"127.0.0.1:42432","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-04-19T13:26:09.876992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.291898101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-19T13:26:09.877046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:09.06934Z","time spent":"807.613383ms","remote":"127.0.0.1:42144","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-04-19T13:26:09.876703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292178273s","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-04-19T13:26:09.877166Z","caller":"traceutil/trace.go:171","msg":"trace[1222788461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"1.292040727s","start":"2026-04-19T13:26:08.585046Z","end":"2026-04-19T13:26:09.877087Z","steps":["trace[1222788461] 'agreement among raft nodes before linearized reading' (duration: 1.291671232s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:09.877275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:08.585038Z","time spent":"1.292219805s","remote":"127.0.0.1:42432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-19T13:26:09.877185Z","caller":"traceutil/trace.go:171","msg":"trace[1754018106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"1.292691503s","start":"2026-04-19T13:26:08.584476Z","end":"2026-04-19T13:26:09.877168Z","steps":["trace[1754018106] 'agreement among raft nodes before linearized reading' (duration: 1.292093659s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:09.877457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:08.584456Z","time spent":"1.292960575s","remote":"127.0.0.1:42432","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-04-19T13:26:11.356602Z","caller":"traceutil/trace.go:171","msg":"trace[456467339] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"538.0001ms","start":"2026-04-19T13:26:10.818545Z","end":"2026-04-19T13:26:11.356545Z","steps":["trace[456467339] 'process raft request' (duration: 531.7109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:11.356881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:10.818512Z","time spent":"538.24574ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:26:11.36585Z","caller":"traceutil/trace.go:171","msg":"trace[1302303558] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"295.708971ms","start":"2026-04-19T13:26:11.070077Z","end":"2026-04-19T13:26:11.365786Z","steps":["trace[1302303558] 'read index received' (duration: 279.307828ms)","trace[1302303558] 'applied index is now lower than readState.Index' (duration: 16.399623ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:26:11.36597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.877337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:26:11.365998Z","caller":"traceutil/trace.go:171","msg":"trace[1326333095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"295.964021ms","start":"2026-04-19T13:26:11.070024Z","end":"2026-04-19T13:26:11.365988Z","steps":["trace[1326333095] 'agreement among raft nodes before linearized reading' (duration: 295.884118ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:11.930893Z","caller":"traceutil/trace.go:171","msg":"trace[1028269512] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"114.094147ms","start":"2026-04-19T13:26:11.816768Z","end":"2026-04-19T13:26:11.930862Z","steps":["trace[1028269512] 'process raft request' (duration: 62.284226ms)","trace[1028269512] 'compare' (duration: 51.665965ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:26:15.077932Z","caller":"traceutil/trace.go:171","msg":"trace[1740833920] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"212.142468ms","start":"2026-04-19T13:26:14.865768Z","end":"2026-04-19T13:26:15.077911Z","steps":["trace[1740833920] 'process raft request' (duration: 212.000703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:26.514772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389797719520658,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T13:26:26.691173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.067688ms","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-04-19T13:26:26.691255Z","caller":"traceutil/trace.go:171","msg":"trace[1709390422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"968.210503ms","start":"2026-04-19T13:26:25.723027Z","end":"2026-04-19T13:26:26.691237Z","steps":["trace[1709390422] 'range keys from in-memory index tree' (duration: 967.884882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:26.691304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31793128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:26:26.691384Z","caller":"traceutil/trace.go:171","msg":"trace[1662396173] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:766; }","duration":"1.318101486s","start":"2026-04-19T13:26:25.373264Z","end":"2026-04-19T13:26:26.691366Z","steps":["trace[1662396173] 'count revisions from in-memory index tree' (duration: 1.317837727s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:26.691428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:25.373242Z","time spent":"1.318174609s","remote":"127.0.0.1:42322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-04-19T13:26:26.691712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.019276ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:26:26.691838Z","caller":"traceutil/trace.go:171","msg":"trace[2074973396] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:766; }","duration":"880.649168ms","start":"2026-04-19T13:26:25.811171Z","end":"2026-04-19T13:26:26.69182Z","steps":["trace[2074973396] 'range keys from in-memory index tree' (duration: 880.001905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:26.691833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013362543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.44\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-04-19T13:26:26.691894Z","caller":"traceutil/trace.go:171","msg":"trace[1555106398] range","detail":"{range_begin:/registry/masterleases/199.19.213.44; range_end:; response_count:1; response_revision:766; }","duration":"1.013468307s","start":"2026-04-19T13:26:25.678409Z","end":"2026-04-19T13:26:26.691878Z","steps":["trace[1555106398] 'range keys from in-memory index tree' (duration: 1.013179797s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:26.69194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:25.678385Z","time spent":"1.013542119s","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.44\" "} {"level":"info","ts":"2026-04-19T13:26:26.692066Z","caller":"traceutil/trace.go:171","msg":"trace[740630947] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"885.054975ms","start":"2026-04-19T13:26:25.807Z","end":"2026-04-19T13:26:26.692055Z","steps":["trace[740630947] 'process raft request' (duration: 884.533596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:26.692156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:25.806964Z","time spent":"885.128957ms","remote":"127.0.0.1:42412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:26:26.691312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:25.723006Z","time spent":"968.295355ms","remote":"127.0.0.1:42432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-19T13:26:26.693432Z","caller":"traceutil/trace.go:171","msg":"trace[589911763] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"447.81163ms","start":"2026-04-19T13:26:26.245595Z","end":"2026-04-19T13:26:26.693406Z","steps":["trace[589911763] 'process raft request' (duration: 447.589463ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:26.693345Z","caller":"traceutil/trace.go:171","msg":"trace[1800475033] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:798; }","duration":"679.423718ms","start":"2026-04-19T13:26:26.013866Z","end":"2026-04-19T13:26:26.69329Z","steps":["trace[1800475033] 'read index received' (duration: 677.799231ms)","trace[1800475033] 'applied index is now lower than readState.Index' (duration: 1.623417ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:26:26.693659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.781981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-19T13:26:26.69372Z","caller":"traceutil/trace.go:171","msg":"trace[1298459940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:768; }","duration":"679.881383ms","start":"2026-04-19T13:26:26.013822Z","end":"2026-04-19T13:26:26.693703Z","steps":["trace[1298459940] 'agreement among raft nodes before linearized reading' (duration: 679.709448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:26.693714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.299463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-19T13:26:26.693769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:26.0138Z","time spent":"679.957618ms","remote":"127.0.0.1:42432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-19T13:26:26.693776Z","caller":"traceutil/trace.go:171","msg":"trace[666207975] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:768; }","duration":"679.379797ms","start":"2026-04-19T13:26:26.014381Z","end":"2026-04-19T13:26:26.693761Z","steps":["trace[666207975] 'agreement among raft nodes before linearized reading' (duration: 679.235522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:26.693794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.264136ms","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-04-19T13:26:26.693821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:26.014374Z","time spent":"679.435918ms","remote":"127.0.0.1:42432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-19T13:26:26.693878Z","caller":"traceutil/trace.go:171","msg":"trace[1910348625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:768; }","duration":"676.315849ms","start":"2026-04-19T13:26:26.017504Z","end":"2026-04-19T13:26:26.69382Z","steps":["trace[1910348625] 'agreement among raft nodes before linearized reading' (duration: 676.201845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:26.693656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:26.245566Z","time spent":"447.917644ms","remote":"127.0.0.1:42432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:26:26.693923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:26.017497Z","time spent":"676.417192ms","remote":"127.0.0.1:42432","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-04-19T13:26:26.694058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.569053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:26:26.694113Z","caller":"traceutil/trace.go:171","msg":"trace[2055818354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"623.866853ms","start":"2026-04-19T13:26:26.070233Z","end":"2026-04-19T13:26:26.694099Z","steps":["trace[2055818354] 'agreement among raft nodes before linearized reading' (duration: 623.78846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:26.694096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.824738ms","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-04-19T13:26:26.694165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:26.070222Z","time spent":"623.934137ms","remote":"127.0.0.1:42144","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-04-19T13:26:26.694182Z","caller":"traceutil/trace.go:171","msg":"trace[793087186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"676.935761ms","start":"2026-04-19T13:26:26.017229Z","end":"2026-04-19T13:26:26.694165Z","steps":["trace[793087186] 'agreement among raft nodes before linearized reading' (duration: 676.491386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:26.694277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:26:26.01722Z","time spent":"677.044705ms","remote":"127.0.0.1:42432","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-04-19T13:26:52.971107Z","caller":"traceutil/trace.go:171","msg":"trace[632992747] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"109.421944ms","start":"2026-04-19T13:26:52.861638Z","end":"2026-04-19T13:26:52.97106Z","steps":["trace[632992747] 'process raft request' (duration: 109.238639ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:28:02.863804Z","caller":"traceutil/trace.go:171","msg":"trace[797633409] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"127.975611ms","start":"2026-04-19T13:28:02.735801Z","end":"2026-04-19T13:28:02.863777Z","steps":["trace[797633409] 'process raft request' (duration: 127.840418ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:28:03.054059Z","caller":"traceutil/trace.go:171","msg":"trace[1555887740] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"112.791368ms","start":"2026-04-19T13:28:02.941233Z","end":"2026-04-19T13:28:03.054025Z","steps":["trace[1555887740] 'process raft request' (duration: 59.668426ms)","trace[1555887740] 'compare' (duration: 52.967168ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:28:03.057573Z","caller":"traceutil/trace.go:171","msg":"trace[260735454] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"109.808022ms","start":"2026-04-19T13:28:02.947747Z","end":"2026-04-19T13:28:03.057555Z","steps":["trace[260735454] 'process raft request' (duration: 109.664639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:28:03.057677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.754002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:28:03.05755Z","caller":"traceutil/trace.go:171","msg":"trace[1678065934] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1277; }","duration":"109.624779ms","start":"2026-04-19T13:28:02.947902Z","end":"2026-04-19T13:28:03.057527Z","steps":["trace[1678065934] 'read index received' (duration: 52.970881ms)","trace[1678065934] 'applied index is now lower than readState.Index' (duration: 56.653018ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:28:03.057729Z","caller":"traceutil/trace.go:171","msg":"trace[119372349] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:1224; }","duration":"109.874434ms","start":"2026-04-19T13:28:02.947844Z","end":"2026-04-19T13:28:03.057718Z","steps":["trace[119372349] 'agreement among raft nodes before linearized reading' (duration: 109.757772ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:28:03.345493Z","caller":"traceutil/trace.go:171","msg":"trace[1808273254] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1280; }","duration":"267.891565ms","start":"2026-04-19T13:28:03.077578Z","end":"2026-04-19T13:28:03.34547Z","steps":["trace[1808273254] 'read index received' (duration: 257.52328ms)","trace[1808273254] 'applied index is now lower than readState.Index' (duration: 10.367485ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:28:03.345709Z","caller":"traceutil/trace.go:171","msg":"trace[437625841] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"270.052972ms","start":"2026-04-19T13:28:03.075631Z","end":"2026-04-19T13:28:03.345684Z","steps":["trace[437625841] 'process raft request' (duration: 259.517273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:28:03.345918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.276773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6e1afd81-9303-422a-8d91-92ed62f09815\" ","response":"range_response_count:1 size:3578"} {"level":"info","ts":"2026-04-19T13:28:03.346049Z","caller":"traceutil/trace.go:171","msg":"trace[877360749] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6e1afd81-9303-422a-8d91-92ed62f09815; range_end:; response_count:1; response_revision:1226; }","duration":"268.454608ms","start":"2026-04-19T13:28:03.077547Z","end":"2026-04-19T13:28:03.346002Z","steps":["trace[877360749] 'agreement among raft nodes before linearized reading' (duration: 268.209862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:28:11.297282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.756007ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-19T13:28:11.297479Z","caller":"traceutil/trace.go:171","msg":"trace[359026320] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"227.747363ms","start":"2026-04-19T13:28:11.069714Z","end":"2026-04-19T13:28:11.297462Z","steps":["trace[359026320] 'read index received' (duration: 54.746552ms)","trace[359026320] 'applied index is now lower than readState.Index' (duration: 172.999291ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:28:11.29755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.836485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:28:11.297576Z","caller":"traceutil/trace.go:171","msg":"trace[1142208341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"227.905857ms","start":"2026-04-19T13:28:11.069661Z","end":"2026-04-19T13:28:11.297567Z","steps":["trace[1142208341] 'agreement among raft nodes before linearized reading' (duration: 227.846436ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:28:19.817426Z","caller":"traceutil/trace.go:171","msg":"trace[785266118] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"365.303191ms","start":"2026-04-19T13:28:19.452098Z","end":"2026-04-19T13:28:19.817401Z","steps":["trace[785266118] 'process raft request' (duration: 365.112927ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:28:19.817513Z","caller":"traceutil/trace.go:171","msg":"trace[1933956404] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1367; }","duration":"164.840024ms","start":"2026-04-19T13:28:19.652606Z","end":"2026-04-19T13:28:19.817446Z","steps":["trace[1933956404] 'read index received' (duration: 164.809924ms)","trace[1933956404] 'applied index is now lower than readState.Index' (duration: 25.87µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:28:19.817792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:28:19.452083Z","time spent":"365.424724ms","remote":"127.0.0.1:42432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:28:19.81796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.317005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-19T13:28:19.818015Z","caller":"traceutil/trace.go:171","msg":"trace[774135708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1309; }","duration":"165.451557ms","start":"2026-04-19T13:28:19.652549Z","end":"2026-04-19T13:28:19.818001Z","steps":["trace[774135708] 'agreement among raft nodes before linearized reading' (duration: 165.11878ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:28:19.839913Z","caller":"traceutil/trace.go:171","msg":"trace[556585824] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"154.629602ms","start":"2026-04-19T13:28:19.685254Z","end":"2026-04-19T13:28:19.839883Z","steps":["trace[556585824] 'process raft request' (duration: 154.405966ms)"],"step_count":1}