{"level":"warn","ts":"2026-03-05T09:41:08.466075Z","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-05T09:41:08.466165Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.51: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.51:2380","--initial-cluster=instance=https://199.19.213.51:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.51:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.51: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-05T09:41:08.46623Z","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-05T09:41:08.466246Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.51:2380"]} {"level":"info","ts":"2026-03-05T09:41:08.466261Z","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-05T09:41:08.467946Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.51:2379"]} {"level":"info","ts":"2026-03-05T09:41:08.468032Z","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.51:2380"],"listen-peer-urls":["https://199.19.213.51:2380"],"advertise-client-urls":["https://199.19.213.51:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.51:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.51: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-05T09:41:08.475058Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.765775ms"} {"level":"info","ts":"2026-03-05T09:41:08.48598Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d8a2e6f117593bd6","cluster-id":"28b0db2e76181428"} {"level":"info","ts":"2026-03-05T09:41:08.486068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 switched to configuration voters=()"} {"level":"info","ts":"2026-03-05T09:41:08.486095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 became follower at term 0"} {"level":"info","ts":"2026-03-05T09:41:08.486106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d8a2e6f117593bd6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-05T09:41:08.486123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 became follower at term 1"} {"level":"info","ts":"2026-03-05T09:41:08.486175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 switched to configuration voters=(15610293181572791254)"} {"level":"warn","ts":"2026-03-05T09:41:08.494114Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-05T09:41:08.495448Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-05T09:41:08.496824Z","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-05T09:41:08.499383Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d8a2e6f117593bd6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-05T09:41:08.500482Z","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-05T09:41:08.500843Z","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-05T09:41:08.500852Z","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-05T09:41:08.500497Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d8a2e6f117593bd6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-05T09:41:08.501564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 switched to configuration voters=(15610293181572791254)"} {"level":"info","ts":"2026-03-05T09:41:08.501784Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"28b0db2e76181428","local-member-id":"d8a2e6f117593bd6","added-peer-id":"d8a2e6f117593bd6","added-peer-peer-urls":["https://199.19.213.51:2380"]} {"level":"info","ts":"2026-03-05T09:41:08.504082Z","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-05T09:41:08.504224Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.51:2380"} {"level":"info","ts":"2026-03-05T09:41:08.504276Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.51:2380"} {"level":"info","ts":"2026-03-05T09:41:08.504449Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d8a2e6f117593bd6","initial-advertise-peer-urls":["https://199.19.213.51:2380"],"listen-peer-urls":["https://199.19.213.51:2380"],"advertise-client-urls":["https://199.19.213.51:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.51:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-05T09:41:08.504498Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-05T09:41:09.189392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 is starting a new election at term 1"} {"level":"info","ts":"2026-03-05T09:41:09.189436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-05T09:41:09.189457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 received MsgPreVoteResp from d8a2e6f117593bd6 at term 1"} {"level":"info","ts":"2026-03-05T09:41:09.189468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 became candidate at term 2"} {"level":"info","ts":"2026-03-05T09:41:09.189473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 received MsgVoteResp from d8a2e6f117593bd6 at term 2"} {"level":"info","ts":"2026-03-05T09:41:09.189482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 became leader at term 2"} {"level":"info","ts":"2026-03-05T09:41:09.189489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d8a2e6f117593bd6 elected leader d8a2e6f117593bd6 at term 2"} {"level":"info","ts":"2026-03-05T09:41:09.19172Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T09:41:09.193348Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"28b0db2e76181428","local-member-id":"d8a2e6f117593bd6","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T09:41:09.193413Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T09:41:09.193428Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T09:41:09.193448Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d8a2e6f117593bd6","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.51:2379]}","request-path":"/0/members/d8a2e6f117593bd6/attributes","cluster-id":"28b0db2e76181428","publish-timeout":"7s"} {"level":"info","ts":"2026-03-05T09:41:09.193815Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T09:41:09.194067Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T09:41:09.194367Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-05T09:41:09.197765Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.51:2379"} {"level":"info","ts":"2026-03-05T09:41:09.199785Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-05T09:41:09.194911Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-05T09:41:11.87408Z","caller":"traceutil/trace.go:171","msg":"trace[575278022] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"484.699527ms","start":"2026-03-05T09:41:11.389331Z","end":"2026-03-05T09:41:11.874031Z","steps":["trace[575278022] 'process raft request' (duration: 484.519671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:11.874278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.384859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-05T09:41:11.874412Z","caller":"traceutil/trace.go:171","msg":"trace[731066124] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:71; }","duration":"483.531434ms","start":"2026-03-05T09:41:11.390836Z","end":"2026-03-05T09:41:11.874367Z","steps":["trace[731066124] 'agreement among raft nodes before linearized reading' (duration: 483.334907ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:11.874486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:11.390823Z","time spent":"483.644979ms","remote":"127.0.0.1:54958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-03-05T09:41:11.874484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:11.389307Z","time spent":"484.856893ms","remote":"127.0.0.1:54982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T09:41:11.874061Z","caller":"traceutil/trace.go:171","msg":"trace[973382910] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"483.14211ms","start":"2026-03-05T09:41:11.390864Z","end":"2026-03-05T09:41:11.874006Z","steps":["trace[973382910] 'read index received' (duration: 483.011105ms)","trace[973382910] 'applied index is now lower than readState.Index' (duration: 129.805µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T09:41:11.875074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.371718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-05T09:41:11.875154Z","caller":"traceutil/trace.go:171","msg":"trace[3246399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"378.496563ms","start":"2026-03-05T09:41:11.496644Z","end":"2026-03-05T09:41:11.87514Z","steps":["trace[3246399] 'agreement among raft nodes before linearized reading' (duration: 378.383739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:11.875196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:11.496619Z","time spent":"378.560996ms","remote":"127.0.0.1:54622","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-05T09:41:12.382917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4311806030416250586,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T09:41:12.88902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4311806030416250586,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T09:41:13.315228Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.434786974s","expected-duration":"1s"} {"level":"info","ts":"2026-03-05T09:41:13.315548Z","caller":"traceutil/trace.go:171","msg":"trace[473408069] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"1.433401564s","start":"2026-03-05T09:41:11.882124Z","end":"2026-03-05T09:41:13.315525Z","steps":["trace[473408069] 'read index received' (duration: 1.43330028s)","trace[473408069] 'applied index is now lower than readState.Index' (duration: 100.284µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T09:41:13.315632Z","caller":"traceutil/trace.go:171","msg":"trace[476592148] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"1.435224689s","start":"2026-03-05T09:41:11.880372Z","end":"2026-03-05T09:41:13.315597Z","steps":["trace[476592148] 'process raft request' (duration: 1.435042353s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:13.315752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.433612372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-05T09:41:13.315803Z","caller":"traceutil/trace.go:171","msg":"trace[667775106] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:72; }","duration":"1.433703514s","start":"2026-03-05T09:41:11.882089Z","end":"2026-03-05T09:41:13.315792Z","steps":["trace[667775106] 'agreement among raft nodes before linearized reading' (duration: 1.433537958s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:13.315838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:11.882074Z","time spent":"1.433756047s","remote":"127.0.0.1:54958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-03-05T09:41:13.315852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:11.880359Z","time spent":"1.435402116s","remote":"127.0.0.1:54982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T09:41:14.435697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014298644s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-05T09:41:14.435795Z","caller":"traceutil/trace.go:171","msg":"trace[219117386] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"1.120176383s","start":"2026-03-05T09:41:13.315602Z","end":"2026-03-05T09:41:14.435779Z","steps":["trace[219117386] 'read index received' (duration: 105.659701ms)","trace[219117386] 'applied index is now lower than readState.Index' (duration: 1.014515392s)"],"step_count":2} {"level":"warn","ts":"2026-03-05T09:41:14.435847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:13.059106Z","time spent":"1.376732962s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-05T09:41:14.435896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117614609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-05T09:41:14.435891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49800808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-05T09:41:14.435925Z","caller":"traceutil/trace.go:171","msg":"trace[1606826941] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:72; }","duration":"1.117675411s","start":"2026-03-05T09:41:13.318241Z","end":"2026-03-05T09:41:14.435917Z","steps":["trace[1606826941] 'agreement among raft nodes before linearized reading' (duration: 1.11762655s)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:41:14.435949Z","caller":"traceutil/trace.go:171","msg":"trace[519055243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"1.498103134s","start":"2026-03-05T09:41:12.937831Z","end":"2026-03-05T09:41:14.435934Z","steps":["trace[519055243] 'agreement among raft nodes before linearized reading' (duration: 1.49799757s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:14.435996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:12.937815Z","time spent":"1.498172417s","remote":"127.0.0.1:54636","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-05T09:41:14.435995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:13.318224Z","time spent":"1.117763745s","remote":"127.0.0.1:54958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"warn","ts":"2026-03-05T09:41:14.435911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.914234657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-05T09:41:14.436438Z","caller":"traceutil/trace.go:171","msg":"trace[2120574105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"1.914803678s","start":"2026-03-05T09:41:12.521616Z","end":"2026-03-05T09:41:14.43642Z","steps":["trace[2120574105] 'agreement among raft nodes before linearized reading' (duration: 1.914235188s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:14.436519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:12.521596Z","time spent":"1.914910022s","remote":"127.0.0.1:54622","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-05T09:41:40.387065Z","caller":"traceutil/trace.go:171","msg":"trace[1658569328] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"119.653218ms","start":"2026-03-05T09:41:40.267383Z","end":"2026-03-05T09:41:40.387036Z","steps":["trace[1658569328] 'process raft request' (duration: 89.631368ms)","trace[1658569328] 'compare' (duration: 29.86022ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T09:41:40.387364Z","caller":"traceutil/trace.go:171","msg":"trace[200732202] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"115.809679ms","start":"2026-03-05T09:41:40.271535Z","end":"2026-03-05T09:41:40.387345Z","steps":["trace[200732202] 'process raft request' (duration: 115.460577ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:41:40.387488Z","caller":"traceutil/trace.go:171","msg":"trace[680754166] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"115.369656ms","start":"2026-03-05T09:41:40.272099Z","end":"2026-03-05T09:41:40.387468Z","steps":["trace[680754166] 'process raft request' (duration: 115.177995ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:41:40.38772Z","caller":"traceutil/trace.go:171","msg":"trace[1953076092] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"113.502226ms","start":"2026-03-05T09:41:40.27421Z","end":"2026-03-05T09:41:40.387712Z","steps":["trace[1953076092] 'process raft request' (duration: 113.185364ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:41:40.388069Z","caller":"traceutil/trace.go:171","msg":"trace[2015899171] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:503; }","duration":"109.969146ms","start":"2026-03-05T09:41:40.278054Z","end":"2026-03-05T09:41:40.388023Z","steps":["trace[2015899171] 'read index received' (duration: 79.077152ms)","trace[2015899171] 'applied index is now lower than readState.Index' (duration: 30.891154ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T09:41:40.388204Z","caller":"traceutil/trace.go:171","msg":"trace[530468900] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"111.968607ms","start":"2026-03-05T09:41:40.276224Z","end":"2026-03-05T09:41:40.388192Z","steps":["trace[530468900] 'process raft request' (duration: 111.370324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:40.388228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.155337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-03-05T09:41:40.388262Z","caller":"traceutil/trace.go:171","msg":"trace[2010256235] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:491; }","duration":"110.219037ms","start":"2026-03-05T09:41:40.278032Z","end":"2026-03-05T09:41:40.388251Z","steps":["trace[2010256235] 'agreement among raft nodes before linearized reading' (duration: 110.085657ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:41:41.601914Z","caller":"traceutil/trace.go:171","msg":"trace[612721421] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"123.820823ms","start":"2026-03-05T09:41:41.478078Z","end":"2026-03-05T09:41:41.601899Z","steps":["trace[612721421] 'process raft request' (duration: 123.710492ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:41:41.846653Z","caller":"traceutil/trace.go:171","msg":"trace[1403940601] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"161.186329ms","start":"2026-03-05T09:41:41.685445Z","end":"2026-03-05T09:41:41.846631Z","steps":["trace[1403940601] 'process raft request' (duration: 71.856674ms)","trace[1403940601] 'compare' (duration: 89.228694ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T09:41:43.076998Z","caller":"traceutil/trace.go:171","msg":"trace[1928584647] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"277.991988ms","start":"2026-03-05T09:41:42.798983Z","end":"2026-03-05T09:41:43.076975Z","steps":["trace[1928584647] 'read index received' (duration: 277.740766ms)","trace[1928584647] 'applied index is now lower than readState.Index' (duration: 250.332µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T09:41:43.077088Z","caller":"traceutil/trace.go:171","msg":"trace[1254732988] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"813.389205ms","start":"2026-03-05T09:41:42.263686Z","end":"2026-03-05T09:41:43.077075Z","steps":["trace[1254732988] 'process raft request' (duration: 813.155314ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:43.077164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.17128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T09:41:43.07723Z","caller":"traceutil/trace.go:171","msg":"trace[1548810121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"278.27623ms","start":"2026-03-05T09:41:42.798929Z","end":"2026-03-05T09:41:43.077205Z","steps":["trace[1548810121] 'agreement among raft nodes before linearized reading' (duration: 278.150479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:43.077412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:42.263668Z","time spent":"813.465116ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T09:41:45.230297Z","caller":"traceutil/trace.go:171","msg":"trace[1457476541] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"145.030922ms","start":"2026-03-05T09:41:45.085237Z","end":"2026-03-05T09:41:45.230268Z","steps":["trace[1457476541] 'process raft request' (duration: 144.80757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:46.341145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.85905ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-05T09:41:46.341343Z","caller":"traceutil/trace.go:171","msg":"trace[1404732888] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"1.045851205s","start":"2026-03-05T09:41:45.295474Z","end":"2026-03-05T09:41:46.341325Z","steps":["trace[1404732888] 'read index received' (duration: 377.733753ms)","trace[1404732888] 'applied index is now lower than readState.Index' (duration: 668.115762ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T09:41:46.341608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:45.231331Z","time spent":"1.110269121s","remote":"127.0.0.1:54666","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-05T09:41:46.341781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046295308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-05T09:41:46.342126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.661909ms","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-05T09:41:46.342119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.897137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T09:41:46.342098Z","caller":"traceutil/trace.go:171","msg":"trace[1079780746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"1.046606391s","start":"2026-03-05T09:41:45.295418Z","end":"2026-03-05T09:41:46.342024Z","steps":["trace[1079780746] 'agreement among raft nodes before linearized reading' (duration: 1.046195768s)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:41:46.342186Z","caller":"traceutil/trace.go:171","msg":"trace[533432769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:510; }","duration":"410.746759ms","start":"2026-03-05T09:41:45.931419Z","end":"2026-03-05T09:41:46.342166Z","steps":["trace[533432769] 'agreement among raft nodes before linearized reading' (duration: 410.638578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:46.342235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:45.931403Z","time spent":"410.824321ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-05T09:41:46.342205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.333082ms","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-05T09:41:46.34226Z","caller":"traceutil/trace.go:171","msg":"trace[1727522922] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:510; }","duration":"669.976117ms","start":"2026-03-05T09:41:45.672195Z","end":"2026-03-05T09:41:46.342172Z","steps":["trace[1727522922] 'agreement among raft nodes before linearized reading' (duration: 669.861246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:46.342267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:45.295394Z","time spent":"1.046810882s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-05T09:41:46.342303Z","caller":"traceutil/trace.go:171","msg":"trace[491739814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"642.475583ms","start":"2026-03-05T09:41:45.699804Z","end":"2026-03-05T09:41:46.342279Z","steps":["trace[491739814] 'agreement among raft nodes before linearized reading' (duration: 642.270251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:46.342359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:45.699788Z","time spent":"642.562876ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-05T09:41:46.342384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:45.672138Z","time spent":"670.23506ms","remote":"127.0.0.1:54730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-03-05T09:41:46.869772Z","caller":"traceutil/trace.go:171","msg":"trace[1526978156] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"111.368497ms","start":"2026-03-05T09:41:46.758354Z","end":"2026-03-05T09:41:46.869722Z","steps":["trace[1526978156] 'process raft request' (duration: 111.161306ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:41:46.869848Z","caller":"traceutil/trace.go:171","msg":"trace[1682633280] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"115.162227ms","start":"2026-03-05T09:41:46.754645Z","end":"2026-03-05T09:41:46.869807Z","steps":["trace[1682633280] 'process raft request' (duration: 114.596603ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:41:51.497051Z","caller":"traceutil/trace.go:171","msg":"trace[154792116] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"127.397221ms","start":"2026-03-05T09:41:51.369631Z","end":"2026-03-05T09:41:51.497028Z","steps":["trace[154792116] 'process raft request' (duration: 127.25586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:52.770252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.623346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T09:41:52.770315Z","caller":"traceutil/trace.go:171","msg":"trace[786144156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"830.730368ms","start":"2026-03-05T09:41:51.939568Z","end":"2026-03-05T09:41:52.770298Z","steps":["trace[786144156] 'range keys from in-memory index tree' (duration: 830.551746ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:52.770353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:51.939545Z","time spent":"830.802148ms","remote":"127.0.0.1:54622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-05T09:41:52.770581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.460274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T09:41:52.770662Z","caller":"traceutil/trace.go:171","msg":"trace[138009510] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"238.937612ms","start":"2026-03-05T09:41:52.531714Z","end":"2026-03-05T09:41:52.770651Z","steps":["trace[138009510] 'read index received' (duration: 53.77µs)","trace[138009510] 'applied index is now lower than readState.Index' (duration: 238.881622ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T09:41:52.770746Z","caller":"traceutil/trace.go:171","msg":"trace[1034869417] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"829.88242ms","start":"2026-03-05T09:41:51.940834Z","end":"2026-03-05T09:41:52.770716Z","steps":["trace[1034869417] 'process raft request' (duration: 247.228984ms)","trace[1034869417] 'compare' (duration: 582.07419ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T09:41:52.770817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.112364ms","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-05T09:41:52.770843Z","caller":"traceutil/trace.go:171","msg":"trace[61207216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:548; }","duration":"239.174875ms","start":"2026-03-05T09:41:52.531662Z","end":"2026-03-05T09:41:52.770837Z","steps":["trace[61207216] 'agreement among raft nodes before linearized reading' (duration: 239.053924ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:52.770868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:51.940816Z","time spent":"829.984551ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T09:41:52.771039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.929453ms","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-05T09:41:52.771069Z","caller":"traceutil/trace.go:171","msg":"trace[1501241310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:548; }","duration":"232.981823ms","start":"2026-03-05T09:41:52.538082Z","end":"2026-03-05T09:41:52.771064Z","steps":["trace[1501241310] 'agreement among raft nodes before linearized reading' (duration: 232.901563ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:52.771257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.151594ms","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-05T09:41:52.771304Z","caller":"traceutil/trace.go:171","msg":"trace[732693165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:548; }","duration":"222.229036ms","start":"2026-03-05T09:41:52.549065Z","end":"2026-03-05T09:41:52.771294Z","steps":["trace[732693165] 'agreement among raft nodes before linearized reading' (duration: 222.103044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:53.298426Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4311806030416251572,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T09:41:53.798794Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4311806030416251572,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T09:41:54.299169Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4311806030416251572,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T09:41:54.490497Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.715545745s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-05T09:41:54.602392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.320503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T09:41:54.602532Z","caller":"traceutil/trace.go:171","msg":"trace[808571361] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.827598226s","start":"2026-03-05T09:41:52.774893Z","end":"2026-03-05T09:41:54.602491Z","steps":["trace[808571361] 'process raft request' (duration: 1.716099711s)","trace[808571361] 'compare' (duration: 111.183322ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T09:41:54.602602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:52.774876Z","time spent":"1.827697087s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T09:41:54.798569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000469124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-05T09:41:54.798722Z","caller":"traceutil/trace.go:171","msg":"trace[1340618571] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000705027s","start":"2026-03-05T09:41:52.797988Z","end":"2026-03-05T09:41:54.798693Z","steps":["trace[1340618571] 'agreement among raft nodes before linearized reading' (duration: 2.000498185s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:54.798794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:52.797957Z","time spent":"2.000819578s","remote":"127.0.0.1:54636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-05T09:41:55.115682Z","caller":"traceutil/trace.go:171","msg":"trace[1311196256] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:569; }","duration":"2.317617851s","start":"2026-03-05T09:41:52.798041Z","end":"2026-03-05T09:41:55.115659Z","steps":["trace[1311196256] 'read index received' (duration: 1.692905399s)","trace[1311196256] 'applied index is now lower than readState.Index' (duration: 624.711372ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T09:41:55.115795Z","caller":"traceutil/trace.go:171","msg":"trace[1319592571] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"2.33949056s","start":"2026-03-05T09:41:52.77628Z","end":"2026-03-05T09:41:55.115771Z","steps":["trace[1319592571] 'process raft request' (duration: 2.339290528s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:55.115901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:52.776266Z","time spent":"2.339554811s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T09:41:55.11589Z","caller":"traceutil/trace.go:171","msg":"trace[555242690] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"2.340260038s","start":"2026-03-05T09:41:52.775588Z","end":"2026-03-05T09:41:55.115848Z","steps":["trace[555242690] 'process raft request' (duration: 2.325492955s)","trace[555242690] 'compare' (duration: 14.174107ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T09:41:55.116129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:52.775549Z","time spent":"2.340438399s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T09:41:55.119071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.617219285s","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-05T09:41:55.119174Z","caller":"traceutil/trace.go:171","msg":"trace[936014579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"1.617366766s","start":"2026-03-05T09:41:53.50178Z","end":"2026-03-05T09:41:55.119147Z","steps":["trace[936014579] 'agreement among raft nodes before linearized reading' (duration: 1.617074612s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:55.11922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:53.501762Z","time spent":"1.617450617s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-05T09:41:55.119505Z","caller":"traceutil/trace.go:171","msg":"trace[1766101878] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"317.337411ms","start":"2026-03-05T09:41:54.802146Z","end":"2026-03-05T09:41:55.119483Z","steps":["trace[1766101878] 'process raft request' (duration: 316.523681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:55.119502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.674881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T09:41:55.119569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:54.802119Z","time spent":"317.422752ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T09:41:55.119587Z","caller":"traceutil/trace.go:171","msg":"trace[964895349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"312.782851ms","start":"2026-03-05T09:41:54.806783Z","end":"2026-03-05T09:41:55.119565Z","steps":["trace[964895349] 'agreement among raft nodes before linearized reading' (duration: 312.65533ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:55.119644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:54.806775Z","time spent":"312.857792ms","remote":"127.0.0.1:54622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-05T09:41:56.518418Z","caller":"traceutil/trace.go:171","msg":"trace[1569011791] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"326.62402ms","start":"2026-03-05T09:41:56.191766Z","end":"2026-03-05T09:41:56.51839Z","steps":["trace[1569011791] 'process raft request' (duration: 326.442218ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:56.518573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:56.191739Z","time spent":"326.755742ms","remote":"127.0.0.1:54818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T09:41:57.586963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.379716ms","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-05T09:41:57.587027Z","caller":"traceutil/trace.go:171","msg":"trace[2078051245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"466.498998ms","start":"2026-03-05T09:41:57.120516Z","end":"2026-03-05T09:41:57.587015Z","steps":["trace[2078051245] 'range keys from in-memory index tree' (duration: 466.162363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:57.587053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:57.120492Z","time spent":"466.556418ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-05T09:41:57.587296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.19267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T09:41:57.587316Z","caller":"traceutil/trace.go:171","msg":"trace[103341972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"787.24661ms","start":"2026-03-05T09:41:56.800065Z","end":"2026-03-05T09:41:57.587312Z","steps":["trace[103341972] 'range keys from in-memory index tree' (duration: 787.109919ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:57.587329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:56.800043Z","time spent":"787.28317ms","remote":"127.0.0.1:54622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-05T09:41:57.58739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.11945ms","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-05T09:41:57.587462Z","caller":"traceutil/trace.go:171","msg":"trace[1993524505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"981.234641ms","start":"2026-03-05T09:41:56.606207Z","end":"2026-03-05T09:41:57.587442Z","steps":["trace[1993524505] 'range keys from in-memory index tree' (duration: 980.940447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:57.587507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:56.606188Z","time spent":"981.307061ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-05T09:41:57.587564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.818441ms","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-05T09:41:57.587603Z","caller":"traceutil/trace.go:171","msg":"trace[1831342475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"466.979393ms","start":"2026-03-05T09:41:57.120613Z","end":"2026-03-05T09:41:57.587592Z","steps":["trace[1831342475] 'range keys from in-memory index tree' (duration: 466.70022ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:57.587618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:57.120532Z","time spent":"467.081455ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-05T09:41:57.587685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.581704ms","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-05T09:41:57.587803Z","caller":"traceutil/trace.go:171","msg":"trace[1810277913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"417.755527ms","start":"2026-03-05T09:41:57.170019Z","end":"2026-03-05T09:41:57.587774Z","steps":["trace[1810277913] 'range keys from in-memory index tree' (duration: 417.381922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:57.587856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:57.169986Z","time spent":"417.858707ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-05T09:41:58.719661Z","caller":"traceutil/trace.go:171","msg":"trace[823498720] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.127261736s","start":"2026-03-05T09:41:57.592376Z","end":"2026-03-05T09:41:58.719637Z","steps":["trace[823498720] 'process raft request' (duration: 1.127098524s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:58.719969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:57.592343Z","time spent":"1.127506709s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T09:41:58.784431Z","caller":"traceutil/trace.go:171","msg":"trace[1051646682] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.190742281s","start":"2026-03-05T09:41:57.59364Z","end":"2026-03-05T09:41:58.784382Z","steps":["trace[1051646682] 'process raft request' (duration: 1.19064658s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:58.784586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:57.593633Z","time spent":"1.190870452s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T09:41:58.784724Z","caller":"traceutil/trace.go:171","msg":"trace[1917917231] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"884.075937ms","start":"2026-03-05T09:41:57.900635Z","end":"2026-03-05T09:41:58.78471Z","steps":["trace[1917917231] 'process raft request' (duration: 883.972696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:58.784823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:57.900599Z","time spent":"884.190258ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T09:41:58.784852Z","caller":"traceutil/trace.go:171","msg":"trace[2006629702] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.19074567s","start":"2026-03-05T09:41:57.594094Z","end":"2026-03-05T09:41:58.78484Z","steps":["trace[2006629702] 'process raft request' (duration: 1.190243665s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:58.784982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:57.594085Z","time spent":"1.190844982s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T09:41:58.784437Z","caller":"traceutil/trace.go:171","msg":"trace[265729120] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.191701971s","start":"2026-03-05T09:41:57.592698Z","end":"2026-03-05T09:41:58.7844Z","steps":["trace[265729120] 'process raft request' (duration: 1.191444978s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:41:58.785264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:41:57.592676Z","time spent":"1.19239922s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T09:42:00.791332Z","caller":"traceutil/trace.go:171","msg":"trace[298902914] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"473.864988ms","start":"2026-03-05T09:42:00.317436Z","end":"2026-03-05T09:42:00.791301Z","steps":["trace[298902914] 'process raft request' (duration: 470.934301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:42:00.791494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:42:00.317428Z","time spent":"473.987691ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T09:42:00.792044Z","caller":"traceutil/trace.go:171","msg":"trace[1315826177] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"467.815722ms","start":"2026-03-05T09:42:00.324218Z","end":"2026-03-05T09:42:00.792034Z","steps":["trace[1315826177] 'process raft request' (duration: 467.63973ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:42:00.792112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:42:00.324205Z","time spent":"467.865223ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T09:42:00.791332Z","caller":"traceutil/trace.go:171","msg":"trace[1892051764] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"471.978334ms","start":"2026-03-05T09:42:00.319331Z","end":"2026-03-05T09:42:00.79131Z","steps":["trace[1892051764] 'process raft request' (duration: 471.902833ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:42:00.79374Z","caller":"traceutil/trace.go:171","msg":"trace[2079794503] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"287.214461ms","start":"2026-03-05T09:42:00.506505Z","end":"2026-03-05T09:42:00.793719Z","steps":["trace[2079794503] 'process raft request' (duration: 285.48965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:42:00.793758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:42:00.319318Z","time spent":"474.244533ms","remote":"127.0.0.1:54676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T09:43:35.54374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.539516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T09:43:35.543955Z","caller":"traceutil/trace.go:171","msg":"trace[993054872] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"221.493249ms","start":"2026-03-05T09:43:35.322443Z","end":"2026-03-05T09:43:35.543937Z","steps":["trace[993054872] 'process raft request' (duration: 221.431387ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:43:35.543993Z","caller":"traceutil/trace.go:171","msg":"trace[609927447] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"223.969779ms","start":"2026-03-05T09:43:35.319997Z","end":"2026-03-05T09:43:35.543967Z","steps":["trace[609927447] 'process raft request' (duration: 20.085293ms)","trace[609927447] 'compare' (duration: 203.412302ms)"],"step_count":2}