{"level":"warn","ts":"2026-03-04T15:23:12.007592Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T15:23:12.007716Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-03-04T15:23:12.007796Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T15:23:12.007811Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-03-04T15:23:12.007833Z","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-03-04T15:23:12.008684Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-03-04T15:23:12.008778Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-03-04T15:23:12.013927Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.786097ms"} {"level":"info","ts":"2026-03-04T15:23:12.038588Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-03-04T15:23:12.038697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-03-04T15:23:12.038735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-03-04T15:23:12.038759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-04T15:23:12.038776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-03-04T15:23:12.038831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-03-04T15:23:12.069202Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-04T15:23:12.083784Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-04T15:23:12.085205Z","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-03-04T15:23:12.087677Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-04T15:23:12.088097Z","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-03-04T15:23:12.088189Z","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-03-04T15:23:12.088205Z","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-03-04T15:23:12.088339Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-04T15:23:12.092225Z","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-03-04T15:23:12.092419Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-03-04T15:23:12.092463Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-03-04T15:23:12.092534Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-04T15:23:12.092595Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-04T15:23:12.093344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-03-04T15:23:12.093476Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-03-04T15:23:13.039212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-03-04T15:23:13.039296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-04T15:23:13.039327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-03-04T15:23:13.039351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-03-04T15:23:13.039364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-03-04T15:23:13.039382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-03-04T15:23:13.039398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-03-04T15:23:13.130513Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-03-04T15:23:13.13057Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T15:23:13.130577Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T15:23:13.130623Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T15:23:13.13077Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-04T15:23:13.13081Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-04T15:23:13.134036Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-03-04T15:23:13.134385Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-04T15:23:13.136377Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T15:23:13.136839Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T15:23:13.137795Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T15:23:19.25482Z","caller":"traceutil/trace.go:171","msg":"trace[1201686017] transaction","detail":"{read_only:false; number_of_response:0; response_revision:275; }","duration":"129.692788ms","start":"2026-03-04T15:23:19.125102Z","end":"2026-03-04T15:23:19.254795Z","steps":["trace[1201686017] 'process raft request' (duration: 129.607486ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:19.303651Z","caller":"traceutil/trace.go:171","msg":"trace[1653635147] transaction","detail":"{read_only:false; number_of_response:0; response_revision:275; }","duration":"178.398691ms","start":"2026-03-04T15:23:19.125209Z","end":"2026-03-04T15:23:19.303607Z","steps":["trace[1653635147] 'process raft request' (duration: 178.253257ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:19.304214Z","caller":"traceutil/trace.go:171","msg":"trace[263639796] transaction","detail":"{read_only:false; number_of_response:0; response_revision:275; }","duration":"178.860384ms","start":"2026-03-04T15:23:19.125337Z","end":"2026-03-04T15:23:19.304198Z","steps":["trace[263639796] 'process raft request' (duration: 178.228026ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:19.304259Z","caller":"traceutil/trace.go:171","msg":"trace[1934240140] transaction","detail":"{read_only:false; number_of_response:0; response_revision:275; }","duration":"178.407181ms","start":"2026-03-04T15:23:19.125818Z","end":"2026-03-04T15:23:19.304226Z","steps":["trace[1934240140] 'process raft request' (duration: 177.769213ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:19.304373Z","caller":"traceutil/trace.go:171","msg":"trace[782987499] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"147.030953ms","start":"2026-03-04T15:23:19.157315Z","end":"2026-03-04T15:23:19.304346Z","steps":["trace[782987499] 'process raft request' (duration: 146.400055ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:19.304334Z","caller":"traceutil/trace.go:171","msg":"trace[84583620] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"163.275579ms","start":"2026-03-04T15:23:19.141046Z","end":"2026-03-04T15:23:19.304322Z","steps":["trace[84583620] 'process raft request' (duration: 162.582349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:22.597341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.614150647s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T15:23:22.59754Z","caller":"traceutil/trace.go:171","msg":"trace[1951935788] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:289; }","duration":"2.00268559s","start":"2026-03-04T15:23:20.594838Z","end":"2026-03-04T15:23:22.597524Z","steps":["trace[1951935788] 'read index received' (duration: 87.239362ms)","trace[1951935788] 'applied index is now lower than readState.Index' (duration: 1.915445288s)"],"step_count":2} {"level":"info","ts":"2026-03-04T15:23:22.597645Z","caller":"traceutil/trace.go:171","msg":"trace[1218063115] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"3.241190898s","start":"2026-03-04T15:23:19.356442Z","end":"2026-03-04T15:23:22.597633Z","steps":["trace[1218063115] 'process raft request' (duration: 3.241023993s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:22.597707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:19.356414Z","time spent":"3.24126534s","remote":"127.0.0.1:52136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T15:23:22.597752Z","caller":"traceutil/trace.go:171","msg":"trace[1444447524] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"3.282768647s","start":"2026-03-04T15:23:19.314923Z","end":"2026-03-04T15:23:22.597691Z","steps":["trace[1444447524] 'process raft request' (duration: 667.608332ms)","trace[1444447524] 'compare' (duration: 2.613998943s)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:23:22.597913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:19.314907Z","time spent":"3.282906731s","remote":"127.0.0.1:52128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:23:22.598033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.626611735s","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-03-04T15:23:22.598116Z","caller":"traceutil/trace.go:171","msg":"trace[691866995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:279; }","duration":"1.626726359s","start":"2026-03-04T15:23:20.971368Z","end":"2026-03-04T15:23:22.598094Z","steps":["trace[691866995] 'agreement among raft nodes before linearized reading' (duration: 1.626578124s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:22.598174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:20.971346Z","time spent":"1.62681497s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-04T15:23:22.598169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.003294116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-04T15:23:22.598272Z","caller":"traceutil/trace.go:171","msg":"trace[389487251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:279; }","duration":"2.003438911s","start":"2026-03-04T15:23:20.594802Z","end":"2026-03-04T15:23:22.598241Z","steps":["trace[389487251] 'agreement among raft nodes before linearized reading' (duration: 2.003234565s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:22.598325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:20.594781Z","time spent":"2.003532563s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T15:23:22.598419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.686902673s","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-03-04T15:23:22.597943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.758635545s","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-03-04T15:23:22.598815Z","caller":"traceutil/trace.go:171","msg":"trace[462679588] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:279; }","duration":"1.759535341s","start":"2026-03-04T15:23:20.839247Z","end":"2026-03-04T15:23:22.598782Z","steps":["trace[462679588] 'agreement among raft nodes before linearized reading' (duration: 1.758535543s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:22.598897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:20.839228Z","time spent":"1.759634393s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-04T15:23:22.599086Z","caller":"traceutil/trace.go:171","msg":"trace[301888603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:279; }","duration":"1.687243853s","start":"2026-03-04T15:23:20.911477Z","end":"2026-03-04T15:23:22.598721Z","steps":["trace[301888603] 'agreement among raft nodes before linearized reading' (duration: 1.686641666s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:22.599943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:20.91146Z","time spent":"1.688461866s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-04T15:23:23.103565Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415871162681862302,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T15:23:23.712357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.503487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T15:23:23.712787Z","caller":"traceutil/trace.go:171","msg":"trace[1962210949] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"1.109285452s","start":"2026-03-04T15:23:22.603487Z","end":"2026-03-04T15:23:23.712773Z","steps":["trace[1962210949] 'process raft request' (duration: 1.109084427s)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:23.712781Z","caller":"traceutil/trace.go:171","msg":"trace[84757566] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"1.110465436s","start":"2026-03-04T15:23:22.602282Z","end":"2026-03-04T15:23:23.712748Z","steps":["trace[84757566] 'process raft request' (duration: 914.497046ms)","trace[84757566] 'compare' (duration: 195.308171ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T15:23:23.712801Z","caller":"traceutil/trace.go:171","msg":"trace[2050546642] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"1.109537119s","start":"2026-03-04T15:23:22.603234Z","end":"2026-03-04T15:23:23.712771Z","steps":["trace[2050546642] 'process raft request' (duration: 1.109306343s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:23.712845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:22.60346Z","time spent":"1.109356925s","remote":"127.0.0.1:52200","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-03-04T15:23:23.712931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:22.602261Z","time spent":"1.110585349s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:23:23.712875Z","caller":"traceutil/trace.go:171","msg":"trace[1638538420] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"1.109618512s","start":"2026-03-04T15:23:22.603219Z","end":"2026-03-04T15:23:23.712838Z","steps":["trace[1638538420] 'process raft request' (duration: 1.109240312s)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:23.712625Z","caller":"traceutil/trace.go:171","msg":"trace[2034992864] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:291; }","duration":"1.109673174s","start":"2026-03-04T15:23:22.602934Z","end":"2026-03-04T15:23:23.712608Z","steps":["trace[2034992864] 'read index received' (duration: 913.810558ms)","trace[2034992864] 'applied index is now lower than readState.Index' (duration: 195.861316ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:23:23.713171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109875359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-03-04T15:23:23.713198Z","caller":"traceutil/trace.go:171","msg":"trace[1974229138] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:283; }","duration":"1.109931281s","start":"2026-03-04T15:23:22.603258Z","end":"2026-03-04T15:23:23.71319Z","steps":["trace[1974229138] 'agreement among raft nodes before linearized reading' (duration: 1.109883299s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:23.713066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110125866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T15:23:23.713222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:22.603242Z","time spent":"1.109975662s","remote":"127.0.0.1:52128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2943,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-03-04T15:23:23.713221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:22.603217Z","time spent":"1.109649553s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:23:23.713207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:22.603208Z","time spent":"1.109850708s","remote":"127.0.0.1:52200","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-03-04T15:23:23.713246Z","caller":"traceutil/trace.go:171","msg":"trace[954553156] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:283; }","duration":"1.110332742s","start":"2026-03-04T15:23:22.602902Z","end":"2026-03-04T15:23:23.713235Z","steps":["trace[954553156] 'agreement among raft nodes before linearized reading' (duration: 1.110096326s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:23.713312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:22.602844Z","time spent":"1.110457765s","remote":"127.0.0.1:52136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" "} {"level":"info","ts":"2026-03-04T15:23:26.015794Z","caller":"traceutil/trace.go:171","msg":"trace[1203216574] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"291.905663ms","start":"2026-03-04T15:23:25.72387Z","end":"2026-03-04T15:23:26.015776Z","steps":["trace[1203216574] 'process raft request' (duration: 291.875382ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:26.016666Z","caller":"traceutil/trace.go:171","msg":"trace[315769704] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"293.213789ms","start":"2026-03-04T15:23:25.723443Z","end":"2026-03-04T15:23:26.016657Z","steps":["trace[315769704] 'process raft request' (duration: 287.014802ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:26.016757Z","caller":"traceutil/trace.go:171","msg":"trace[533578879] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"293.231099ms","start":"2026-03-04T15:23:25.723518Z","end":"2026-03-04T15:23:26.016749Z","steps":["trace[533578879] 'process raft request' (duration: 292.201962ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:26.594927Z","caller":"traceutil/trace.go:171","msg":"trace[1370755192] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"148.575253ms","start":"2026-03-04T15:23:26.446325Z","end":"2026-03-04T15:23:26.5949Z","steps":["trace[1370755192] 'process raft request' (duration: 148.403608ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:32.478163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.994026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T15:23:32.478239Z","caller":"traceutil/trace.go:171","msg":"trace[704249719] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"267.888261ms","start":"2026-03-04T15:23:32.210339Z","end":"2026-03-04T15:23:32.478227Z","steps":["trace[704249719] 'read index received' (duration: 76.769201ms)","trace[704249719] 'applied index is now lower than readState.Index' (duration: 191.11839ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:23:32.47829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.961923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:23:32.478312Z","caller":"traceutil/trace.go:171","msg":"trace[402807597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"268.011874ms","start":"2026-03-04T15:23:32.210285Z","end":"2026-03-04T15:23:32.478297Z","steps":["trace[402807597] 'agreement among raft nodes before linearized reading' (duration: 267.967863ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:32.478327Z","caller":"traceutil/trace.go:171","msg":"trace[1304465984] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"337.748987ms","start":"2026-03-04T15:23:32.14054Z","end":"2026-03-04T15:23:32.478289Z","steps":["trace[1304465984] 'process raft request' (duration: 146.575656ms)","trace[1304465984] 'compare' (duration: 190.905834ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:23:32.478474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:32.140529Z","time spent":"337.875391ms","remote":"127.0.0.1:52394","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-03-04T15:23:45.611155Z","caller":"traceutil/trace.go:171","msg":"trace[856253272] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"402.557075ms","start":"2026-03-04T15:23:45.208518Z","end":"2026-03-04T15:23:45.611075Z","steps":["trace[856253272] 'read index received' (duration: 402.376091ms)","trace[856253272] 'applied index is now lower than readState.Index' (duration: 179.944µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:23:45.611289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.739349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:23:45.611322Z","caller":"traceutil/trace.go:171","msg":"trace[1772823040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:464; }","duration":"402.826811ms","start":"2026-03-04T15:23:45.208484Z","end":"2026-03-04T15:23:45.611311Z","steps":["trace[1772823040] 'agreement among raft nodes before linearized reading' (duration: 402.746909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:45.611359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:45.20847Z","time spent":"402.881633ms","remote":"127.0.0.1:51952","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-03-04T15:23:45.611493Z","caller":"traceutil/trace.go:171","msg":"trace[721524179] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"409.021112ms","start":"2026-03-04T15:23:45.20244Z","end":"2026-03-04T15:23:45.611462Z","steps":["trace[721524179] 'process raft request' (duration: 408.48125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:45.611648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:45.20242Z","time spent":"409.149326ms","remote":"127.0.0.1:52200","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-03-04T15:23:46.898596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.28692ms","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-03-04T15:23:46.89871Z","caller":"traceutil/trace.go:171","msg":"trace[677447749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:464; }","duration":"756.475004ms","start":"2026-03-04T15:23:46.142205Z","end":"2026-03-04T15:23:46.89868Z","steps":["trace[677447749] 'range keys from in-memory index tree' (duration: 756.096746ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:46.898706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.324055ms","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-03-04T15:23:46.89875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.089029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T15:23:46.898763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:46.142183Z","time spent":"756.566437ms","remote":"127.0.0.1:52200","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-03-04T15:23:46.898775Z","caller":"traceutil/trace.go:171","msg":"trace[1159423671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:464; }","duration":"750.449458ms","start":"2026-03-04T15:23:46.148308Z","end":"2026-03-04T15:23:46.898757Z","steps":["trace[1159423671] 'range keys from in-memory index tree' (duration: 750.11073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:46.898762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.318953ms","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-03-04T15:23:46.898804Z","caller":"traceutil/trace.go:171","msg":"trace[295752432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:464; }","duration":"686.181402ms","start":"2026-03-04T15:23:46.212607Z","end":"2026-03-04T15:23:46.898789Z","steps":["trace[295752432] 'range keys from in-memory index tree' (duration: 685.989437ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:46.898817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:46.148294Z","time spent":"750.513039ms","remote":"127.0.0.1:52200","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-03-04T15:23:46.898834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:46.212586Z","time spent":"686.242413ms","remote":"127.0.0.1:51950","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-03-04T15:23:46.898837Z","caller":"traceutil/trace.go:171","msg":"trace[426559580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:464; }","duration":"945.441656ms","start":"2026-03-04T15:23:45.953375Z","end":"2026-03-04T15:23:46.898816Z","steps":["trace[426559580] 'range keys from in-memory index tree' (duration: 945.064938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:46.898863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.300672ms","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-03-04T15:23:46.89884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.458795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-04T15:23:46.898896Z","caller":"traceutil/trace.go:171","msg":"trace[1806912382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:464; }","duration":"749.374214ms","start":"2026-03-04T15:23:46.14951Z","end":"2026-03-04T15:23:46.898884Z","steps":["trace[1806912382] 'range keys from in-memory index tree' (duration: 749.124328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:46.89889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:45.95335Z","time spent":"945.526888ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-04T15:23:46.898898Z","caller":"traceutil/trace.go:171","msg":"trace[892628368] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:464; }","duration":"715.585178ms","start":"2026-03-04T15:23:46.183302Z","end":"2026-03-04T15:23:46.898887Z","steps":["trace[892628368] 'range keys from in-memory index tree' (duration: 715.336343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:46.89892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:46.149485Z","time spent":"749.429545ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T15:23:46.898925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:46.183273Z","time spent":"715.64763ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-03-04T15:23:46.89895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.516691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-03-04T15:23:46.899012Z","caller":"traceutil/trace.go:171","msg":"trace[1072432677] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:464; }","duration":"683.616033ms","start":"2026-03-04T15:23:46.215376Z","end":"2026-03-04T15:23:46.898992Z","steps":["trace[1072432677] 'range keys from in-memory index tree' (duration: 683.376718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:46.899052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:46.21536Z","time spent":"683.680724ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4398,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-04T15:23:49.113557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.86636647s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T15:23:49.113795Z","caller":"traceutil/trace.go:171","msg":"trace[2107230432] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"2.211340375s","start":"2026-03-04T15:23:46.902435Z","end":"2026-03-04T15:23:49.113775Z","steps":["trace[2107230432] 'process raft request' (duration: 344.677379ms)","trace[2107230432] 'compare' (duration: 1.866210836s)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:23:49.113881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:46.902417Z","time spent":"2.211430766s","remote":"127.0.0.1:52200","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-03-04T15:23:49.114061Z","caller":"traceutil/trace.go:171","msg":"trace[1410722015] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"2.211576509s","start":"2026-03-04T15:23:46.902462Z","end":"2026-03-04T15:23:49.114039Z","steps":["trace[1410722015] 'process raft request' (duration: 2.211188301s)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:49.114196Z","caller":"traceutil/trace.go:171","msg":"trace[2103657438] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"2.211233242s","start":"2026-03-04T15:23:46.902951Z","end":"2026-03-04T15:23:49.114184Z","steps":["trace[2103657438] 'process raft request' (duration: 2.210952205s)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:49.114237Z","caller":"traceutil/trace.go:171","msg":"trace[1084739162] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"2.211181931s","start":"2026-03-04T15:23:46.903046Z","end":"2026-03-04T15:23:49.114228Z","steps":["trace[1084739162] 'process raft request' (duration: 2.210917005s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:49.114264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:46.902944Z","time spent":"2.211287503s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:23:49.114245Z","caller":"traceutil/trace.go:171","msg":"trace[575892891] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"2.20542335s","start":"2026-03-04T15:23:46.908793Z","end":"2026-03-04T15:23:49.114216Z","steps":["trace[575892891] 'process raft request' (duration: 2.205207486s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:49.114289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:46.903041Z","time spent":"2.211220511s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:23:49.114197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:46.902446Z","time spent":"2.211678732s","remote":"127.0.0.1:52200","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-03-04T15:23:49.114415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:46.908784Z","time spent":"2.205538963s","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:23:49.11454Z","caller":"traceutil/trace.go:171","msg":"trace[1738829630] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"389.284337ms","start":"2026-03-04T15:23:48.725245Z","end":"2026-03-04T15:23:49.114529Z","steps":["trace[1738829630] 'process raft request' (duration: 389.238886ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:49.114579Z","caller":"traceutil/trace.go:171","msg":"trace[947883478] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"1.965080189s","start":"2026-03-04T15:23:47.149482Z","end":"2026-03-04T15:23:49.114562Z","steps":["trace[947883478] 'process raft request' (duration: 1.964648799s)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:49.114621Z","caller":"traceutil/trace.go:171","msg":"trace[1536206437] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:484; }","duration":"904.244559ms","start":"2026-03-04T15:23:48.21037Z","end":"2026-03-04T15:23:49.114614Z","steps":["trace[1536206437] 'read index received' (duration: 31.531µs)","trace[1536206437] 'applied index is now lower than readState.Index' (duration: 904.212358ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:23:49.114658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.29161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:23:49.114687Z","caller":"traceutil/trace.go:171","msg":"trace[1676670220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"904.355452ms","start":"2026-03-04T15:23:48.210325Z","end":"2026-03-04T15:23:49.11468Z","steps":["trace[1676670220] 'agreement among raft nodes before linearized reading' (duration: 904.313271ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:49.11468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:47.149457Z","time spent":"1.96516838s","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:23:49.11471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:48.210306Z","time spent":"904.400092ms","remote":"127.0.0.1:51950","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-03-04T15:23:49.114598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:48.725219Z","time spent":"389.352039ms","remote":"127.0.0.1:52200","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-03-04T15:23:52.152588Z","caller":"traceutil/trace.go:171","msg":"trace[1728126425] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"121.181424ms","start":"2026-03-04T15:23:52.031382Z","end":"2026-03-04T15:23:52.152563Z","steps":["trace[1728126425] 'process raft request' (duration: 120.98774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:55.638388Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415871162681862765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T15:23:56.138565Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415871162681862765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T15:23:56.338968Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.200706313s","expected-duration":"1s"} {"level":"info","ts":"2026-03-04T15:23:56.339401Z","caller":"traceutil/trace.go:171","msg":"trace[320091355] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.201188483s","start":"2026-03-04T15:23:55.138162Z","end":"2026-03-04T15:23:56.33935Z","steps":["trace[320091355] 'process raft request' (duration: 1.201014969s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:56.339544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:55.138144Z","time spent":"1.201323946s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T15:23:56.343782Z","caller":"traceutil/trace.go:171","msg":"trace[1080239502] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:525; }","duration":"1.205419142s","start":"2026-03-04T15:23:55.138341Z","end":"2026-03-04T15:23:56.34376Z","steps":["trace[1080239502] 'read index received' (duration: 1.200884606s)","trace[1080239502] 'applied index is now lower than readState.Index' (duration: 4.534045ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T15:23:56.343871Z","caller":"traceutil/trace.go:171","msg":"trace[853067629] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"717.17448ms","start":"2026-03-04T15:23:55.626677Z","end":"2026-03-04T15:23:56.343852Z","steps":["trace[853067629] 'process raft request' (duration: 716.984186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:56.343976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205605935s","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-03-04T15:23:56.344008Z","caller":"traceutil/trace.go:171","msg":"trace[1794596442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:506; }","duration":"1.205673228s","start":"2026-03-04T15:23:55.138325Z","end":"2026-03-04T15:23:56.343999Z","steps":["trace[1794596442] 'agreement among raft nodes before linearized reading' (duration: 1.205543355s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:56.344069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:55.138318Z","time spent":"1.205711118s","remote":"127.0.0.1:52200","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-03-04T15:23:56.344011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:55.626619Z","time spent":"717.308283ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:23:56.344101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133771494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T15:23:56.344104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116816975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v4lj4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-04T15:23:56.344156Z","caller":"traceutil/trace.go:171","msg":"trace[256427647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"1.133916318s","start":"2026-03-04T15:23:55.210224Z","end":"2026-03-04T15:23:56.34414Z","steps":["trace[256427647] 'agreement among raft nodes before linearized reading' (duration: 1.133787505s)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:56.344165Z","caller":"traceutil/trace.go:171","msg":"trace[1192761151] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v4lj4; range_end:; response_count:1; response_revision:506; }","duration":"1.116928748s","start":"2026-03-04T15:23:55.22722Z","end":"2026-03-04T15:23:56.344149Z","steps":["trace[1192761151] 'agreement among raft nodes before linearized reading' (duration: 1.116750384s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:56.344191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:55.227193Z","time spent":"1.116992818s","remote":"127.0.0.1:52128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-v4lj4\" "} {"level":"warn","ts":"2026-03-04T15:23:56.344192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:55.210202Z","time spent":"1.133981868s","remote":"127.0.0.1:51950","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-03-04T15:23:56.344239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.920023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-04T15:23:56.344303Z","caller":"traceutil/trace.go:171","msg":"trace[1042405729] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:506; }","duration":"160.033726ms","start":"2026-03-04T15:23:56.18425Z","end":"2026-03-04T15:23:56.344284Z","steps":["trace[1042405729] 'agreement among raft nodes before linearized reading' (duration: 159.75773ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:56.344299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204221657s","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-03-04T15:23:56.344363Z","caller":"traceutil/trace.go:171","msg":"trace[1864236455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:506; }","duration":"1.204296058s","start":"2026-03-04T15:23:55.140048Z","end":"2026-03-04T15:23:56.344344Z","steps":["trace[1864236455] 'agreement among raft nodes before linearized reading' (duration: 1.204126424s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:56.344404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:55.140042Z","time spent":"1.20435004s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T15:23:56.34468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.20483808s","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-03-04T15:23:56.344741Z","caller":"traceutil/trace.go:171","msg":"trace[2081925080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:506; }","duration":"1.204912451s","start":"2026-03-04T15:23:55.139812Z","end":"2026-03-04T15:23:56.344725Z","steps":["trace[2081925080] 'agreement among raft nodes before linearized reading' (duration: 1.204134934s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:56.344778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:55.139805Z","time spent":"1.204965413s","remote":"127.0.0.1:52200","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":"warn","ts":"2026-03-04T15:23:57.814013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380523423s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T15:23:57.814215Z","caller":"traceutil/trace.go:171","msg":"trace[66260857] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:530; }","duration":"1.462547798s","start":"2026-03-04T15:23:56.351652Z","end":"2026-03-04T15:23:57.8142Z","steps":["trace[66260857] 'read index received' (duration: 19.172253ms)","trace[66260857] 'applied index is now lower than readState.Index' (duration: 1.443374495s)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:23:57.814275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46262913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:23:57.814289Z","caller":"traceutil/trace.go:171","msg":"trace[164688972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"1.462678401s","start":"2026-03-04T15:23:56.351605Z","end":"2026-03-04T15:23:57.814284Z","steps":["trace[164688972] 'agreement among raft nodes before linearized reading' (duration: 1.46263071s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:57.814308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:56.351589Z","time spent":"1.462714562s","remote":"127.0.0.1:51950","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-03-04T15:23:57.814448Z","caller":"traceutil/trace.go:171","msg":"trace[2032746829] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.457200236s","start":"2026-03-04T15:23:56.357205Z","end":"2026-03-04T15:23:57.814405Z","steps":["trace[2032746829] 'process raft request' (duration: 1.45689899s)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:57.814476Z","caller":"traceutil/trace.go:171","msg":"trace[1141920007] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.463075109s","start":"2026-03-04T15:23:56.351386Z","end":"2026-03-04T15:23:57.814461Z","steps":["trace[1141920007] 'process raft request' (duration: 82.011105ms)","trace[1141920007] 'compare' (duration: 1.380306299s)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:23:57.814539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:56.351371Z","time spent":"1.46313826s","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:23:57.814584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:56.357182Z","time spent":"1.457327798s","remote":"127.0.0.1:52128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T15:23:59.375039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000350247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-04T15:23:59.375095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001576434s","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-03-04T15:23:59.375124Z","caller":"traceutil/trace.go:171","msg":"trace[333988642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"1.000468891s","start":"2026-03-04T15:23:58.374637Z","end":"2026-03-04T15:23:59.375106Z","steps":["trace[333988642] 'range keys from in-memory index tree' (duration: 1.000169364s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:59.37518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:58.374631Z","time spent":"1.000539252s","remote":"127.0.0.1:52200","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-03-04T15:23:59.375181Z","caller":"traceutil/trace.go:171","msg":"trace[2025358216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:511; }","duration":"1.001686656s","start":"2026-03-04T15:23:58.37347Z","end":"2026-03-04T15:23:59.375156Z","steps":["trace[2025358216] 'range keys from in-memory index tree' (duration: 1.00141649s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:59.375245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:58.373462Z","time spent":"1.001767487s","remote":"127.0.0.1:52200","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-03-04T15:23:59.375242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000957461s","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-03-04T15:23:59.375242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032409752s","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-03-04T15:23:59.375301Z","caller":"traceutil/trace.go:171","msg":"trace[1999102872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"1.001039432s","start":"2026-03-04T15:23:58.374247Z","end":"2026-03-04T15:23:59.375286Z","steps":["trace[1999102872] 'range keys from in-memory index tree' (duration: 1.000850438s)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:23:59.37531Z","caller":"traceutil/trace.go:171","msg":"trace[786758781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:511; }","duration":"1.032512854s","start":"2026-03-04T15:23:58.342781Z","end":"2026-03-04T15:23:59.375294Z","steps":["trace[786758781] 'range keys from in-memory index tree' (duration: 1.03227729s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:59.375332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:58.374238Z","time spent":"1.001087043s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T15:23:59.375348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:23:58.342763Z","time spent":"1.032575265s","remote":"127.0.0.1:52200","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-03-04T15:23:59.375429Z","caller":"traceutil/trace.go:171","msg":"trace[1640007195] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"166.014098ms","start":"2026-03-04T15:23:59.209402Z","end":"2026-03-04T15:23:59.375416Z","steps":["trace[1640007195] 'read index received' (duration: 165.785273ms)","trace[1640007195] 'applied index is now lower than readState.Index' (duration: 227.915µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T15:23:59.375444Z","caller":"traceutil/trace.go:171","msg":"trace[1033361524] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"232.000807ms","start":"2026-03-04T15:23:59.143431Z","end":"2026-03-04T15:23:59.375432Z","steps":["trace[1033361524] 'process raft request' (duration: 231.768212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:23:59.375478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.08264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:23:59.375498Z","caller":"traceutil/trace.go:171","msg":"trace[368105642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"166.148481ms","start":"2026-03-04T15:23:59.209344Z","end":"2026-03-04T15:23:59.375493Z","steps":["trace[368105642] 'agreement among raft nodes before linearized reading' (duration: 166.112811ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T15:24:01.47065Z","caller":"traceutil/trace.go:171","msg":"trace[1531376365] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"113.874712ms","start":"2026-03-04T15:24:01.356745Z","end":"2026-03-04T15:24:01.470619Z","steps":["trace[1531376365] 'process raft request' (duration: 113.673828ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:25:20.362369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.863842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T15:25:20.362461Z","caller":"traceutil/trace.go:171","msg":"trace[1921744822] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"380.97171ms","start":"2026-03-04T15:25:19.981476Z","end":"2026-03-04T15:25:20.362448Z","steps":["trace[1921744822] 'read index received' (duration: 140.852455ms)","trace[1921744822] 'applied index is now lower than readState.Index' (duration: 240.118265ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T15:25:20.362535Z","caller":"traceutil/trace.go:171","msg":"trace[1108244921] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"382.37258ms","start":"2026-03-04T15:25:19.980155Z","end":"2026-03-04T15:25:20.362527Z","steps":["trace[1108244921] 'process raft request' (duration: 142.275176ms)","trace[1108244921] 'compare' (duration: 239.706079ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T15:25:20.362578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:25:19.980123Z","time spent":"382.432861ms","remote":"127.0.0.1:52200","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-03-04T15:25:20.362772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.788533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:25:20.362806Z","caller":"traceutil/trace.go:171","msg":"trace[1884142577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"150.855234ms","start":"2026-03-04T15:25:20.211939Z","end":"2026-03-04T15:25:20.362794Z","steps":["trace[1884142577] 'agreement among raft nodes before linearized reading' (duration: 150.801223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:25:20.362904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.461627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-04T15:25:20.362884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.60825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T15:25:20.362939Z","caller":"traceutil/trace.go:171","msg":"trace[2015500841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:916; }","duration":"381.522218ms","start":"2026-03-04T15:25:19.981406Z","end":"2026-03-04T15:25:20.362928Z","steps":["trace[2015500841] 'agreement among raft nodes before linearized reading' (duration: 381.389646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:25:20.362958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:25:19.981396Z","time spent":"381.557618ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-04T15:25:20.362965Z","caller":"traceutil/trace.go:171","msg":"trace[56520297] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:916; }","duration":"277.753932ms","start":"2026-03-04T15:25:20.085193Z","end":"2026-03-04T15:25:20.362947Z","steps":["trace[56520297] 'agreement among raft nodes before linearized reading' (duration: 277.61398ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:25:20.363044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.345867ms","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-03-04T15:25:20.363212Z","caller":"traceutil/trace.go:171","msg":"trace[567455195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:916; }","duration":"379.543929ms","start":"2026-03-04T15:25:19.983646Z","end":"2026-03-04T15:25:20.36319Z","steps":["trace[567455195] 'agreement among raft nodes before linearized reading' (duration: 379.011082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T15:25:20.363258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T15:25:19.983639Z","time spent":"379.60578ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}