{"level":"warn","ts":"2026-03-18T00:43:39.233512Z","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-18T00:43:39.233704Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-18T00:43:39.233875Z","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-18T00:43:39.233942Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-03-18T00:43:39.234101Z","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-18T00:43:39.235533Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-03-18T00:43:39.235782Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-18T00:43:39.242395Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.512095ms"} {"level":"info","ts":"2026-03-18T00:43:39.253666Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-03-18T00:43:39.253817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T00:43:39.253926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-03-18T00:43:39.253951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T00:43:39.257338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-03-18T00:43:39.257451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-03-18T00:43:39.266019Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T00:43:39.26734Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T00:43:39.268474Z","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-18T00:43:39.271379Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T00:43:39.271543Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T00:43:39.271599Z","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-18T00:43:39.273442Z","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-18T00:43:39.273461Z","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-18T00:43:39.274216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-03-18T00:43:39.274518Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-03-18T00:43:39.278747Z","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-18T00:43:39.278937Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-03-18T00:43:39.278967Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-03-18T00:43:39.279213Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T00:43:39.279292Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T00:43:40.15911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T00:43:40.159167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T00:43:40.159196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-03-18T00:43:40.159214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-03-18T00:43:40.159224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-03-18T00:43:40.159244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-03-18T00:43:40.159257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-03-18T00:43:40.160419Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T00:43:40.160425Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T00:43:40.160441Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T00:43:40.160673Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T00:43:40.162108Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T00:43:40.16223Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T00:43:40.16226Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T00:43:40.163605Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-03-18T00:43:40.164319Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T00:43:40.164346Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T00:43:40.164556Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-18T00:43:48.196426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.845353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-18T00:43:48.196523Z","caller":"traceutil/trace.go:171","msg":"trace[450777076] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:335; }","duration":"128.061552ms","start":"2026-03-18T00:43:48.06844Z","end":"2026-03-18T00:43:48.196502Z","steps":["trace[450777076] 'range keys from in-memory index tree' (duration: 127.668822ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:43:50.388916Z","caller":"traceutil/trace.go:171","msg":"trace[581387901] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"199.474815ms","start":"2026-03-18T00:43:50.189405Z","end":"2026-03-18T00:43:50.38888Z","steps":["trace[581387901] 'process raft request' (duration: 199.321388ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:43:51.313267Z","caller":"traceutil/trace.go:171","msg":"trace[946480818] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"315.493427ms","start":"2026-03-18T00:43:50.997744Z","end":"2026-03-18T00:43:51.313238Z","steps":["trace[946480818] 'process raft request' (duration: 258.423717ms)","trace[946480818] 'compare' (duration: 56.847731ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:43:51.313876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:43:50.997717Z","time spent":"315.630251ms","remote":"127.0.0.1:47834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T00:43:51.634732Z","caller":"traceutil/trace.go:171","msg":"trace[1822481769] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"538.89353ms","start":"2026-03-18T00:43:51.095609Z","end":"2026-03-18T00:43:51.634503Z","steps":["trace[1822481769] 'process raft request' (duration: 511.323022ms)","trace[1822481769] 'compare' (duration: 27.271636ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:43:51.634868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:43:51.095591Z","time spent":"539.212965ms","remote":"127.0.0.1:48332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T00:43:51.865835Z","caller":"traceutil/trace.go:171","msg":"trace[1118927318] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"222.557736ms","start":"2026-03-18T00:43:51.643255Z","end":"2026-03-18T00:43:51.865813Z","steps":["trace[1118927318] 'process raft request' (duration: 221.36994ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:43:51.867619Z","caller":"traceutil/trace.go:171","msg":"trace[42986922] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"165.549166ms","start":"2026-03-18T00:43:51.702051Z","end":"2026-03-18T00:43:51.8676Z","steps":["trace[42986922] 'process raft request' (duration: 165.16549ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:43:52.655882Z","caller":"traceutil/trace.go:171","msg":"trace[1999830456] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"248.306954ms","start":"2026-03-18T00:43:52.407558Z","end":"2026-03-18T00:43:52.655865Z","steps":["trace[1999830456] 'process raft request' (duration: 248.203707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:43:53.934179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.205491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:43:53.934277Z","caller":"traceutil/trace.go:171","msg":"trace[1892154872] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"1.160584037s","start":"2026-03-18T00:43:52.773679Z","end":"2026-03-18T00:43:53.934263Z","steps":["trace[1892154872] 'read index received' (duration: 341.874039ms)","trace[1892154872] 'applied index is now lower than readState.Index' (duration: 818.708687ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:43:53.934347Z","caller":"traceutil/trace.go:171","msg":"trace[1489430725] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"1.526358631s","start":"2026-03-18T00:43:52.407946Z","end":"2026-03-18T00:43:53.934305Z","steps":["trace[1489430725] 'process raft request' (duration: 707.666036ms)","trace[1489430725] 'compare' (duration: 818.093132ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:43:53.934404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.465775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-18T00:43:53.934433Z","caller":"traceutil/trace.go:171","msg":"trace[2126623598] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:399; }","duration":"892.534907ms","start":"2026-03-18T00:43:53.041889Z","end":"2026-03-18T00:43:53.934424Z","steps":["trace[2126623598] 'agreement among raft nodes before linearized reading' (duration: 892.475887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:43:53.934476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:43:53.041867Z","time spent":"892.600678ms","remote":"127.0.0.1:47774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"warn","ts":"2026-03-18T00:43:53.93454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:43:52.407885Z","time spent":"1.526547694s","remote":"127.0.0.1:48026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:43:53.934555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16082926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-03-18T00:43:53.934677Z","caller":"traceutil/trace.go:171","msg":"trace[658006305] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:399; }","duration":"1.161031704s","start":"2026-03-18T00:43:52.773627Z","end":"2026-03-18T00:43:53.934659Z","steps":["trace[658006305] 'agreement among raft nodes before linearized reading' (duration: 1.16071238s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:43:53.934732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.160756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:43:53.93477Z","caller":"traceutil/trace.go:171","msg":"trace[958896948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:399; }","duration":"874.196932ms","start":"2026-03-18T00:43:53.060564Z","end":"2026-03-18T00:43:53.934761Z","steps":["trace[958896948] 'agreement among raft nodes before linearized reading' (duration: 874.144723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:43:53.934801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:43:53.060528Z","time spent":"874.267404ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T00:43:53.934744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:43:52.773605Z","time spent":"1.161126141s","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6987,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-03-18T00:43:54.161595Z","caller":"traceutil/trace.go:171","msg":"trace[1591219299] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:412; }","duration":"210.058839ms","start":"2026-03-18T00:43:53.951497Z","end":"2026-03-18T00:43:54.161555Z","steps":["trace[1591219299] 'read index received' (duration: 164.30637ms)","trace[1591219299] 'applied index is now lower than readState.Index' (duration: 45.750889ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:43:54.161687Z","caller":"traceutil/trace.go:171","msg":"trace[1118272032] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"219.548786ms","start":"2026-03-18T00:43:53.942105Z","end":"2026-03-18T00:43:54.161653Z","steps":["trace[1118272032] 'process raft request' (duration: 219.209888ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:43:54.163705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.328105ms","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-18T00:43:54.163798Z","caller":"traceutil/trace.go:171","msg":"trace[67177821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:400; }","duration":"212.324935ms","start":"2026-03-18T00:43:53.95145Z","end":"2026-03-18T00:43:54.163775Z","steps":["trace[67177821] 'agreement among raft nodes before linearized reading' (duration: 210.313042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:43:54.172878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.187574ms","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-18T00:43:54.172958Z","caller":"traceutil/trace.go:171","msg":"trace[871771908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:401; }","duration":"174.323028ms","start":"2026-03-18T00:43:53.998621Z","end":"2026-03-18T00:43:54.172944Z","steps":["trace[871771908] 'agreement among raft nodes before linearized reading' (duration: 174.155109ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:43:54.172901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.748085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:43:54.173063Z","caller":"traceutil/trace.go:171","msg":"trace[849843295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"112.945289ms","start":"2026-03-18T00:43:54.060104Z","end":"2026-03-18T00:43:54.173049Z","steps":["trace[849843295] 'agreement among raft nodes before linearized reading' (duration: 112.669632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:43:54.827222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.662996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:43:54.827336Z","caller":"traceutil/trace.go:171","msg":"trace[731048061] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"158.279236ms","start":"2026-03-18T00:43:54.669038Z","end":"2026-03-18T00:43:54.827317Z","steps":["trace[731048061] 'process raft request' (duration: 25.43625ms)","trace[731048061] 'compare' (duration: 132.526712ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:43:56.205115Z","caller":"traceutil/trace.go:171","msg":"trace[359144838] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"262.402229ms","start":"2026-03-18T00:43:55.942691Z","end":"2026-03-18T00:43:56.205094Z","steps":["trace[359144838] 'process raft request' (duration: 262.22867ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:43:57.813872Z","caller":"traceutil/trace.go:171","msg":"trace[1131582949] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"166.577911ms","start":"2026-03-18T00:43:57.647265Z","end":"2026-03-18T00:43:57.813843Z","steps":["trace[1131582949] 'process raft request' (duration: 166.39103ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:43:57.957257Z","caller":"traceutil/trace.go:171","msg":"trace[1480846878] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"131.381069ms","start":"2026-03-18T00:43:57.825847Z","end":"2026-03-18T00:43:57.957228Z","steps":["trace[1480846878] 'process raft request' (duration: 83.157353ms)","trace[1480846878] 'compare' (duration: 48.080912ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:44:00.476512Z","caller":"traceutil/trace.go:171","msg":"trace[1571297835] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"204.53602ms","start":"2026-03-18T00:44:00.271945Z","end":"2026-03-18T00:44:00.476481Z","steps":["trace[1571297835] 'process raft request' (duration: 163.959026ms)","trace[1571297835] 'compare' (duration: 40.454194ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:44:00.52314Z","caller":"traceutil/trace.go:171","msg":"trace[543308549] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"250.239763ms","start":"2026-03-18T00:44:00.272881Z","end":"2026-03-18T00:44:00.523121Z","steps":["trace[543308549] 'process raft request' (duration: 250.161591ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:44:02.500587Z","caller":"traceutil/trace.go:171","msg":"trace[447327304] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"120.06238ms","start":"2026-03-18T00:44:02.380492Z","end":"2026-03-18T00:44:02.500554Z","steps":["trace[447327304] 'process raft request' (duration: 119.737267ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:44:04.250864Z","caller":"traceutil/trace.go:171","msg":"trace[27115482] transaction","detail":"{read_only:false; number_of_response:1; response_revision:443; }","duration":"164.773039ms","start":"2026-03-18T00:44:04.08606Z","end":"2026-03-18T00:44:04.250833Z","steps":["trace[27115482] 'process raft request' (duration: 125.178123ms)","trace[27115482] 'compare' (duration: 39.518224ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:44:04.266967Z","caller":"traceutil/trace.go:171","msg":"trace[1566257186] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"175.211311ms","start":"2026-03-18T00:44:04.091749Z","end":"2026-03-18T00:44:04.26696Z","steps":["trace[1566257186] 'process raft request' (duration: 175.162993ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:44:04.267026Z","caller":"traceutil/trace.go:171","msg":"trace[2131581637] transaction","detail":"{read_only:false; number_of_response:1; response_revision:443; }","duration":"177.27398ms","start":"2026-03-18T00:44:04.089728Z","end":"2026-03-18T00:44:04.267002Z","steps":["trace[2131581637] 'process raft request' (duration: 177.14776ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:44:04.398813Z","caller":"traceutil/trace.go:171","msg":"trace[573331629] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"104.154918ms","start":"2026-03-18T00:44:04.294632Z","end":"2026-03-18T00:44:04.398787Z","steps":["trace[573331629] 'process raft request' (duration: 78.376712ms)","trace[573331629] 'compare' (duration: 25.546679ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:44:04.39885Z","caller":"traceutil/trace.go:171","msg":"trace[1445668932] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:464; }","duration":"101.059883ms","start":"2026-03-18T00:44:04.297768Z","end":"2026-03-18T00:44:04.398827Z","steps":["trace[1445668932] 'read index received' (duration: 4.636991ms)","trace[1445668932] 'applied index is now lower than readState.Index' (duration: 96.421751ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:44:04.398945Z","caller":"traceutil/trace.go:171","msg":"trace[1525142629] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"101.352939ms","start":"2026-03-18T00:44:04.297523Z","end":"2026-03-18T00:44:04.398876Z","steps":["trace[1525142629] 'process raft request' (duration: 101.216908ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:44:04.399049Z","caller":"traceutil/trace.go:171","msg":"trace[853567688] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"100.633505ms","start":"2026-03-18T00:44:04.298405Z","end":"2026-03-18T00:44:04.399038Z","steps":["trace[853567688] 'process raft request' (duration: 100.377474ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:44:04.399053Z","caller":"traceutil/trace.go:171","msg":"trace[492586821] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"102.703087ms","start":"2026-03-18T00:44:04.296344Z","end":"2026-03-18T00:44:04.399047Z","steps":["trace[492586821] 'process raft request' (duration: 102.34714ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:04.399006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.220709ms","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-18T00:44:04.399276Z","caller":"traceutil/trace.go:171","msg":"trace[543459660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:451; }","duration":"101.513336ms","start":"2026-03-18T00:44:04.297728Z","end":"2026-03-18T00:44:04.399241Z","steps":["trace[543459660] 'agreement among raft nodes before linearized reading' (duration: 101.179742ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:06.346922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.784645949s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:44:06.347032Z","caller":"traceutil/trace.go:171","msg":"trace[920511613] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"1.892747768s","start":"2026-03-18T00:44:04.454265Z","end":"2026-03-18T00:44:06.347012Z","steps":["trace[920511613] 'process raft request' (duration: 107.53945ms)","trace[920511613] 'compare' (duration: 1.784546893s)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:44:06.347096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:04.454244Z","time spent":"1.892820519s","remote":"127.0.0.1:48300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:44:06.349818Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.787978296s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T00:44:06.350315Z","caller":"traceutil/trace.go:171","msg":"trace[30172881] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:476; }","duration":"1.83681365s","start":"2026-03-18T00:44:04.513477Z","end":"2026-03-18T00:44:06.350291Z","steps":["trace[30172881] 'read index received' (duration: 48.343047ms)","trace[30172881] 'applied index is now lower than readState.Index' (duration: 1.788469663s)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:44:06.350441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.836957403s","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-18T00:44:06.350435Z","caller":"traceutil/trace.go:171","msg":"trace[1624873147] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"1.895516834s","start":"2026-03-18T00:44:04.454841Z","end":"2026-03-18T00:44:06.350357Z","steps":["trace[1624873147] 'process raft request' (duration: 1.895337305s)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:44:06.350471Z","caller":"traceutil/trace.go:171","msg":"trace[234361787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:460; }","duration":"1.837042896s","start":"2026-03-18T00:44:04.513421Z","end":"2026-03-18T00:44:06.350464Z","steps":["trace[234361787] 'agreement among raft nodes before linearized reading' (duration: 1.836973705s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:06.350492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:04.513399Z","time spent":"1.837087873s","remote":"127.0.0.1:48026","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-18T00:44:06.350552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:04.454822Z","time spent":"1.895662977s","remote":"127.0.0.1:48270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:44:06.351652Z","caller":"traceutil/trace.go:171","msg":"trace[1283406064] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"1.507607434s","start":"2026-03-18T00:44:04.844017Z","end":"2026-03-18T00:44:06.351625Z","steps":["trace[1283406064] 'process raft request' (duration: 1.507463471s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:06.351924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:04.84399Z","time spent":"1.507844211s","remote":"127.0.0.1:48026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:44:06.35239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.780420811s","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-18T00:44:06.352513Z","caller":"traceutil/trace.go:171","msg":"trace[1961832002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:461; }","duration":"1.780586889s","start":"2026-03-18T00:44:04.57191Z","end":"2026-03-18T00:44:06.352497Z","steps":["trace[1961832002] 'agreement among raft nodes before linearized reading' (duration: 1.780414772s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:06.352817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:04.571894Z","time spent":"1.78090559s","remote":"127.0.0.1:48026","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-18T00:44:06.352494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.291792155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:44:06.352965Z","caller":"traceutil/trace.go:171","msg":"trace[1116247348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:461; }","duration":"1.292306057s","start":"2026-03-18T00:44:05.060638Z","end":"2026-03-18T00:44:06.352944Z","steps":["trace[1116247348] 'agreement among raft nodes before linearized reading' (duration: 1.291780314s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:06.353026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:05.060618Z","time spent":"1.29239377s","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T00:44:06.352695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511437025s","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-18T00:44:06.353139Z","caller":"traceutil/trace.go:171","msg":"trace[908306169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:461; }","duration":"1.51191322s","start":"2026-03-18T00:44:04.841207Z","end":"2026-03-18T00:44:06.35312Z","steps":["trace[908306169] 'agreement among raft nodes before linearized reading' (duration: 1.511348481s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:06.353542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:04.841185Z","time spent":"1.512014506s","remote":"127.0.0.1:48026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-18T00:44:15.738725Z","caller":"traceutil/trace.go:171","msg":"trace[81539875] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"584.196342ms","start":"2026-03-18T00:44:15.154499Z","end":"2026-03-18T00:44:15.738696Z","steps":["trace[81539875] 'process raft request' (duration: 571.561512ms)","trace[81539875] 'compare' (duration: 12.50233ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:44:15.738864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:15.154467Z","time spent":"584.339224ms","remote":"127.0.0.1:47972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":505,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T00:44:15.739648Z","caller":"traceutil/trace.go:171","msg":"trace[1022654028] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"584.929693ms","start":"2026-03-18T00:44:15.154688Z","end":"2026-03-18T00:44:15.739618Z","steps":["trace[1022654028] 'process raft request' (duration: 584.789191ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:15.739838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:15.15467Z","time spent":"585.089568ms","remote":"127.0.0.1:47972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T00:44:18.682277Z","caller":"traceutil/trace.go:171","msg":"trace[939652964] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"251.547037ms","start":"2026-03-18T00:44:18.430699Z","end":"2026-03-18T00:44:18.682246Z","steps":["trace[939652964] 'read index received' (duration: 251.386343ms)","trace[939652964] 'applied index is now lower than readState.Index' (duration: 159.284µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:44:18.68237Z","caller":"traceutil/trace.go:171","msg":"trace[2129101956] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"252.988365ms","start":"2026-03-18T00:44:18.429329Z","end":"2026-03-18T00:44:18.682317Z","steps":["trace[2129101956] 'process raft request' (duration: 252.744859ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:18.682502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.473206ms","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-18T00:44:18.682516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.82627ms","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-18T00:44:18.682505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.784373ms","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-18T00:44:18.682561Z","caller":"traceutil/trace.go:171","msg":"trace[1771697117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:527; }","duration":"248.5659ms","start":"2026-03-18T00:44:18.433978Z","end":"2026-03-18T00:44:18.682544Z","steps":["trace[1771697117] 'agreement among raft nodes before linearized reading' (duration: 248.449623ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:44:18.682572Z","caller":"traceutil/trace.go:171","msg":"trace[755220293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"205.919994ms","start":"2026-03-18T00:44:18.476636Z","end":"2026-03-18T00:44:18.682556Z","steps":["trace[755220293] 'agreement among raft nodes before linearized reading' (duration: 205.822749ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:44:18.6826Z","caller":"traceutil/trace.go:171","msg":"trace[791288841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:527; }","duration":"251.919734ms","start":"2026-03-18T00:44:18.430651Z","end":"2026-03-18T00:44:18.682571Z","steps":["trace[791288841] 'agreement among raft nodes before linearized reading' (duration: 251.750349ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:44:19.435499Z","caller":"traceutil/trace.go:171","msg":"trace[1693616082] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"749.141042ms","start":"2026-03-18T00:44:18.686333Z","end":"2026-03-18T00:44:19.435474Z","steps":["trace[1693616082] 'process raft request' (duration: 748.988049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:19.435647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:18.68631Z","time spent":"749.25702ms","remote":"127.0.0.1:48026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:44:19.437119Z","caller":"traceutil/trace.go:171","msg":"trace[1456173928] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:548; }","duration":"376.706923ms","start":"2026-03-18T00:44:19.060389Z","end":"2026-03-18T00:44:19.437096Z","steps":["trace[1456173928] 'read index received' (duration: 374.926547ms)","trace[1456173928] 'applied index is now lower than readState.Index' (duration: 1.779516ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:44:19.437155Z","caller":"traceutil/trace.go:171","msg":"trace[1275026347] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"750.165145ms","start":"2026-03-18T00:44:18.686975Z","end":"2026-03-18T00:44:19.437141Z","steps":["trace[1275026347] 'process raft request' (duration: 749.981257ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:44:19.437231Z","caller":"traceutil/trace.go:171","msg":"trace[109402228] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"750.168765ms","start":"2026-03-18T00:44:18.687028Z","end":"2026-03-18T00:44:19.437197Z","steps":["trace[109402228] 'process raft request' (duration: 750.036765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:19.437344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.951269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:44:19.437375Z","caller":"traceutil/trace.go:171","msg":"trace[1247993188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"376.983934ms","start":"2026-03-18T00:44:19.060383Z","end":"2026-03-18T00:44:19.437367Z","steps":["trace[1247993188] 'agreement among raft nodes before linearized reading' (duration: 376.933557ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:19.437381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:18.686958Z","time spent":"750.321587ms","remote":"127.0.0.1:48026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:44:19.437395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:19.06034Z","time spent":"377.050864ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T00:44:19.437431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:18.687023Z","time spent":"750.305466ms","remote":"127.0.0.1:48026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:44:25.857957Z","caller":"traceutil/trace.go:171","msg":"trace[1146577798] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"393.297256ms","start":"2026-03-18T00:44:25.464629Z","end":"2026-03-18T00:44:25.857926Z","steps":["trace[1146577798] 'process raft request' (duration: 393.161256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:25.858177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:25.464616Z","time spent":"393.480703ms","remote":"127.0.0.1:48026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:44:25.870113Z","caller":"traceutil/trace.go:171","msg":"trace[2031503179] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:589; }","duration":"173.403183ms","start":"2026-03-18T00:44:25.696688Z","end":"2026-03-18T00:44:25.870091Z","steps":["trace[2031503179] 'read index received' (duration: 161.082255ms)","trace[2031503179] 'applied index is now lower than readState.Index' (duration: 12.320058ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:44:25.870232Z","caller":"traceutil/trace.go:171","msg":"trace[670675745] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"320.310208ms","start":"2026-03-18T00:44:25.549906Z","end":"2026-03-18T00:44:25.870216Z","steps":["trace[670675745] 'process raft request' (duration: 320.080724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:25.87032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:25.549884Z","time spent":"320.39036ms","remote":"127.0.0.1:48026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:44:25.870334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.628086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wdbws\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-18T00:44:25.870399Z","caller":"traceutil/trace.go:171","msg":"trace[269192593] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wdbws; range_end:; response_count:1; response_revision:569; }","duration":"173.748143ms","start":"2026-03-18T00:44:25.696636Z","end":"2026-03-18T00:44:25.870384Z","steps":["trace[269192593] 'agreement among raft nodes before linearized reading' (duration: 173.538482ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:44:26.379692Z","caller":"traceutil/trace.go:171","msg":"trace[950357750] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"319.113023ms","start":"2026-03-18T00:44:26.060561Z","end":"2026-03-18T00:44:26.379674Z","steps":["trace[950357750] 'read index received' (duration: 318.963671ms)","trace[950357750] 'applied index is now lower than readState.Index' (duration: 148.742µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:44:26.379845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.266385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:44:26.379872Z","caller":"traceutil/trace.go:171","msg":"trace[1555517343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"319.336725ms","start":"2026-03-18T00:44:26.060529Z","end":"2026-03-18T00:44:26.379866Z","steps":["trace[1555517343] 'agreement among raft nodes before linearized reading' (duration: 319.214007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:26.379894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:26.060502Z","time spent":"319.387502ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T00:44:26.380097Z","caller":"traceutil/trace.go:171","msg":"trace[1485792882] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"497.962688ms","start":"2026-03-18T00:44:25.8821Z","end":"2026-03-18T00:44:26.380063Z","steps":["trace[1485792882] 'process raft request' (duration: 497.480097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:26.380262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:25.882082Z","time spent":"498.096568ms","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:44:28.696295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.979274548s","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-18T00:44:28.696366Z","caller":"traceutil/trace.go:171","msg":"trace[390194794] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"1.979399296s","start":"2026-03-18T00:44:26.71695Z","end":"2026-03-18T00:44:28.696349Z","steps":["trace[390194794] 'range keys from in-memory index tree' (duration: 1.979095251s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:28.696405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:26.716921Z","time spent":"1.979475698s","remote":"127.0.0.1:48026","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-18T00:44:28.697055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.532411376s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:44:28.697131Z","caller":"traceutil/trace.go:171","msg":"trace[805511956] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"1.147606756s","start":"2026-03-18T00:44:27.549511Z","end":"2026-03-18T00:44:28.697118Z","steps":["trace[805511956] 'read index received' (duration: 41.966µs)","trace[805511956] 'applied index is now lower than readState.Index' (duration: 1.14756371s)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:44:28.697174Z","caller":"traceutil/trace.go:171","msg":"trace[1762114495] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.816378178s","start":"2026-03-18T00:44:26.88077Z","end":"2026-03-18T00:44:28.697148Z","steps":["trace[1762114495] 'process raft request' (duration: 283.802828ms)","trace[1762114495] 'compare' (duration: 1.531563273s)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:44:28.697323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:26.880743Z","time spent":"1.816488634s","remote":"127.0.0.1:48026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:44:28.697338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147810556s","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-18T00:44:28.697408Z","caller":"traceutil/trace.go:171","msg":"trace[1034189678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:571; }","duration":"1.147919002s","start":"2026-03-18T00:44:27.549471Z","end":"2026-03-18T00:44:28.69739Z","steps":["trace[1034189678] 'agreement among raft nodes before linearized reading' (duration: 1.147709521s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:28.697463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:27.549452Z","time spent":"1.148001244s","remote":"127.0.0.1:48026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T00:44:28.756517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.56087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T00:44:28.756536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.722569ms","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-18T00:44:28.756606Z","caller":"traceutil/trace.go:171","msg":"trace[751761358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"695.76127ms","start":"2026-03-18T00:44:28.060822Z","end":"2026-03-18T00:44:28.756583Z","steps":["trace[751761358] 'agreement among raft nodes before linearized reading' (duration: 695.595235ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:44:28.756625Z","caller":"traceutil/trace.go:171","msg":"trace[1853189599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"893.861001ms","start":"2026-03-18T00:44:27.862742Z","end":"2026-03-18T00:44:28.756603Z","steps":["trace[1853189599] 'agreement among raft nodes before linearized reading' (duration: 893.644429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:28.756652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:28.060774Z","time spent":"695.869236ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T00:44:28.756681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:27.862725Z","time spent":"893.942484ms","remote":"127.0.0.1:48026","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-18T00:44:28.756695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.974887ms","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-18T00:44:28.756775Z","caller":"traceutil/trace.go:171","msg":"trace[1607863192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:571; }","duration":"882.085753ms","start":"2026-03-18T00:44:27.874663Z","end":"2026-03-18T00:44:28.756749Z","steps":["trace[1607863192] 'agreement among raft nodes before linearized reading' (duration: 881.852749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:44:28.756819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:44:27.874652Z","time spent":"882.158004ms","remote":"127.0.0.1:48026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T00:44:30.298806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.918338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:44:30.298973Z","caller":"traceutil/trace.go:171","msg":"trace[655158892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"240.136339ms","start":"2026-03-18T00:44:30.058815Z","end":"2026-03-18T00:44:30.298952Z","steps":["trace[655158892] 'range keys from in-memory index tree' (duration: 239.813463ms)"],"step_count":1}