{"level":"warn","ts":"2026-04-14T13:54:45.335095Z","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-14T13:54:45.335195Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.62: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.62:2380","--initial-cluster=instance=https://199.19.213.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.62: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-14T13:54:45.335265Z","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-14T13:54:45.335278Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.62:2380"]} {"level":"info","ts":"2026-04-14T13:54:45.335295Z","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-14T13:54:45.33593Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"]} {"level":"info","ts":"2026-04-14T13:54:45.336001Z","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.62:2380"],"listen-peer-urls":["https://199.19.213.62:2380"],"advertise-client-urls":["https://199.19.213.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.62: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-14T13:54:45.357473Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"21.245693ms"} {"level":"info","ts":"2026-04-14T13:54:45.383432Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2451868e402db19e","cluster-id":"1953d1daf63e3757"} {"level":"info","ts":"2026-04-14T13:54:45.383576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T13:54:45.383607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became follower at term 0"} {"level":"info","ts":"2026-04-14T13:54:45.383628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2451868e402db19e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T13:54:45.383651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became follower at term 1"} {"level":"info","ts":"2026-04-14T13:54:45.383715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=(2617020803999183262)"} {"level":"warn","ts":"2026-04-14T13:54:45.456097Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T13:54:45.462647Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T13:54:45.465615Z","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-14T13:54:45.47062Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2451868e402db19e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T13:54:45.47072Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2451868e402db19e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T13:54:45.471061Z","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-14T13:54:45.471123Z","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-14T13:54:45.471141Z","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-14T13:54:45.479806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=(2617020803999183262)"} {"level":"info","ts":"2026-04-14T13:54:45.480177Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1953d1daf63e3757","local-member-id":"2451868e402db19e","added-peer-id":"2451868e402db19e","added-peer-peer-urls":["https://199.19.213.62:2380"]} {"level":"info","ts":"2026-04-14T13:54:45.487219Z","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-14T13:54:45.487523Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2451868e402db19e","initial-advertise-peer-urls":["https://199.19.213.62:2380"],"listen-peer-urls":["https://199.19.213.62:2380"],"advertise-client-urls":["https://199.19.213.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T13:54:45.487555Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.62:2380"} {"level":"info","ts":"2026-04-14T13:54:45.487579Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T13:54:45.487589Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.62:2380"} {"level":"info","ts":"2026-04-14T13:54:46.185402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T13:54:46.185499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T13:54:46.185555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e received MsgPreVoteResp from 2451868e402db19e at term 1"} {"level":"info","ts":"2026-04-14T13:54:46.185578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became candidate at term 2"} {"level":"info","ts":"2026-04-14T13:54:46.185589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e received MsgVoteResp from 2451868e402db19e at term 2"} {"level":"info","ts":"2026-04-14T13:54:46.185606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became leader at term 2"} {"level":"info","ts":"2026-04-14T13:54:46.18562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2451868e402db19e elected leader 2451868e402db19e at term 2"} {"level":"info","ts":"2026-04-14T13:54:46.188079Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T13:54:46.191144Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2451868e402db19e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.62:2379]}","request-path":"/0/members/2451868e402db19e/attributes","cluster-id":"1953d1daf63e3757","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T13:54:46.191182Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T13:54:46.191146Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T13:54:46.191484Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T13:54:46.191586Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T13:54:46.19228Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1953d1daf63e3757","local-member-id":"2451868e402db19e","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T13:54:46.192394Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T13:54:46.192452Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T13:54:46.194165Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-14T13:54:46.195943Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.62:2379"} {"level":"info","ts":"2026-04-14T13:54:48.927792Z","caller":"traceutil/trace.go:171","msg":"trace[1721897076] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"113.012644ms","start":"2026-04-14T13:54:48.814739Z","end":"2026-04-14T13:54:48.927751Z","steps":["trace[1721897076] 'process raft request' (duration: 75.205275ms)","trace[1721897076] 'compare' (duration: 37.594868ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T13:55:08.26342Z","caller":"traceutil/trace.go:171","msg":"trace[1517968135] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:413; }","duration":"170.266837ms","start":"2026-04-14T13:55:08.093124Z","end":"2026-04-14T13:55:08.263391Z","steps":["trace[1517968135] 'read index received' (duration: 11.695977ms)","trace[1517968135] 'applied index is now lower than readState.Index' (duration: 158.56925ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T13:55:08.263532Z","caller":"traceutil/trace.go:171","msg":"trace[1860470703] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"175.376156ms","start":"2026-04-14T13:55:08.088144Z","end":"2026-04-14T13:55:08.26352Z","steps":["trace[1860470703] 'process raft request' (duration: 87.773033ms)","trace[1860470703] 'compare' (duration: 87.307712ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T13:55:08.26371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.542389ms","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-14T13:55:08.263808Z","caller":"traceutil/trace.go:171","msg":"trace[722760702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:398; }","duration":"170.706209ms","start":"2026-04-14T13:55:08.093081Z","end":"2026-04-14T13:55:08.263787Z","steps":["trace[722760702] 'agreement among raft nodes before linearized reading' (duration: 170.537368ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:08.271732Z","caller":"traceutil/trace.go:171","msg":"trace[142835930] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"162.731206ms","start":"2026-04-14T13:55:08.108984Z","end":"2026-04-14T13:55:08.271715Z","steps":["trace[142835930] 'process raft request' (duration: 162.565126ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:08.272017Z","caller":"traceutil/trace.go:171","msg":"trace[866646810] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"160.233802ms","start":"2026-04-14T13:55:08.111733Z","end":"2026-04-14T13:55:08.271967Z","steps":["trace[866646810] 'process raft request' (duration: 159.896162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:08.272713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.917247ms","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-14T13:55:08.272771Z","caller":"traceutil/trace.go:171","msg":"trace[1676421743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:400; }","duration":"110.537598ms","start":"2026-04-14T13:55:08.162219Z","end":"2026-04-14T13:55:08.272756Z","steps":["trace[1676421743] 'agreement among raft nodes before linearized reading' (duration: 109.913287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:08.813529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.838728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T13:55:08.813699Z","caller":"traceutil/trace.go:171","msg":"trace[1050195253] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"486.740637ms","start":"2026-04-14T13:55:08.32689Z","end":"2026-04-14T13:55:08.813631Z","steps":["trace[1050195253] 'process raft request' (duration: 38.002778ms)","trace[1050195253] 'compare' (duration: 447.378607ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T13:55:08.813934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:08.326876Z","time spent":"486.962027ms","remote":"127.0.0.1:33294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T13:55:09.140162Z","caller":"traceutil/trace.go:171","msg":"trace[739749929] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"811.2621ms","start":"2026-04-14T13:55:08.328878Z","end":"2026-04-14T13:55:09.14014Z","steps":["trace[739749929] 'process raft request' (duration: 736.795636ms)","trace[739749929] 'compare' (duration: 74.321073ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T13:55:09.140288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:08.328863Z","time spent":"811.36279ms","remote":"127.0.0.1:33512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T13:55:09.140413Z","caller":"traceutil/trace.go:171","msg":"trace[1835156134] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:428; }","duration":"442.330881ms","start":"2026-04-14T13:55:08.698074Z","end":"2026-04-14T13:55:09.140405Z","steps":["trace[1835156134] 'read index received' (duration: 367.522067ms)","trace[1835156134] 'applied index is now lower than readState.Index' (duration: 74.808074ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T13:55:09.140492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.427121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T13:55:09.140529Z","caller":"traceutil/trace.go:171","msg":"trace[728531744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:413; }","duration":"442.504041ms","start":"2026-04-14T13:55:08.698016Z","end":"2026-04-14T13:55:09.14052Z","steps":["trace[728531744] 'agreement among raft nodes before linearized reading' (duration: 442.424001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:09.140554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:08.697978Z","time spent":"442.569731ms","remote":"127.0.0.1:33294","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-14T13:55:09.140614Z","caller":"traceutil/trace.go:171","msg":"trace[2037400458] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"810.394328ms","start":"2026-04-14T13:55:08.330203Z","end":"2026-04-14T13:55:09.140597Z","steps":["trace[2037400458] 'process raft request' (duration: 809.896787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:09.140676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:08.330196Z","time spent":"810.448168ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T13:55:21.813907Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12798840416827476792,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T13:55:22.028035Z","caller":"traceutil/trace.go:171","msg":"trace[1377478645] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"740.297554ms","start":"2026-04-14T13:55:21.287705Z","end":"2026-04-14T13:55:22.028002Z","steps":["trace[1377478645] 'process raft request' (duration: 730.158649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:22.028216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:21.287696Z","time spent":"740.425205ms","remote":"127.0.0.1:33054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T13:55:22.049544Z","caller":"traceutil/trace.go:171","msg":"trace[468604388] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"724.836032ms","start":"2026-04-14T13:55:21.324682Z","end":"2026-04-14T13:55:22.049518Z","steps":["trace[468604388] 'process raft request' (duration: 724.541272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:22.049714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:21.324659Z","time spent":"724.955822ms","remote":"127.0.0.1:33054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T13:55:22.049802Z","caller":"traceutil/trace.go:171","msg":"trace[864311495] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:529; }","duration":"736.87815ms","start":"2026-04-14T13:55:21.312901Z","end":"2026-04-14T13:55:22.049779Z","steps":["trace[864311495] 'read index received' (duration: 704.926882ms)","trace[864311495] 'applied index is now lower than readState.Index' (duration: 31.950328ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T13:55:22.049854Z","caller":"traceutil/trace.go:171","msg":"trace[118949528] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"539.685932ms","start":"2026-04-14T13:55:21.510153Z","end":"2026-04-14T13:55:22.049839Z","steps":["trace[118949528] 'process raft request' (duration: 539.544282ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:22.049909Z","caller":"traceutil/trace.go:171","msg":"trace[1968472006] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"695.632608ms","start":"2026-04-14T13:55:21.354246Z","end":"2026-04-14T13:55:22.049879Z","steps":["trace[1968472006] 'process raft request' (duration: 695.210528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:22.049933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:21.510129Z","time spent":"539.767172ms","remote":"127.0.0.1:33294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T13:55:22.049957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.03946ms","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-14T13:55:22.049998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:21.354223Z","time spent":"695.719178ms","remote":"127.0.0.1:33054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T13:55:22.05Z","caller":"traceutil/trace.go:171","msg":"trace[931025255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"737.12765ms","start":"2026-04-14T13:55:21.31286Z","end":"2026-04-14T13:55:22.049988Z","steps":["trace[931025255] 'agreement among raft nodes before linearized reading' (duration: 737.02338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:22.050045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:21.312845Z","time spent":"737.19137ms","remote":"127.0.0.1:33294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T13:55:22.073802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.432385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T13:55:22.073868Z","caller":"traceutil/trace.go:171","msg":"trace[463588014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"481.547995ms","start":"2026-04-14T13:55:21.592307Z","end":"2026-04-14T13:55:22.073855Z","steps":["trace[463588014] 'agreement among raft nodes before linearized reading' (duration: 481.435315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:22.073896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:21.592285Z","time spent":"481.606615ms","remote":"127.0.0.1:33014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T13:55:29.202257Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12798840416827477027,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T13:55:29.281846Z","caller":"traceutil/trace.go:171","msg":"trace[2034447401] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"600.885423ms","start":"2026-04-14T13:55:28.68093Z","end":"2026-04-14T13:55:29.281815Z","steps":["trace[2034447401] 'process raft request' (duration: 600.255552ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:29.281747Z","caller":"traceutil/trace.go:171","msg":"trace[486787872] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"580.343102ms","start":"2026-04-14T13:55:28.701381Z","end":"2026-04-14T13:55:29.281724Z","steps":["trace[486787872] 'read index received' (duration: 579.453841ms)","trace[486787872] 'applied index is now lower than readState.Index' (duration: 887.951µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T13:55:29.28322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.806744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-14T13:55:29.283364Z","caller":"traceutil/trace.go:171","msg":"trace[684237295] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:587; }","duration":"581.947714ms","start":"2026-04-14T13:55:28.701339Z","end":"2026-04-14T13:55:29.283287Z","steps":["trace[684237295] 'agreement among raft nodes before linearized reading' (duration: 580.630652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:29.283362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:28.680902Z","time spent":"601.125953ms","remote":"127.0.0.1:33090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T13:55:29.283491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:28.701319Z","time spent":"582.148524ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-14T13:55:29.283731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.658995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-14T13:55:29.283829Z","caller":"traceutil/trace.go:171","msg":"trace[479558406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"189.762825ms","start":"2026-04-14T13:55:29.094023Z","end":"2026-04-14T13:55:29.283786Z","steps":["trace[479558406] 'agreement among raft nodes before linearized reading' (duration: 188.180063ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:29.766219Z","caller":"traceutil/trace.go:171","msg":"trace[723011460] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"385.274419ms","start":"2026-04-14T13:55:29.380917Z","end":"2026-04-14T13:55:29.766192Z","steps":["trace[723011460] 'read index received' (duration: 385.042469ms)","trace[723011460] 'applied index is now lower than readState.Index' (duration: 230.9µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T13:55:29.766331Z","caller":"traceutil/trace.go:171","msg":"trace[2119892217] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"421.736094ms","start":"2026-04-14T13:55:29.344586Z","end":"2026-04-14T13:55:29.766322Z","steps":["trace[2119892217] 'process raft request' (duration: 421.451134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:29.766442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:29.344558Z","time spent":"421.798624ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T13:55:29.766609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.860132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T13:55:29.766664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.70883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-14T13:55:29.766716Z","caller":"traceutil/trace.go:171","msg":"trace[1948939437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"174.018312ms","start":"2026-04-14T13:55:29.592671Z","end":"2026-04-14T13:55:29.766689Z","steps":["trace[1948939437] 'agreement among raft nodes before linearized reading' (duration: 173.757031ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:29.766767Z","caller":"traceutil/trace.go:171","msg":"trace[2003780104] range","detail":"{range_begin:/registry/masterleases/199.19.213.62; range_end:; response_count:1; response_revision:590; }","duration":"385.85705ms","start":"2026-04-14T13:55:29.380879Z","end":"2026-04-14T13:55:29.766736Z","steps":["trace[2003780104] 'agreement among raft nodes before linearized reading' (duration: 385.594109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:29.766846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:29.380858Z","time spent":"385.97033ms","remote":"127.0.0.1:33042","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.62\" "} {"level":"warn","ts":"2026-04-14T13:55:30.730707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.390563ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-04-14T13:55:30.730815Z","caller":"traceutil/trace.go:171","msg":"trace[1363943614] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"960.987174ms","start":"2026-04-14T13:55:29.769795Z","end":"2026-04-14T13:55:30.730782Z","steps":["trace[1363943614] 'read index received' (duration: 113.433751ms)","trace[1363943614] 'applied index is now lower than readState.Index' (duration: 847.551583ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T13:55:30.730889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:29.768385Z","time spent":"962.501876ms","remote":"127.0.0.1:33042","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-14T13:55:30.731095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.300904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-14T13:55:30.731132Z","caller":"traceutil/trace.go:171","msg":"trace[111042841] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:590; }","duration":"961.394844ms","start":"2026-04-14T13:55:29.769728Z","end":"2026-04-14T13:55:30.731122Z","steps":["trace[111042841] 'agreement among raft nodes before linearized reading' (duration: 961.313984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:30.731163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:29.769707Z","time spent":"961.449284ms","remote":"127.0.0.1:33212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-14T13:55:30.731411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.160639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T13:55:30.731448Z","caller":"traceutil/trace.go:171","msg":"trace[744339217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"139.238809ms","start":"2026-04-14T13:55:30.5922Z","end":"2026-04-14T13:55:30.731439Z","steps":["trace[744339217] 'agreement among raft nodes before linearized reading' (duration: 139.184119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:30.731462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.057967ms","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-14T13:55:30.731545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.740275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T13:55:30.731559Z","caller":"traceutil/trace.go:171","msg":"trace[2001472939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"597.189537ms","start":"2026-04-14T13:55:30.134343Z","end":"2026-04-14T13:55:30.731533Z","steps":["trace[2001472939] 'agreement among raft nodes before linearized reading' (duration: 596.932837ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:30.731606Z","caller":"traceutil/trace.go:171","msg":"trace[919623632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"296.834125ms","start":"2026-04-14T13:55:30.434752Z","end":"2026-04-14T13:55:30.731586Z","steps":["trace[919623632] 'agreement among raft nodes before linearized reading' (duration: 296.647985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:30.73162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:30.134322Z","time spent":"597.284997ms","remote":"127.0.0.1:33294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T13:55:30.731551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.986712ms","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-14T13:55:30.731751Z","caller":"traceutil/trace.go:171","msg":"trace[1467171999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"241.351833ms","start":"2026-04-14T13:55:30.490382Z","end":"2026-04-14T13:55:30.731734Z","steps":["trace[1467171999] 'agreement among raft nodes before linearized reading' (duration: 240.907932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:30.732201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.555489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h97mj\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-14T13:55:30.732246Z","caller":"traceutil/trace.go:171","msg":"trace[1418246228] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h97mj; range_end:; response_count:1; response_revision:590; }","duration":"446.305631ms","start":"2026-04-14T13:55:30.285929Z","end":"2026-04-14T13:55:30.732234Z","steps":["trace[1418246228] 'agreement among raft nodes before linearized reading' (duration: 445.50833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:30.732279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:30.285897Z","time spent":"446.371941ms","remote":"127.0.0.1:33196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-h97mj\" "} {"level":"info","ts":"2026-04-14T13:55:30.92697Z","caller":"traceutil/trace.go:171","msg":"trace[139740021] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"193.6739ms","start":"2026-04-14T13:55:30.733273Z","end":"2026-04-14T13:55:30.926947Z","steps":["trace[139740021] 'read index received' (duration: 159.93905ms)","trace[139740021] 'applied index is now lower than readState.Index' (duration: 33.73386ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T13:55:30.927084Z","caller":"traceutil/trace.go:171","msg":"trace[1534570839] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"194.738243ms","start":"2026-04-14T13:55:30.732333Z","end":"2026-04-14T13:55:30.927071Z","steps":["trace[1534570839] 'process raft request' (duration: 160.832232ms)","trace[1534570839] 'compare' (duration: 33.53437ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T13:55:30.927233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.926941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T13:55:30.927377Z","caller":"traceutil/trace.go:171","msg":"trace[743050131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"194.085702ms","start":"2026-04-14T13:55:30.733245Z","end":"2026-04-14T13:55:30.927331Z","steps":["trace[743050131] 'agreement among raft nodes before linearized reading' (duration: 193.829261ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:31.085859Z","caller":"traceutil/trace.go:171","msg":"trace[60368902] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"349.107116ms","start":"2026-04-14T13:55:30.736726Z","end":"2026-04-14T13:55:31.085833Z","steps":["trace[60368902] 'process raft request' (duration: 349.022995ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:31.085898Z","caller":"traceutil/trace.go:171","msg":"trace[861837326] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"349.359516ms","start":"2026-04-14T13:55:30.736497Z","end":"2026-04-14T13:55:31.085856Z","steps":["trace[861837326] 'process raft request' (duration: 303.107246ms)","trace[861837326] 'compare' (duration: 45.938879ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T13:55:31.086037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:30.736528Z","time spent":"349.411536ms","remote":"127.0.0.1:33294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T13:55:31.08604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:30.736482Z","time spent":"349.484226ms","remote":"127.0.0.1:33294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T13:55:31.086141Z","caller":"traceutil/trace.go:171","msg":"trace[1831608808] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:614; }","duration":"159.040089ms","start":"2026-04-14T13:55:30.927034Z","end":"2026-04-14T13:55:31.086074Z","steps":["trace[1831608808] 'read index received' (duration: 112.567299ms)","trace[1831608808] 'applied index is now lower than readState.Index' (duration: 46.47182ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T13:55:31.086253Z","caller":"traceutil/trace.go:171","msg":"trace[701887126] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"339.640821ms","start":"2026-04-14T13:55:30.746594Z","end":"2026-04-14T13:55:31.086234Z","steps":["trace[701887126] 'process raft request' (duration: 339.385451ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:31.086262Z","caller":"traceutil/trace.go:171","msg":"trace[1970491683] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"349.478626ms","start":"2026-04-14T13:55:30.736761Z","end":"2026-04-14T13:55:31.086239Z","steps":["trace[1970491683] 'process raft request' (duration: 349.029925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:31.086292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.859526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-14T13:55:31.086325Z","caller":"traceutil/trace.go:171","msg":"trace[467543616] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:595; }","duration":"350.931558ms","start":"2026-04-14T13:55:30.735384Z","end":"2026-04-14T13:55:31.086316Z","steps":["trace[467543616] 'agreement among raft nodes before linearized reading' (duration: 350.851038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:31.086354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:30.746583Z","time spent":"339.707721ms","remote":"127.0.0.1:33196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T13:55:31.086355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:30.736754Z","time spent":"349.553536ms","remote":"127.0.0.1:33294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T13:55:31.086421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.743566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-14T13:55:31.086473Z","caller":"traceutil/trace.go:171","msg":"trace[775127311] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:595; }","duration":"156.810836ms","start":"2026-04-14T13:55:30.929628Z","end":"2026-04-14T13:55:31.086439Z","steps":["trace[775127311] 'agreement among raft nodes before linearized reading' (duration: 156.762656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:31.08636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:30.735372Z","time spent":"350.980848ms","remote":"127.0.0.1:33212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-14T13:55:32.577072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243885068s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T13:55:32.57731Z","caller":"traceutil/trace.go:171","msg":"trace[1176421889] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"340.986073ms","start":"2026-04-14T13:55:32.236301Z","end":"2026-04-14T13:55:32.577287Z","steps":["trace[1176421889] 'process raft request' (duration: 340.878982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:32.577404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:32.236276Z","time spent":"341.081303ms","remote":"127.0.0.1:33294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T13:55:32.577595Z","caller":"traceutil/trace.go:171","msg":"trace[1045802988] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.482045905s","start":"2026-04-14T13:55:31.095537Z","end":"2026-04-14T13:55:32.577583Z","steps":["trace[1045802988] 'process raft request' (duration: 237.585006ms)","trace[1045802988] 'compare' (duration: 1.243757198s)"],"step_count":2} {"level":"warn","ts":"2026-04-14T13:55:32.577641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:31.095524Z","time spent":"1.482094835s","remote":"127.0.0.1:33196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T13:55:32.57771Z","caller":"traceutil/trace.go:171","msg":"trace[2094666000] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"1.277557328s","start":"2026-04-14T13:55:31.300107Z","end":"2026-04-14T13:55:32.577664Z","steps":["trace[2094666000] 'read index received' (duration: 33.104169ms)","trace[2094666000] 'applied index is now lower than readState.Index' (duration: 1.244450319s)"],"step_count":2} {"level":"warn","ts":"2026-04-14T13:55:32.577824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277712458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-14T13:55:32.577882Z","caller":"traceutil/trace.go:171","msg":"trace[1233647004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"1.277813228s","start":"2026-04-14T13:55:31.300053Z","end":"2026-04-14T13:55:32.577866Z","steps":["trace[1233647004] 'agreement among raft nodes before linearized reading' (duration: 1.277703458s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:32.578612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:31.300033Z","time spent":"1.27855928s","remote":"127.0.0.1:33294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-14T13:55:33.040745Z","caller":"traceutil/trace.go:171","msg":"trace[1698348602] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"457.803927ms","start":"2026-04-14T13:55:32.58292Z","end":"2026-04-14T13:55:33.040724Z","steps":["trace[1698348602] 'process raft request' (duration: 457.661057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:33.040884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:32.582897Z","time spent":"457.918658ms","remote":"127.0.0.1:33078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T13:55:33.18366Z","caller":"traceutil/trace.go:171","msg":"trace[1842132431] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"600.492252ms","start":"2026-04-14T13:55:32.583126Z","end":"2026-04-14T13:55:33.183618Z","steps":["trace[1842132431] 'process raft request' (duration: 599.914121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:33.183902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:32.583116Z","time spent":"600.703542ms","remote":"127.0.0.1:33078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T13:55:33.184264Z","caller":"traceutil/trace.go:171","msg":"trace[1855323194] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"600.855863ms","start":"2026-04-14T13:55:32.583387Z","end":"2026-04-14T13:55:33.184243Z","steps":["trace[1855323194] 'process raft request' (duration: 600.079762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:33.184478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:32.583369Z","time spent":"600.969663ms","remote":"127.0.0.1:33294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T13:55:33.184785Z","caller":"traceutil/trace.go:171","msg":"trace[551163720] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"601.124113ms","start":"2026-04-14T13:55:32.583634Z","end":"2026-04-14T13:55:33.184758Z","steps":["trace[551163720] 'process raft request' (duration: 600.552931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:33.18495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:32.583623Z","time spent":"601.251323ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T13:55:33.185007Z","caller":"traceutil/trace.go:171","msg":"trace[673766425] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:620; }","duration":"594.066632ms","start":"2026-04-14T13:55:32.59092Z","end":"2026-04-14T13:55:33.184986Z","steps":["trace[673766425] 'read index received' (duration: 449.679025ms)","trace[673766425] 'applied index is now lower than readState.Index' (duration: 144.386327ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T13:55:33.185346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.417972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T13:55:33.185383Z","caller":"traceutil/trace.go:171","msg":"trace[1010879498] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"600.831132ms","start":"2026-04-14T13:55:32.584537Z","end":"2026-04-14T13:55:33.185369Z","steps":["trace[1010879498] 'process raft request' (duration: 599.903081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:33.185449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:32.584528Z","time spent":"600.885562ms","remote":"127.0.0.1:33196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T13:55:33.185401Z","caller":"traceutil/trace.go:171","msg":"trace[82548666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"594.496182ms","start":"2026-04-14T13:55:32.590889Z","end":"2026-04-14T13:55:33.185385Z","steps":["trace[82548666] 'agreement among raft nodes before linearized reading' (duration: 594.417172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:33.18571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:32.590877Z","time spent":"594.790663ms","remote":"127.0.0.1:33014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T13:55:33.375242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.256528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T13:55:33.375466Z","caller":"traceutil/trace.go:171","msg":"trace[628069274] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"184.847018ms","start":"2026-04-14T13:55:33.190602Z","end":"2026-04-14T13:55:33.375449Z","steps":["trace[628069274] 'process raft request' (duration: 184.761368ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:33.375477Z","caller":"traceutil/trace.go:171","msg":"trace[697572012] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"185.186488ms","start":"2026-04-14T13:55:33.190259Z","end":"2026-04-14T13:55:33.375445Z","steps":["trace[697572012] 'process raft request' (duration: 79.63439ms)","trace[697572012] 'compare' (duration: 105.108188ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T13:55:33.375613Z","caller":"traceutil/trace.go:171","msg":"trace[841816903] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"184.244426ms","start":"2026-04-14T13:55:33.191348Z","end":"2026-04-14T13:55:33.375592Z","steps":["trace[841816903] 'process raft request' (duration: 184.065256ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:42.443255Z","caller":"traceutil/trace.go:171","msg":"trace[891035037] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"139.65628ms","start":"2026-04-14T13:55:42.303575Z","end":"2026-04-14T13:55:42.443231Z","steps":["trace[891035037] 'process raft request' (duration: 96.847265ms)","trace[891035037] 'compare' (duration: 42.556483ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T13:55:42.65394Z","caller":"traceutil/trace.go:171","msg":"trace[668709539] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"197.118776ms","start":"2026-04-14T13:55:42.456796Z","end":"2026-04-14T13:55:42.653915Z","steps":["trace[668709539] 'process raft request' (duration: 196.367735ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:42.718552Z","caller":"traceutil/trace.go:171","msg":"trace[783923666] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:739; }","duration":"125.164467ms","start":"2026-04-14T13:55:42.593366Z","end":"2026-04-14T13:55:42.718531Z","steps":["trace[783923666] 'read index received' (duration: 59.91411ms)","trace[783923666] 'applied index is now lower than readState.Index' (duration: 65.249247ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T13:55:42.718576Z","caller":"traceutil/trace.go:171","msg":"trace[585152955] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"195.707723ms","start":"2026-04-14T13:55:42.522817Z","end":"2026-04-14T13:55:42.718525Z","steps":["trace[585152955] 'process raft request' (duration: 195.475363ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:42.718686Z","caller":"traceutil/trace.go:171","msg":"trace[1158631327] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"150.428474ms","start":"2026-04-14T13:55:42.568237Z","end":"2026-04-14T13:55:42.718666Z","steps":["trace[1158631327] 'process raft request' (duration: 150.171764ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:42.718699Z","caller":"traceutil/trace.go:171","msg":"trace[548110900] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"110.691905ms","start":"2026-04-14T13:55:42.607987Z","end":"2026-04-14T13:55:42.718679Z","steps":["trace[548110900] 'process raft request' (duration: 110.494535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:42.719068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.690088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T13:55:42.719119Z","caller":"traceutil/trace.go:171","msg":"trace[1068214997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"125.793739ms","start":"2026-04-14T13:55:42.593314Z","end":"2026-04-14T13:55:42.719107Z","steps":["trace[1068214997] 'agreement among raft nodes before linearized reading' (duration: 125.305337ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:42.906889Z","caller":"traceutil/trace.go:171","msg":"trace[1517896064] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"180.711421ms","start":"2026-04-14T13:55:42.726149Z","end":"2026-04-14T13:55:42.906861Z","steps":["trace[1517896064] 'process raft request' (duration: 113.4572ms)","trace[1517896064] 'compare' (duration: 67.128031ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T13:55:42.9197Z","caller":"traceutil/trace.go:171","msg":"trace[1573950756] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"190.196415ms","start":"2026-04-14T13:55:42.729488Z","end":"2026-04-14T13:55:42.919685Z","steps":["trace[1573950756] 'process raft request' (duration: 190.011465ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:43.545678Z","caller":"traceutil/trace.go:171","msg":"trace[381819653] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"124.055316ms","start":"2026-04-14T13:55:43.421599Z","end":"2026-04-14T13:55:43.545654Z","steps":["trace[381819653] 'process raft request' (duration: 123.837406ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:45.539471Z","caller":"traceutil/trace.go:171","msg":"trace[1818041219] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"118.679228ms","start":"2026-04-14T13:55:45.420772Z","end":"2026-04-14T13:55:45.539451Z","steps":["trace[1818041219] 'process raft request' (duration: 118.621648ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:45.539541Z","caller":"traceutil/trace.go:171","msg":"trace[726946399] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"122.513533ms","start":"2026-04-14T13:55:45.417Z","end":"2026-04-14T13:55:45.539513Z","steps":["trace[726946399] 'process raft request' (duration: 122.268053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:50.066758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.271193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-14T13:55:50.066881Z","caller":"traceutil/trace.go:171","msg":"trace[933699236] range","detail":"{range_begin:/registry/masterleases/199.19.213.62; range_end:; response_count:1; response_revision:758; }","duration":"684.549595ms","start":"2026-04-14T13:55:49.382302Z","end":"2026-04-14T13:55:50.066852Z","steps":["trace[933699236] 'range keys from in-memory index tree' (duration: 684.019822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:50.066904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.677307ms","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-14T13:55:50.066935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:49.382282Z","time spent":"684.638335ms","remote":"127.0.0.1:33042","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.62\" "} {"level":"info","ts":"2026-04-14T13:55:50.066973Z","caller":"traceutil/trace.go:171","msg":"trace[1940977984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:758; }","duration":"712.784187ms","start":"2026-04-14T13:55:49.354169Z","end":"2026-04-14T13:55:50.066953Z","steps":["trace[1940977984] 'range keys from in-memory index tree' (duration: 712.327295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:50.067019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:49.354147Z","time spent":"712.859297ms","remote":"127.0.0.1:33294","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":"warn","ts":"2026-04-14T13:55:50.067119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.342151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T13:55:50.067188Z","caller":"traceutil/trace.go:171","msg":"trace[1126657969] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"461.107ms","start":"2026-04-14T13:55:49.606068Z","end":"2026-04-14T13:55:50.067175Z","steps":["trace[1126657969] 'read index received' (duration: 286.694489ms)","trace[1126657969] 'applied index is now lower than readState.Index' (duration: 174.411171ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T13:55:50.067466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.397851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-14T13:55:50.067487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.989433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T13:55:50.06752Z","caller":"traceutil/trace.go:171","msg":"trace[1190027436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:759; }","duration":"456.066303ms","start":"2026-04-14T13:55:49.611444Z","end":"2026-04-14T13:55:50.06751Z","steps":["trace[1190027436] 'agreement among raft nodes before linearized reading' (duration: 455.956493ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:55:50.067518Z","caller":"traceutil/trace.go:171","msg":"trace[1041168473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:759; }","duration":"461.485241ms","start":"2026-04-14T13:55:49.606015Z","end":"2026-04-14T13:55:50.0675Z","steps":["trace[1041168473] 'agreement among raft nodes before linearized reading' (duration: 461.354541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:50.067552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:49.61142Z","time spent":"456.125333ms","remote":"127.0.0.1:33294","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-14T13:55:50.067562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:49.605994Z","time spent":"461.557001ms","remote":"127.0.0.1:33294","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-14T13:55:50.067564Z","caller":"traceutil/trace.go:171","msg":"trace[305492715] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"662.827331ms","start":"2026-04-14T13:55:49.404691Z","end":"2026-04-14T13:55:50.067518Z","steps":["trace[305492715] 'process raft request' (duration: 488.01998ms)","trace[305492715] 'compare' (duration: 174.238611ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T13:55:50.067734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:49.404655Z","time spent":"662.983941ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T13:55:50.067796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.71862ms","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-14T13:55:50.067903Z","caller":"traceutil/trace.go:171","msg":"trace[1861702661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:759; }","duration":"454.86492ms","start":"2026-04-14T13:55:49.612995Z","end":"2026-04-14T13:55:50.06786Z","steps":["trace[1861702661] 'agreement among raft nodes before linearized reading' (duration: 454.60919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:55:50.06823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:55:49.612964Z","time spent":"455.251302ms","remote":"127.0.0.1:33294","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-14T13:56:34.777403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.118196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T13:56:34.777531Z","caller":"traceutil/trace.go:171","msg":"trace[1717139847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1046; }","duration":"185.282216ms","start":"2026-04-14T13:56:34.592229Z","end":"2026-04-14T13:56:34.777511Z","steps":["trace[1717139847] 'range keys from in-memory index tree' (duration: 185.056556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:56:34.777426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.735874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T13:56:34.777682Z","caller":"traceutil/trace.go:171","msg":"trace[255927376] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1046; }","duration":"190.978614ms","start":"2026-04-14T13:56:34.586656Z","end":"2026-04-14T13:56:34.777635Z","steps":["trace[255927376] 'count revisions from in-memory index tree' (duration: 190.672584ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:56:34.779493Z","caller":"traceutil/trace.go:171","msg":"trace[1334582431] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"140.220579ms","start":"2026-04-14T13:56:34.639258Z","end":"2026-04-14T13:56:34.779478Z","steps":["trace[1334582431] 'process raft request' (duration: 139.941849ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:56:40.702288Z","caller":"traceutil/trace.go:171","msg":"trace[1526872849] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"191.413866ms","start":"2026-04-14T13:56:40.510849Z","end":"2026-04-14T13:56:40.702263Z","steps":["trace[1526872849] 'process raft request' (duration: 150.707415ms)","trace[1526872849] 'compare' (duration: 40.556ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T13:56:40.702495Z","caller":"traceutil/trace.go:171","msg":"trace[1181250854] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"191.408496ms","start":"2026-04-14T13:56:40.511007Z","end":"2026-04-14T13:56:40.702416Z","steps":["trace[1181250854] 'process raft request' (duration: 191.216695ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:57:26.215573Z","caller":"traceutil/trace.go:171","msg":"trace[98914265] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"344.271273ms","start":"2026-04-14T13:57:25.871278Z","end":"2026-04-14T13:57:26.215549Z","steps":["trace[98914265] 'process raft request' (duration: 335.87653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:57:26.215692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:57:25.871251Z","time spent":"344.392983ms","remote":"127.0.0.1:33078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":703,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T13:57:34.847048Z","caller":"traceutil/trace.go:171","msg":"trace[648592] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"151.316975ms","start":"2026-04-14T13:57:34.695711Z","end":"2026-04-14T13:57:34.847028Z","steps":["trace[648592] 'read index received' (duration: 151.160045ms)","trace[648592] 'applied index is now lower than readState.Index' (duration: 156.25µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T13:57:34.847243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.512156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T13:57:34.847268Z","caller":"traceutil/trace.go:171","msg":"trace[1466128657] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1272; }","duration":"151.595366ms","start":"2026-04-14T13:57:34.695666Z","end":"2026-04-14T13:57:34.847262Z","steps":["trace[1466128657] 'agreement among raft nodes before linearized reading' (duration: 151.449035ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:57:34.847499Z","caller":"traceutil/trace.go:171","msg":"trace[1510054565] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"156.566354ms","start":"2026-04-14T13:57:34.690926Z","end":"2026-04-14T13:57:34.847492Z","steps":["trace[1510054565] 'process raft request' (duration: 155.987943ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:57:43.274394Z","caller":"traceutil/trace.go:171","msg":"trace[1037811997] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"286.360297ms","start":"2026-04-14T13:57:42.988012Z","end":"2026-04-14T13:57:43.274372Z","steps":["trace[1037811997] 'read index received' (duration: 286.177777ms)","trace[1037811997] 'applied index is now lower than readState.Index' (duration: 181.76µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T13:57:43.274692Z","caller":"traceutil/trace.go:171","msg":"trace[197950712] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"387.694187ms","start":"2026-04-14T13:57:42.886989Z","end":"2026-04-14T13:57:43.274683Z","steps":["trace[197950712] 'process raft request' (duration: 387.240017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:57:43.274875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T13:57:42.886966Z","time spent":"387.800637ms","remote":"127.0.0.1:33294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T13:57:43.275252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.243757ms","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-14T13:57:43.275277Z","caller":"traceutil/trace.go:171","msg":"trace[729835977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1305; }","duration":"287.269567ms","start":"2026-04-14T13:57:42.988001Z","end":"2026-04-14T13:57:43.275271Z","steps":["trace[729835977] 'agreement among raft nodes before linearized reading' (duration: 287.009187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:57:43.289995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.111735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-14T13:57:43.290077Z","caller":"traceutil/trace.go:171","msg":"trace[1477888760] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1306; }","duration":"117.245245ms","start":"2026-04-14T13:57:43.172815Z","end":"2026-04-14T13:57:43.29006Z","steps":["trace[1477888760] 'agreement among raft nodes before linearized reading' (duration: 117.002545ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:57:43.289968Z","caller":"traceutil/trace.go:171","msg":"trace[792508325] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"134.750841ms","start":"2026-04-14T13:57:43.155148Z","end":"2026-04-14T13:57:43.289899Z","steps":["trace[792508325] 'process raft request' (duration: 134.519321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:57:43.290126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.609485ms","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-14T13:57:43.290358Z","caller":"traceutil/trace.go:171","msg":"trace[1509981206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1306; }","duration":"110.913545ms","start":"2026-04-14T13:57:43.17942Z","end":"2026-04-14T13:57:43.290334Z","steps":["trace[1509981206] 'agreement among raft nodes before linearized reading' (duration: 110.479045ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:58:15.52525Z","caller":"traceutil/trace.go:171","msg":"trace[394680753] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"105.315377ms","start":"2026-04-14T13:58:15.419895Z","end":"2026-04-14T13:58:15.52521Z","steps":["trace[394680753] 'process raft request' (duration: 104.525946ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:58:17.873808Z","caller":"traceutil/trace.go:171","msg":"trace[1592271521] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"251.665545ms","start":"2026-04-14T13:58:17.622114Z","end":"2026-04-14T13:58:17.87378Z","steps":["trace[1592271521] 'process raft request' (duration: 250.466283ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:58:17.873946Z","caller":"traceutil/trace.go:171","msg":"trace[1845000791] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"250.674124ms","start":"2026-04-14T13:58:17.62325Z","end":"2026-04-14T13:58:17.873924Z","steps":["trace[1845000791] 'process raft request' (duration: 250.475523ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T13:58:17.873992Z","caller":"traceutil/trace.go:171","msg":"trace[87816005] linearizableReadLoop","detail":"{readStateIndex:1484; appliedIndex:1482; }","duration":"230.535354ms","start":"2026-04-14T13:58:17.643356Z","end":"2026-04-14T13:58:17.873891Z","steps":["trace[87816005] 'read index received' (duration: 229.337792ms)","trace[87816005] 'applied index is now lower than readState.Index' (duration: 1.195492ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T13:58:17.87417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.788914ms","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-14T13:58:17.87425Z","caller":"traceutil/trace.go:171","msg":"trace[605987656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1424; }","duration":"230.912364ms","start":"2026-04-14T13:58:17.643314Z","end":"2026-04-14T13:58:17.874227Z","steps":["trace[605987656] 'agreement among raft nodes before linearized reading' (duration: 230.708704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:58:17.874409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.960259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-fjtrx\" ","response":"range_response_count:1 size:4904"} {"level":"info","ts":"2026-04-14T13:58:17.87459Z","caller":"traceutil/trace.go:171","msg":"trace[1392918027] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-fjtrx; range_end:; response_count:1; response_revision:1424; }","duration":"201.075469ms","start":"2026-04-14T13:58:17.673413Z","end":"2026-04-14T13:58:17.874489Z","steps":["trace[1392918027] 'agreement among raft nodes before linearized reading' (duration: 200.896339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T13:58:17.874595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.704011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T13:58:17.874766Z","caller":"traceutil/trace.go:171","msg":"trace[1825195593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1424; }","duration":"209.937291ms","start":"2026-04-14T13:58:17.664792Z","end":"2026-04-14T13:58:17.87473Z","steps":["trace[1825195593] 'agreement among raft nodes before linearized reading' (duration: 209.444981ms)"],"step_count":1}