{"level":"warn","ts":"2026-03-03T10:52:17.56533Z","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-03T10:52:17.565469Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.150: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.150:2380","--initial-cluster=instance=https://199.19.213.150:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.150:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.150: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-03T10:52:17.565611Z","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-03T10:52:17.565637Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.150:2380"]} {"level":"info","ts":"2026-03-03T10:52:17.565675Z","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-03T10:52:17.566932Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"]} {"level":"info","ts":"2026-03-03T10:52:17.567072Z","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.150:2380"],"listen-peer-urls":["https://199.19.213.150:2380"],"advertise-client-urls":["https://199.19.213.150:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.150: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-03T10:52:17.572556Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.124969ms"} {"level":"info","ts":"2026-03-03T10:52:17.583259Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dbd0ffa4f8c99812","cluster-id":"98aad832cd49bc30"} {"level":"info","ts":"2026-03-03T10:52:17.583358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=()"} {"level":"info","ts":"2026-03-03T10:52:17.583391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became follower at term 0"} {"level":"info","ts":"2026-03-03T10:52:17.583402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dbd0ffa4f8c99812 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-03T10:52:17.583411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became follower at term 1"} {"level":"info","ts":"2026-03-03T10:52:17.583452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=(15839440973475715090)"} {"level":"warn","ts":"2026-03-03T10:52:17.601763Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-03T10:52:17.603662Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-03T10:52:17.606742Z","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-03T10:52:17.610154Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dbd0ffa4f8c99812","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-03T10:52:17.61043Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dbd0ffa4f8c99812","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-03T10:52:17.610721Z","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-03T10:52:17.610818Z","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-03T10:52:17.610828Z","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-03T10:52:17.612698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=(15839440973475715090)"} {"level":"info","ts":"2026-03-03T10:52:17.613287Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98aad832cd49bc30","local-member-id":"dbd0ffa4f8c99812","added-peer-id":"dbd0ffa4f8c99812","added-peer-peer-urls":["https://199.19.213.150:2380"]} {"level":"info","ts":"2026-03-03T10:52:17.616481Z","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-03T10:52:17.616779Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dbd0ffa4f8c99812","initial-advertise-peer-urls":["https://199.19.213.150:2380"],"listen-peer-urls":["https://199.19.213.150:2380"],"advertise-client-urls":["https://199.19.213.150:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-03T10:52:17.61683Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-03T10:52:17.617224Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.150:2380"} {"level":"info","ts":"2026-03-03T10:52:17.617264Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.150:2380"} {"level":"info","ts":"2026-03-03T10:52:17.987501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 is starting a new election at term 1"} {"level":"info","ts":"2026-03-03T10:52:17.987564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-03T10:52:17.987588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 received MsgPreVoteResp from dbd0ffa4f8c99812 at term 1"} {"level":"info","ts":"2026-03-03T10:52:17.987602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became candidate at term 2"} {"level":"info","ts":"2026-03-03T10:52:17.987609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 received MsgVoteResp from dbd0ffa4f8c99812 at term 2"} {"level":"info","ts":"2026-03-03T10:52:17.987618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became leader at term 2"} {"level":"info","ts":"2026-03-03T10:52:17.987626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dbd0ffa4f8c99812 elected leader dbd0ffa4f8c99812 at term 2"} {"level":"info","ts":"2026-03-03T10:52:17.996499Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T10:52:18.000455Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dbd0ffa4f8c99812","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.150:2379]}","request-path":"/0/members/dbd0ffa4f8c99812/attributes","cluster-id":"98aad832cd49bc30","publish-timeout":"7s"} {"level":"info","ts":"2026-03-03T10:52:18.000502Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T10:52:18.000462Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T10:52:18.001101Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-03T10:52:18.001261Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-03T10:52:18.002903Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98aad832cd49bc30","local-member-id":"dbd0ffa4f8c99812","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T10:52:18.003095Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T10:52:18.003141Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T10:52:18.00573Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-03T10:52:18.006274Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.150:2379"} {"level":"warn","ts":"2026-03-03T10:52:38.301719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957993137364349622,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T10:52:38.56031Z","caller":"traceutil/trace.go:171","msg":"trace[137016160] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"759.082076ms","start":"2026-03-03T10:52:37.801186Z","end":"2026-03-03T10:52:38.560268Z","steps":["trace[137016160] 'read index received' (duration: 758.666122ms)","trace[137016160] 'applied index is now lower than readState.Index' (duration: 414.194µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:52:38.560479Z","caller":"traceutil/trace.go:171","msg":"trace[1107614973] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"900.638693ms","start":"2026-03-03T10:52:37.659746Z","end":"2026-03-03T10:52:38.560384Z","steps":["trace[1107614973] 'process raft request' (duration: 900.218298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:38.5608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.962238ms","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-03T10:52:38.560931Z","caller":"traceutil/trace.go:171","msg":"trace[216656531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:426; }","duration":"529.052629ms","start":"2026-03-03T10:52:38.031823Z","end":"2026-03-03T10:52:38.560876Z","steps":["trace[216656531] 'agreement among raft nodes before linearized reading' (duration: 528.887427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:38.561028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:38.031752Z","time spent":"529.259332ms","remote":"127.0.0.1:50346","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":"warn","ts":"2026-03-03T10:52:38.560881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.468724ms","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-03T10:52:38.561291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.378399ms","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-03T10:52:38.561289Z","caller":"traceutil/trace.go:171","msg":"trace[1606654527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:426; }","duration":"110.946699ms","start":"2026-03-03T10:52:38.450311Z","end":"2026-03-03T10:52:38.561257Z","steps":["trace[1606654527] 'agreement among raft nodes before linearized reading' (duration: 110.436684ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:52:38.561343Z","caller":"traceutil/trace.go:171","msg":"trace[193331294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:426; }","duration":"760.194018ms","start":"2026-03-03T10:52:37.801133Z","end":"2026-03-03T10:52:38.561327Z","steps":["trace[193331294] 'agreement among raft nodes before linearized reading' (duration: 759.367199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:38.561379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:37.801108Z","time spent":"760.26067ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T10:52:38.561852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:37.659725Z","time spent":"901.110828ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:52:38.734189Z","caller":"traceutil/trace.go:171","msg":"trace[144262683] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"164.157334ms","start":"2026-03-03T10:52:38.570008Z","end":"2026-03-03T10:52:38.734165Z","steps":["trace[144262683] 'process raft request' (duration: 121.68656ms)","trace[144262683] 'compare' (duration: 42.227851ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:52:38.734555Z","caller":"traceutil/trace.go:171","msg":"trace[1536551783] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"164.320806ms","start":"2026-03-03T10:52:38.570226Z","end":"2026-03-03T10:52:38.734547Z","steps":["trace[1536551783] 'process raft request' (duration: 163.906272ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:52:38.734759Z","caller":"traceutil/trace.go:171","msg":"trace[1046117796] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"162.326013ms","start":"2026-03-03T10:52:38.572401Z","end":"2026-03-03T10:52:38.734727Z","steps":["trace[1046117796] 'process raft request' (duration: 162.087771ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:52:40.84905Z","caller":"traceutil/trace.go:171","msg":"trace[858674097] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"105.847877ms","start":"2026-03-03T10:52:40.743169Z","end":"2026-03-03T10:52:40.849017Z","steps":["trace[858674097] 'process raft request' (duration: 105.677235ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:52:40.866799Z","caller":"traceutil/trace.go:171","msg":"trace[1296979987] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"122.660661ms","start":"2026-03-03T10:52:40.744112Z","end":"2026-03-03T10:52:40.866773Z","steps":["trace[1296979987] 'process raft request' (duration: 122.61993ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:52:40.866902Z","caller":"traceutil/trace.go:171","msg":"trace[1384772955] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"122.807241ms","start":"2026-03-03T10:52:40.744047Z","end":"2026-03-03T10:52:40.866854Z","steps":["trace[1384772955] 'process raft request' (duration: 122.557898ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:52:44.982531Z","caller":"traceutil/trace.go:171","msg":"trace[1340836427] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:454; }","duration":"345.120454ms","start":"2026-03-03T10:52:44.637389Z","end":"2026-03-03T10:52:44.982509Z","steps":["trace[1340836427] 'read index received' (duration: 345.002892ms)","trace[1340836427] 'applied index is now lower than readState.Index' (duration: 116.722µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:52:44.982635Z","caller":"traceutil/trace.go:171","msg":"trace[1382324962] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"385.46033ms","start":"2026-03-03T10:52:44.597169Z","end":"2026-03-03T10:52:44.982629Z","steps":["trace[1382324962] 'process raft request' (duration: 385.204886ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:44.982718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:44.597156Z","time spent":"385.497021ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:52:44.982792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.567483ms","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-03T10:52:44.982873Z","caller":"traceutil/trace.go:171","msg":"trace[889038729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:441; }","duration":"118.692195ms","start":"2026-03-03T10:52:44.86416Z","end":"2026-03-03T10:52:44.982852Z","steps":["trace[889038729] 'agreement among raft nodes before linearized reading' (duration: 118.567243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:44.983026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.592627ms","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-03T10:52:44.983054Z","caller":"traceutil/trace.go:171","msg":"trace[1544061391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:441; }","duration":"103.640858ms","start":"2026-03-03T10:52:44.879405Z","end":"2026-03-03T10:52:44.983046Z","steps":["trace[1544061391] 'agreement among raft nodes before linearized reading' (duration: 103.593458ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:44.983086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.007887ms","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-03-03T10:52:44.983118Z","caller":"traceutil/trace.go:171","msg":"trace[1016224152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:441; }","duration":"105.069537ms","start":"2026-03-03T10:52:44.87804Z","end":"2026-03-03T10:52:44.983109Z","steps":["trace[1016224152] 'agreement among raft nodes before linearized reading' (duration: 105.015576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:44.983149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.760893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T10:52:44.983177Z","caller":"traceutil/trace.go:171","msg":"trace[1715466888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:441; }","duration":"345.804924ms","start":"2026-03-03T10:52:44.637355Z","end":"2026-03-03T10:52:44.98316Z","steps":["trace[1715466888] 'agreement among raft nodes before linearized reading' (duration: 345.776194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:44.983191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:44.637343Z","time spent":"345.844734ms","remote":"127.0.0.1:50048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T10:52:45.469192Z","caller":"traceutil/trace.go:171","msg":"trace[260688938] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"481.322181ms","start":"2026-03-03T10:52:44.987832Z","end":"2026-03-03T10:52:45.469154Z","steps":["trace[260688938] 'process raft request' (duration: 481.164189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:45.469413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:44.987823Z","time spent":"481.458023ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:52:45.47101Z","caller":"traceutil/trace.go:171","msg":"trace[311351586] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"482.6411ms","start":"2026-03-03T10:52:44.988338Z","end":"2026-03-03T10:52:45.470979Z","steps":["trace[311351586] 'process raft request' (duration: 482.583329ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:52:45.471084Z","caller":"traceutil/trace.go:171","msg":"trace[648284765] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"483.127077ms","start":"2026-03-03T10:52:44.987907Z","end":"2026-03-03T10:52:45.471034Z","steps":["trace[648284765] 'process raft request' (duration: 482.843003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:45.471195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:44.988327Z","time spent":"482.764652ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:52:45.471485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:44.98788Z","time spent":"483.38601ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:52:46.825292Z","caller":"traceutil/trace.go:171","msg":"trace[1142302247] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:458; }","duration":"184.171977ms","start":"2026-03-03T10:52:46.641081Z","end":"2026-03-03T10:52:46.825253Z","steps":["trace[1142302247] 'read index received' (duration: 94.127963ms)","trace[1142302247] 'applied index is now lower than readState.Index' (duration: 90.042174ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:52:46.825412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.311709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T10:52:46.825451Z","caller":"traceutil/trace.go:171","msg":"trace[1390022770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:444; }","duration":"184.4042ms","start":"2026-03-03T10:52:46.641038Z","end":"2026-03-03T10:52:46.825442Z","steps":["trace[1390022770] 'agreement among raft nodes before linearized reading' (duration: 184.306669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:51.142258Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957993137364349756,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T10:52:51.275674Z","caller":"traceutil/trace.go:171","msg":"trace[87124892] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:501; }","duration":"633.961536ms","start":"2026-03-03T10:52:50.641678Z","end":"2026-03-03T10:52:51.275639Z","steps":["trace[87124892] 'read index received' (duration: 633.638211ms)","trace[87124892] 'applied index is now lower than readState.Index' (duration: 321.535µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:52:51.27589Z","caller":"traceutil/trace.go:171","msg":"trace[924109613] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"640.107276ms","start":"2026-03-03T10:52:50.635766Z","end":"2026-03-03T10:52:51.275873Z","steps":["trace[924109613] 'process raft request' (duration: 639.70657ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:51.276055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.356683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T10:52:51.276148Z","caller":"traceutil/trace.go:171","msg":"trace[1497295762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"634.517705ms","start":"2026-03-03T10:52:50.641607Z","end":"2026-03-03T10:52:51.276125Z","steps":["trace[1497295762] 'agreement among raft nodes before linearized reading' (duration: 634.384503ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:51.276192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:50.641571Z","time spent":"634.612257ms","remote":"127.0.0.1:50048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T10:52:51.276647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:50.63574Z","time spent":"640.189527ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:52:51.276807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.252624ms","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-03T10:52:51.277151Z","caller":"traceutil/trace.go:171","msg":"trace[1550773838] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:487; }","duration":"271.008977ms","start":"2026-03-03T10:52:51.006117Z","end":"2026-03-03T10:52:51.277126Z","steps":["trace[1550773838] 'agreement among raft nodes before linearized reading' (duration: 269.807677ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:52:57.586345Z","caller":"traceutil/trace.go:171","msg":"trace[1767082857] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"259.304484ms","start":"2026-03-03T10:52:57.327023Z","end":"2026-03-03T10:52:57.586327Z","steps":["trace[1767082857] 'read index received' (duration: 259.146371ms)","trace[1767082857] 'applied index is now lower than readState.Index' (duration: 157.143µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:52:57.586398Z","caller":"traceutil/trace.go:171","msg":"trace[898963725] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"337.445151ms","start":"2026-03-03T10:52:57.248928Z","end":"2026-03-03T10:52:57.586373Z","steps":["trace[898963725] 'process raft request' (duration: 337.262077ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:57.586525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.494187ms","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-03T10:52:57.586553Z","caller":"traceutil/trace.go:171","msg":"trace[2039463625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"259.579049ms","start":"2026-03-03T10:52:57.326965Z","end":"2026-03-03T10:52:57.586544Z","steps":["trace[2039463625] 'agreement among raft nodes before linearized reading' (duration: 259.467017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:57.586798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:57.248904Z","time spent":"337.556002ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:52:58.191074Z","caller":"traceutil/trace.go:171","msg":"trace[688064948] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"599.58491ms","start":"2026-03-03T10:52:57.591469Z","end":"2026-03-03T10:52:58.191054Z","steps":["trace[688064948] 'process raft request' (duration: 599.476758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:58.191243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:57.591446Z","time spent":"599.686001ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:52:58.233331Z","caller":"traceutil/trace.go:171","msg":"trace[988202525] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"641.080287ms","start":"2026-03-03T10:52:57.592209Z","end":"2026-03-03T10:52:58.233289Z","steps":["trace[988202525] 'process raft request' (duration: 641.039216ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:52:58.23338Z","caller":"traceutil/trace.go:171","msg":"trace[636844678] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"641.277671ms","start":"2026-03-03T10:52:57.592054Z","end":"2026-03-03T10:52:58.233331Z","steps":["trace[636844678] 'process raft request' (duration: 641.157259ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:58.233492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:57.592181Z","time spent":"641.22483ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:52:58.233541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:57.592043Z","time spent":"641.408233ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:52:58.233705Z","caller":"traceutil/trace.go:171","msg":"trace[1159173232] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"641.876393ms","start":"2026-03-03T10:52:57.591811Z","end":"2026-03-03T10:52:58.233688Z","steps":["trace[1159173232] 'process raft request' (duration: 641.263941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:58.233801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:57.5918Z","time spent":"641.965614ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:52:59.063084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.566835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T10:52:59.063581Z","caller":"traceutil/trace.go:171","msg":"trace[1860310266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"406.184447ms","start":"2026-03-03T10:52:58.657373Z","end":"2026-03-03T10:52:59.063558Z","steps":["trace[1860310266] 'range keys from in-memory index tree' (duration: 405.429732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:59.063667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:58.657342Z","time spent":"406.284659ms","remote":"127.0.0.1:50048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T10:53:00.73744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957993137364349853,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T10:53:00.779981Z","caller":"traceutil/trace.go:171","msg":"trace[939392509] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"542.735874ms","start":"2026-03-03T10:53:00.237219Z","end":"2026-03-03T10:53:00.779955Z","steps":["trace[939392509] 'read index received' (duration: 542.485279ms)","trace[939392509] 'applied index is now lower than readState.Index' (duration: 249.585µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:53:00.780093Z","caller":"traceutil/trace.go:171","msg":"trace[1931782443] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"562.274217ms","start":"2026-03-03T10:53:00.217808Z","end":"2026-03-03T10:53:00.780082Z","steps":["trace[1931782443] 'process raft request' (duration: 561.997752ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:53:00.780261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:53:00.217792Z","time spent":"562.337449ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:53:00.780276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.308116ms","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-03T10:53:00.78035Z","caller":"traceutil/trace.go:171","msg":"trace[541709221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"541.392098ms","start":"2026-03-03T10:53:00.238922Z","end":"2026-03-03T10:53:00.780314Z","steps":["trace[541709221] 'agreement among raft nodes before linearized reading' (duration: 541.242555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:53:00.780374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:53:00.238907Z","time spent":"541.462609ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-03T10:53:00.780541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.295195ms","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-03T10:53:00.780628Z","caller":"traceutil/trace.go:171","msg":"trace[284721856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:533; }","duration":"543.435578ms","start":"2026-03-03T10:53:00.237177Z","end":"2026-03-03T10:53:00.780612Z","steps":["trace[284721856] 'agreement among raft nodes before linearized reading' (duration: 543.222443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:53:00.780665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:53:00.237156Z","time spent":"543.503389ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T10:53:00.780912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.945098ms","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-03T10:53:00.780969Z","caller":"traceutil/trace.go:171","msg":"trace[1742060903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:533; }","duration":"515.0443ms","start":"2026-03-03T10:53:00.265911Z","end":"2026-03-03T10:53:00.780955Z","steps":["trace[1742060903] 'agreement among raft nodes before linearized reading' (duration: 514.468379ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:53:00.780983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.053649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T10:53:00.781006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:53:00.265891Z","time spent":"515.107171ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-03T10:53:00.781039Z","caller":"traceutil/trace.go:171","msg":"trace[29177106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"140.10677ms","start":"2026-03-03T10:53:00.640894Z","end":"2026-03-03T10:53:00.781001Z","steps":["trace[29177106] 'agreement among raft nodes before linearized reading' (duration: 139.447327ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:53:01.818391Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957993137364349857,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T10:53:01.926869Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.14241885s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-03T10:53:02.003784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.767339ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T10:53:02.003932Z","caller":"traceutil/trace.go:171","msg":"trace[2027183850] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.219540909s","start":"2026-03-03T10:53:00.784366Z","end":"2026-03-03T10:53:02.003907Z","steps":["trace[2027183850] 'process raft request' (duration: 1.143058413s)","trace[2027183850] 'compare' (duration: 75.900734ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:53:02.004036Z","caller":"traceutil/trace.go:171","msg":"trace[2053375802] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:533; }","duration":"122.961803ms","start":"2026-03-03T10:53:01.880973Z","end":"2026-03-03T10:53:02.003935Z","steps":["trace[2053375802] 'range keys from in-memory index tree' (duration: 122.742709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:53:02.004091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:53:00.784345Z","time spent":"1.21965075s","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:53:02.012863Z","caller":"traceutil/trace.go:171","msg":"trace[48076967] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.226051611s","start":"2026-03-03T10:53:00.786789Z","end":"2026-03-03T10:53:02.01284Z","steps":["trace[48076967] 'process raft request' (duration: 1.225900568s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:53:02.013032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:53:00.786775Z","time spent":"1.226171333s","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:53:02.014228Z","caller":"traceutil/trace.go:171","msg":"trace[1989361262] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.220099195s","start":"2026-03-03T10:53:00.794063Z","end":"2026-03-03T10:53:02.014162Z","steps":["trace[1989361262] 'process raft request' (duration: 1.218742727s)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:53:02.014472Z","caller":"traceutil/trace.go:171","msg":"trace[627701946] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:550; }","duration":"696.66579ms","start":"2026-03-03T10:53:01.317748Z","end":"2026-03-03T10:53:02.014414Z","steps":["trace[627701946] 'read index received' (duration: 609.684882ms)","trace[627701946] 'applied index is now lower than readState.Index' (duration: 86.979588ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:53:02.014564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:53:00.794044Z","time spent":"1.22036276s","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:53:02.014884Z","caller":"traceutil/trace.go:171","msg":"trace[1194133861] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"618.077493ms","start":"2026-03-03T10:53:01.396759Z","end":"2026-03-03T10:53:02.014837Z","steps":["trace[1194133861] 'process raft request' (duration: 616.45619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:53:02.015064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:53:01.39673Z","time spent":"618.246196ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:53:02.01536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.6316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.150\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-03T10:53:02.015415Z","caller":"traceutil/trace.go:171","msg":"trace[285962535] range","detail":"{range_begin:/registry/masterleases/199.19.213.150; range_end:; response_count:1; response_revision:537; }","duration":"697.677911ms","start":"2026-03-03T10:53:01.317716Z","end":"2026-03-03T10:53:02.015394Z","steps":["trace[285962535] 'agreement among raft nodes before linearized reading' (duration: 696.795393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:53:02.015572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:53:01.317692Z","time spent":"697.869634ms","remote":"127.0.0.1:50078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.150\" "} {"level":"info","ts":"2026-03-03T10:53:03.950237Z","caller":"traceutil/trace.go:171","msg":"trace[126302541] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"510.795828ms","start":"2026-03-03T10:53:03.439414Z","end":"2026-03-03T10:53:03.95021Z","steps":["trace[126302541] 'process raft request' (duration: 510.650265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:53:03.950583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:53:03.439389Z","time spent":"511.034613ms","remote":"127.0.0.1:50100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:53:03.954624Z","caller":"traceutil/trace.go:171","msg":"trace[312194738] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"356.78429ms","start":"2026-03-03T10:53:03.597811Z","end":"2026-03-03T10:53:03.954595Z","steps":["trace[312194738] 'process raft request' (duration: 356.714958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:53:03.954765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:53:03.597754Z","time spent":"356.942093ms","remote":"127.0.0.1:50100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T10:53:03.95498Z","caller":"traceutil/trace.go:171","msg":"trace[1957965144] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"158.674244ms","start":"2026-03-03T10:53:03.796288Z","end":"2026-03-03T10:53:03.954962Z","steps":["trace[1957965144] 'process raft request' (duration: 158.275396ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:53:03.954654Z","caller":"traceutil/trace.go:171","msg":"trace[48369814] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"514.248829ms","start":"2026-03-03T10:53:03.440354Z","end":"2026-03-03T10:53:03.954603Z","steps":["trace[48369814] 'process raft request' (duration: 513.976013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:53:03.955503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:53:03.440335Z","time spent":"515.080036ms","remote":"127.0.0.1:50100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5162,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:53:06.743716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.402265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T10:53:06.743797Z","caller":"traceutil/trace.go:171","msg":"trace[218321203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"106.497107ms","start":"2026-03-03T10:53:06.637284Z","end":"2026-03-03T10:53:06.743781Z","steps":["trace[218321203] 'range keys from in-memory index tree' (duration: 106.322662ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:53:06.744177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.024683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-03-03T10:53:06.74425Z","caller":"traceutil/trace.go:171","msg":"trace[598001814] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:607; }","duration":"151.293518ms","start":"2026-03-03T10:53:06.592944Z","end":"2026-03-03T10:53:06.744238Z","steps":["trace[598001814] 'range keys from in-memory index tree' (duration: 150.912621ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:54:34.840548Z","caller":"traceutil/trace.go:171","msg":"trace[50088610] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"319.397119ms","start":"2026-03-03T10:54:34.521126Z","end":"2026-03-03T10:54:34.840523Z","steps":["trace[50088610] 'process raft request' (duration: 319.250254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:54:34.840691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:54:34.521105Z","time spent":"319.511823ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:54:34.905063Z","caller":"traceutil/trace.go:171","msg":"trace[2013120644] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"383.500709ms","start":"2026-03-03T10:54:34.521543Z","end":"2026-03-03T10:54:34.905044Z","steps":["trace[2013120644] 'process raft request' (duration: 383.351374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:54:34.905197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:54:34.521523Z","time spent":"383.606202ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:54:34.905222Z","caller":"traceutil/trace.go:171","msg":"trace[560139950] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1022; }","duration":"265.283373ms","start":"2026-03-03T10:54:34.639918Z","end":"2026-03-03T10:54:34.905202Z","steps":["trace[560139950] 'read index received' (duration: 200.468238ms)","trace[560139950] 'applied index is now lower than readState.Index' (duration: 64.813585ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:54:34.905286Z","caller":"traceutil/trace.go:171","msg":"trace[1463133935] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"383.687835ms","start":"2026-03-03T10:54:34.521575Z","end":"2026-03-03T10:54:34.905263Z","steps":["trace[1463133935] 'process raft request' (duration: 383.435926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:54:34.905336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.396977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T10:54:34.905382Z","caller":"traceutil/trace.go:171","msg":"trace[2044419378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"265.486901ms","start":"2026-03-03T10:54:34.639881Z","end":"2026-03-03T10:54:34.905368Z","steps":["trace[2044419378] 'agreement among raft nodes before linearized reading' (duration: 265.402548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:54:34.905403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:54:34.521551Z","time spent":"383.793229ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:54:35.567972Z","caller":"traceutil/trace.go:171","msg":"trace[94716461] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"129.932061ms","start":"2026-03-03T10:54:35.437414Z","end":"2026-03-03T10:54:35.567346Z","steps":["trace[94716461] 'process raft request' (duration: 129.683882ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:54:36.058952Z","caller":"traceutil/trace.go:171","msg":"trace[976044544] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"177.336076ms","start":"2026-03-03T10:54:35.881577Z","end":"2026-03-03T10:54:36.058913Z","steps":["trace[976044544] 'process raft request' (duration: 177.110238ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:54:51.394524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.003463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:5 size:1780"} {"level":"info","ts":"2026-03-03T10:54:51.394657Z","caller":"traceutil/trace.go:171","msg":"trace[1710981289] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:5; response_revision:1034; }","duration":"162.18497ms","start":"2026-03-03T10:54:51.232445Z","end":"2026-03-03T10:54:51.39463Z","steps":["trace[1710981289] 'range keys from in-memory index tree' (duration: 161.778194ms)"],"step_count":1}