{"level":"warn","ts":"2026-03-30T17:54:39.688346Z","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-30T17:54:39.688444Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.179: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.179:2380","--initial-cluster=instance=https://199.19.213.179:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.179:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.179: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-30T17:54:39.688524Z","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-30T17:54:39.688536Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.179:2380"]} {"level":"info","ts":"2026-03-30T17:54:39.688557Z","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-30T17:54:39.689335Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.179:2379"]} {"level":"info","ts":"2026-03-30T17:54:39.689455Z","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.179:2380"],"listen-peer-urls":["https://199.19.213.179:2380"],"advertise-client-urls":["https://199.19.213.179:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.179:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.179: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-30T17:54:39.694831Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.116442ms"} {"level":"info","ts":"2026-03-30T17:54:39.703927Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"69895f7c3b831156","cluster-id":"caac76e5ff14ceff"} {"level":"info","ts":"2026-03-30T17:54:39.704048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 switched to configuration voters=()"} {"level":"info","ts":"2026-03-30T17:54:39.70408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became follower at term 0"} {"level":"info","ts":"2026-03-30T17:54:39.704099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 69895f7c3b831156 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-30T17:54:39.70411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became follower at term 1"} {"level":"info","ts":"2026-03-30T17:54:39.704166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 switched to configuration voters=(7604714432970821974)"} {"level":"warn","ts":"2026-03-30T17:54:39.718566Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-30T17:54:39.720128Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-30T17:54:39.721215Z","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-30T17:54:39.723375Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"69895f7c3b831156","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-30T17:54:39.723896Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"69895f7c3b831156","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-30T17:54:39.723961Z","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-30T17:54:39.723997Z","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-30T17:54:39.724006Z","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-30T17:54:39.726481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 switched to configuration voters=(7604714432970821974)"} {"level":"info","ts":"2026-03-30T17:54:39.726618Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"caac76e5ff14ceff","local-member-id":"69895f7c3b831156","added-peer-id":"69895f7c3b831156","added-peer-peer-urls":["https://199.19.213.179:2380"]} {"level":"info","ts":"2026-03-30T17:54:39.729882Z","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-30T17:54:39.730075Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.179:2380"} {"level":"info","ts":"2026-03-30T17:54:39.730166Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.179:2380"} {"level":"info","ts":"2026-03-30T17:54:39.730263Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"69895f7c3b831156","initial-advertise-peer-urls":["https://199.19.213.179:2380"],"listen-peer-urls":["https://199.19.213.179:2380"],"advertise-client-urls":["https://199.19.213.179:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.179:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-30T17:54:39.730354Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-30T17:54:40.604483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 is starting a new election at term 1"} {"level":"info","ts":"2026-03-30T17:54:40.604548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-30T17:54:40.604571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 received MsgPreVoteResp from 69895f7c3b831156 at term 1"} {"level":"info","ts":"2026-03-30T17:54:40.60459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became candidate at term 2"} {"level":"info","ts":"2026-03-30T17:54:40.604602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 received MsgVoteResp from 69895f7c3b831156 at term 2"} {"level":"info","ts":"2026-03-30T17:54:40.604617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became leader at term 2"} {"level":"info","ts":"2026-03-30T17:54:40.604631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 69895f7c3b831156 elected leader 69895f7c3b831156 at term 2"} {"level":"info","ts":"2026-03-30T17:54:40.605637Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"69895f7c3b831156","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.179:2379]}","request-path":"/0/members/69895f7c3b831156/attributes","cluster-id":"caac76e5ff14ceff","publish-timeout":"7s"} {"level":"info","ts":"2026-03-30T17:54:40.605789Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-30T17:54:40.605857Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-30T17:54:40.60594Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:54:40.606442Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-30T17:54:40.60655Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-30T17:54:40.606819Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"caac76e5ff14ceff","local-member-id":"69895f7c3b831156","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:54:40.606899Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:54:40.606924Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:54:40.609304Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.179:2379"} {"level":"info","ts":"2026-03-30T17:54:40.610665Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-30T17:54:44.789645Z","caller":"traceutil/trace.go:171","msg":"trace[346780090] linearizableReadLoop","detail":"{readStateIndex:218; appliedIndex:217; }","duration":"188.693458ms","start":"2026-03-30T17:54:44.600927Z","end":"2026-03-30T17:54:44.78962Z","steps":["trace[346780090] 'read index received' (duration: 188.570395ms)","trace[346780090] 'applied index is now lower than readState.Index' (duration: 121.833µs)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:54:44.789857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.910794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:54:44.789921Z","caller":"traceutil/trace.go:171","msg":"trace[1990092916] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:213; }","duration":"189.034558ms","start":"2026-03-30T17:54:44.600873Z","end":"2026-03-30T17:54:44.789907Z","steps":["trace[1990092916] 'agreement among raft nodes before linearized reading' (duration: 188.925684ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:54:44.78979Z","caller":"traceutil/trace.go:171","msg":"trace[1438320879] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"225.446208ms","start":"2026-03-30T17:54:44.564307Z","end":"2026-03-30T17:54:44.789753Z","steps":["trace[1438320879] 'process raft request' (duration: 225.17089ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:54:45.058826Z","caller":"traceutil/trace.go:171","msg":"trace[1215574404] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"264.780532ms","start":"2026-03-30T17:54:44.794006Z","end":"2026-03-30T17:54:45.058786Z","steps":["trace[1215574404] 'process raft request' (duration: 264.542734ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:54:45.067755Z","caller":"traceutil/trace.go:171","msg":"trace[427290261] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"259.250898ms","start":"2026-03-30T17:54:44.808476Z","end":"2026-03-30T17:54:45.067727Z","steps":["trace[427290261] 'process raft request' (duration: 259.174766ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:54:45.067827Z","caller":"traceutil/trace.go:171","msg":"trace[2000955223] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"264.336679ms","start":"2026-03-30T17:54:44.803445Z","end":"2026-03-30T17:54:45.067782Z","steps":["trace[2000955223] 'process raft request' (duration: 264.067372ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:55:09.043495Z","caller":"traceutil/trace.go:171","msg":"trace[462881501] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"198.063397ms","start":"2026-03-30T17:55:08.845399Z","end":"2026-03-30T17:55:09.043462Z","steps":["trace[462881501] 'process raft request' (duration: 192.059641ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:55:09.043806Z","caller":"traceutil/trace.go:171","msg":"trace[5972949] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"102.192486ms","start":"2026-03-30T17:55:08.941589Z","end":"2026-03-30T17:55:09.043781Z","steps":["trace[5972949] 'process raft request' (duration: 102.126264ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:55:09.044075Z","caller":"traceutil/trace.go:171","msg":"trace[2027744663] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"183.231677ms","start":"2026-03-30T17:55:08.860828Z","end":"2026-03-30T17:55:09.044059Z","steps":["trace[2027744663] 'process raft request' (duration: 182.63409ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:55:09.044055Z","caller":"traceutil/trace.go:171","msg":"trace[769899894] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"193.642926ms","start":"2026-03-30T17:55:08.850379Z","end":"2026-03-30T17:55:09.044022Z","steps":["trace[769899894] 'process raft request' (duration: 192.998258ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:55:09.044279Z","caller":"traceutil/trace.go:171","msg":"trace[1730233959] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"172.046356ms","start":"2026-03-30T17:55:08.871947Z","end":"2026-03-30T17:55:09.043993Z","steps":["trace[1730233959] 'process raft request' (duration: 171.698376ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:55:15.600309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.084988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:55:15.600388Z","caller":"traceutil/trace.go:171","msg":"trace[1528610122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"216.220392ms","start":"2026-03-30T17:55:15.384151Z","end":"2026-03-30T17:55:15.600371Z","steps":["trace[1528610122] 'range keys from in-memory index tree' (duration: 215.898764ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:55:15.600602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.644332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-30T17:55:15.600819Z","caller":"traceutil/trace.go:171","msg":"trace[1471687133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"110.959391ms","start":"2026-03-30T17:55:15.489834Z","end":"2026-03-30T17:55:15.600794Z","steps":["trace[1471687133] 'range keys from in-memory index tree' (duration: 110.317353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:55:16.119955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.444809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:55:16.12024Z","caller":"traceutil/trace.go:171","msg":"trace[2122902449] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"447.381899ms","start":"2026-03-30T17:55:15.672818Z","end":"2026-03-30T17:55:16.1202Z","steps":["trace[2122902449] 'process raft request' (duration: 324.044025ms)","trace[2122902449] 'compare' (duration: 122.250784ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:55:16.120434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:55:15.672778Z","time spent":"447.551094ms","remote":"127.0.0.1:34224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:55:16.120623Z","caller":"traceutil/trace.go:171","msg":"trace[248276090] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"417.646286ms","start":"2026-03-30T17:55:15.702957Z","end":"2026-03-30T17:55:16.120604Z","steps":["trace[248276090] 'process raft request' (duration: 417.141461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:55:16.120699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:55:15.702936Z","time spent":"417.725888ms","remote":"127.0.0.1:34258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-30T17:56:06.101797Z","caller":"traceutil/trace.go:171","msg":"trace[998506064] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"161.947195ms","start":"2026-03-30T17:56:05.939822Z","end":"2026-03-30T17:56:06.101769Z","steps":["trace[998506064] 'process raft request' (duration: 160.296591ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:56:30.02305Z","caller":"traceutil/trace.go:171","msg":"trace[596614056] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"155.615903ms","start":"2026-03-30T17:56:29.867405Z","end":"2026-03-30T17:56:30.023021Z","steps":["trace[596614056] 'process raft request' (duration: 155.463209ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:56:30.95437Z","caller":"traceutil/trace.go:171","msg":"trace[1047962269] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"104.583892ms","start":"2026-03-30T17:56:30.849754Z","end":"2026-03-30T17:56:30.954338Z","steps":["trace[1047962269] 'read index received' (duration: 104.304725ms)","trace[1047962269] 'applied index is now lower than readState.Index' (duration: 277.957µs)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:56:30.954451Z","caller":"traceutil/trace.go:171","msg":"trace[1371434280] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"236.707789ms","start":"2026-03-30T17:56:30.717706Z","end":"2026-03-30T17:56:30.954413Z","steps":["trace[1371434280] 'process raft request' (duration: 236.436182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:56:30.954873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.097456ms","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-30T17:56:30.955065Z","caller":"traceutil/trace.go:171","msg":"trace[1305818217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1077; }","duration":"105.311882ms","start":"2026-03-30T17:56:30.849731Z","end":"2026-03-30T17:56:30.955043Z","steps":["trace[1305818217] 'agreement among raft nodes before linearized reading' (duration: 104.736447ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:56:44.248069Z","caller":"traceutil/trace.go:171","msg":"trace[2022341494] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"154.861253ms","start":"2026-03-30T17:56:44.093182Z","end":"2026-03-30T17:56:44.248043Z","steps":["trace[2022341494] 'process raft request' (duration: 154.659818ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:00.893116Z","caller":"traceutil/trace.go:171","msg":"trace[1907076643] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"225.426044ms","start":"2026-03-30T17:57:00.667639Z","end":"2026-03-30T17:57:00.893065Z","steps":["trace[1907076643] 'process raft request' (duration: 225.169557ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:01.092355Z","caller":"traceutil/trace.go:171","msg":"trace[1517702614] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"190.033426ms","start":"2026-03-30T17:57:00.902295Z","end":"2026-03-30T17:57:01.092328Z","steps":["trace[1517702614] 'process raft request' (duration: 189.877802ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:02.519533Z","caller":"traceutil/trace.go:171","msg":"trace[86577908] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"119.824254ms","start":"2026-03-30T17:57:02.399681Z","end":"2026-03-30T17:57:02.519505Z","steps":["trace[86577908] 'process raft request' (duration: 114.010556ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:05.625502Z","caller":"traceutil/trace.go:171","msg":"trace[920874546] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"379.138855ms","start":"2026-03-30T17:57:05.24634Z","end":"2026-03-30T17:57:05.625478Z","steps":["trace[920874546] 'read index received' (duration: 378.919819ms)","trace[920874546] 'applied index is now lower than readState.Index' (duration: 217.836µs)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:57:05.625603Z","caller":"traceutil/trace.go:171","msg":"trace[693946404] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"485.653359ms","start":"2026-03-30T17:57:05.139909Z","end":"2026-03-30T17:57:05.625562Z","steps":["trace[693946404] 'process raft request' (duration: 485.421802ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:05.625804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.406782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-30T17:57:05.62585Z","caller":"traceutil/trace.go:171","msg":"trace[1988035352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1282; }","duration":"379.539436ms","start":"2026-03-30T17:57:05.246297Z","end":"2026-03-30T17:57:05.625837Z","steps":["trace[1988035352] 'agreement among raft nodes before linearized reading' (duration: 379.343951ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:05.625886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:05.246278Z","time spent":"379.598397ms","remote":"127.0.0.1:34284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-30T17:57:05.625896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:05.139883Z","time spent":"485.829484ms","remote":"127.0.0.1:34284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:57:05.626275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.500899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-30T17:57:05.626353Z","caller":"traceutil/trace.go:171","msg":"trace[783207568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1282; }","duration":"364.623172ms","start":"2026-03-30T17:57:05.261708Z","end":"2026-03-30T17:57:05.626331Z","steps":["trace[783207568] 'agreement among raft nodes before linearized reading' (duration: 364.086738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:05.626397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:05.261692Z","time spent":"364.695714ms","remote":"127.0.0.1:34284","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-30T17:57:06.861842Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1249358844344787695,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T17:57:07.362993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1249358844344787695,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T17:57:07.876769Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1249358844344787695,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T17:57:08.37718Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1249358844344787695,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T17:57:08.379384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000553996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-30T17:57:08.379446Z","caller":"traceutil/trace.go:171","msg":"trace[939203574] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000652799s","start":"2026-03-30T17:57:06.378781Z","end":"2026-03-30T17:57:08.379434Z","steps":["trace[939203574] 'agreement among raft nodes before linearized reading' (duration: 2.000568637s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:08.379495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:06.378773Z","time spent":"2.000715741s","remote":"127.0.0.1:34026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-30T17:57:08.877829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1249358844344787695,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T17:57:09.378754Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1249358844344787695,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T17:57:09.87923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1249358844344787695,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T17:57:10.379938Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1249358844344787695,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T17:57:10.880307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1249358844344787695,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T17:57:10.977313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999765192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T17:57:10.977378Z","caller":"traceutil/trace.go:171","msg":"trace[1813302052] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999850354s","start":"2026-03-30T17:57:08.977508Z","end":"2026-03-30T17:57:10.977359Z","steps":["trace[1813302052] 'agreement among raft nodes before linearized reading' (duration: 1.999769442s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:10.977432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:08.977505Z","time spent":"1.999918576s","remote":"127.0.0.1:34020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/30 17:57:10 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T17:57:11.048555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.131847ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:57:11.0487Z","caller":"traceutil/trace.go:171","msg":"trace[1036543190] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1284; }","duration":"483.288191ms","start":"2026-03-30T17:57:10.565399Z","end":"2026-03-30T17:57:11.048687Z","steps":["trace[1036543190] 'range keys from in-memory index tree' (duration: 483.119927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:11.051119Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.705549995s","expected-duration":"1s"} {"level":"info","ts":"2026-03-30T17:57:11.051422Z","caller":"traceutil/trace.go:171","msg":"trace[920069076] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"4.705936115s","start":"2026-03-30T17:57:06.345469Z","end":"2026-03-30T17:57:11.051405Z","steps":["trace[920069076] 'process raft request' (duration: 4.705833012s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:11.051519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:06.345446Z","time spent":"4.706021726s","remote":"127.0.0.1:34284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:57:11.062433Z","caller":"traceutil/trace.go:171","msg":"trace[671338292] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"2.98426661s","start":"2026-03-30T17:57:08.078111Z","end":"2026-03-30T17:57:11.062378Z","steps":["trace[671338292] 'process raft request' (duration: 2.984060995s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:11.062557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:08.078087Z","time spent":"2.984402974s","remote":"127.0.0.1:34284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:57:11.065559Z","caller":"traceutil/trace.go:171","msg":"trace[2077027759] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"1.33920084s","start":"2026-03-30T17:57:09.726346Z","end":"2026-03-30T17:57:11.065547Z","steps":["trace[2077027759] 'process raft request' (duration: 1.339082888s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:11.065617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:09.726322Z","time spent":"1.339268173s","remote":"127.0.0.1:34108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":886,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-30T17:57:11.066164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.704793265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-30T17:57:11.066218Z","caller":"traceutil/trace.go:171","msg":"trace[234670835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1287; }","duration":"4.704865067s","start":"2026-03-30T17:57:06.361343Z","end":"2026-03-30T17:57:11.066208Z","steps":["trace[234670835] 'agreement among raft nodes before linearized reading' (duration: 4.704707334s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:11.066243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:06.361333Z","time spent":"4.704903558s","remote":"127.0.0.1:34284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-30T17:57:11.065592Z","caller":"traceutil/trace.go:171","msg":"trace[719308154] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1332; }","duration":"4.704203941s","start":"2026-03-30T17:57:06.361369Z","end":"2026-03-30T17:57:11.065573Z","steps":["trace[719308154] 'read index received' (duration: 4.690027154s)","trace[719308154] 'applied index is now lower than readState.Index' (duration: 14.175277ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:57:11.06671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.42345421s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-30T17:57:11.066739Z","caller":"traceutil/trace.go:171","msg":"trace[484304411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1287; }","duration":"3.423498302s","start":"2026-03-30T17:57:07.643234Z","end":"2026-03-30T17:57:11.066732Z","steps":["trace[484304411] 'agreement among raft nodes before linearized reading' (duration: 3.42342268s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:11.066759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:07.643229Z","time spent":"3.423525353s","remote":"127.0.0.1:34284","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-30T17:57:11.066818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.013422629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-30T17:57:11.066824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.850979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-30T17:57:11.066869Z","caller":"traceutil/trace.go:171","msg":"trace[794378759] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1287; }","duration":"3.013525241s","start":"2026-03-30T17:57:08.053329Z","end":"2026-03-30T17:57:11.066854Z","steps":["trace[794378759] 'agreement among raft nodes before linearized reading' (duration: 3.013408958s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:11.066881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.089388589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-03-30T17:57:11.066908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:08.053306Z","time spent":"3.013592302s","remote":"127.0.0.1:50088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-03-30T17:57:11.066928Z","caller":"traceutil/trace.go:171","msg":"trace[1035324455] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1287; }","duration":"2.08944893s","start":"2026-03-30T17:57:08.977457Z","end":"2026-03-30T17:57:11.066906Z","steps":["trace[1035324455] 'agreement among raft nodes before linearized reading' (duration: 2.089327877s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:11.066957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:08.977441Z","time spent":"2.089508212s","remote":"127.0.0.1:34284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-30T17:57:11.066961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.88050312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-30T17:57:11.066974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382568171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-30T17:57:11.067003Z","caller":"traceutil/trace.go:171","msg":"trace[1005104530] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1287; }","duration":"1.382640253s","start":"2026-03-30T17:57:09.684355Z","end":"2026-03-30T17:57:11.066995Z","steps":["trace[1005104530] 'agreement among raft nodes before linearized reading' (duration: 1.382564751s)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:11.066901Z","caller":"traceutil/trace.go:171","msg":"trace[1873677110] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1287; }","duration":"409.948852ms","start":"2026-03-30T17:57:10.656934Z","end":"2026-03-30T17:57:11.066883Z","steps":["trace[1873677110] 'agreement among raft nodes before linearized reading' (duration: 409.764707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:11.067099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:09.684326Z","time spent":"1.382709896s","remote":"127.0.0.1:34284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-30T17:57:11.067183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.99059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:57:11.067206Z","caller":"traceutil/trace.go:171","msg":"trace[1507081858] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1287; }","duration":"502.036791ms","start":"2026-03-30T17:57:10.56516Z","end":"2026-03-30T17:57:11.067197Z","steps":["trace[1507081858] 'agreement among raft nodes before linearized reading' (duration: 501.99215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:11.067214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:10.656898Z","time spent":"410.213319ms","remote":"127.0.0.1:34146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-03-30T17:57:11.067305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.436951955s","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-30T17:57:11.066997Z","caller":"traceutil/trace.go:171","msg":"trace[515950544] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1287; }","duration":"2.880847998s","start":"2026-03-30T17:57:08.18614Z","end":"2026-03-30T17:57:11.066988Z","steps":["trace[515950544] 'agreement among raft nodes before linearized reading' (duration: 2.880528341s)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:11.067336Z","caller":"traceutil/trace.go:171","msg":"trace[1503775001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1287; }","duration":"3.436999056s","start":"2026-03-30T17:57:07.630328Z","end":"2026-03-30T17:57:11.067327Z","steps":["trace[1503775001] 'agreement among raft nodes before linearized reading' (duration: 3.436914063s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:11.067414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:07.630316Z","time spent":"3.437090478s","remote":"127.0.0.1:34284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-30T17:57:11.067436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.544673122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-03-30T17:57:11.067457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.427993773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-03-30T17:57:11.067476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.596152544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:57:11.067487Z","caller":"traceutil/trace.go:171","msg":"trace[1837452007] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1287; }","duration":"3.544746553s","start":"2026-03-30T17:57:07.522732Z","end":"2026-03-30T17:57:11.067479Z","steps":["trace[1837452007] 'agreement among raft nodes before linearized reading' (duration: 3.544652241s)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:11.067494Z","caller":"traceutil/trace.go:171","msg":"trace[199462918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1287; }","duration":"3.428040863s","start":"2026-03-30T17:57:07.639444Z","end":"2026-03-30T17:57:11.067485Z","steps":["trace[199462918] 'agreement among raft nodes before linearized reading' (duration: 3.427950681s)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:11.067507Z","caller":"traceutil/trace.go:171","msg":"trace[629728787] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1287; }","duration":"1.596211406s","start":"2026-03-30T17:57:09.471287Z","end":"2026-03-30T17:57:11.067499Z","steps":["trace[629728787] 'agreement among raft nodes before linearized reading' (duration: 1.596160384s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:11.067525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:07.522719Z","time spent":"3.544785005s","remote":"127.0.0.1:34284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-30T17:57:11.067526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:07.639439Z","time spent":"3.428077524s","remote":"127.0.0.1:34284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-30T17:57:11.067532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:09.471272Z","time spent":"1.596252606s","remote":"127.0.0.1:50090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-03-30T17:57:11.067376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:08.186123Z","time spent":"2.881235999s","remote":"127.0.0.1:50010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-03-30T17:57:11.067227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:10.565145Z","time spent":"502.075812ms","remote":"127.0.0.1:50054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-03-30T17:57:11.069201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.671738251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:57:11.069247Z","caller":"traceutil/trace.go:171","msg":"trace[1502995429] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1287; }","duration":"1.674347906s","start":"2026-03-30T17:57:09.39488Z","end":"2026-03-30T17:57:11.069228Z","steps":["trace[1502995429] 'agreement among raft nodes before linearized reading' (duration: 1.671751362s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:11.069275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:09.394861Z","time spent":"1.674406006s","remote":"127.0.0.1:50020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-03-30T17:57:11.069465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.663352054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-30T17:57:11.069488Z","caller":"traceutil/trace.go:171","msg":"trace[528620782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1287; }","duration":"4.663402276s","start":"2026-03-30T17:57:06.406078Z","end":"2026-03-30T17:57:11.06948Z","steps":["trace[528620782] 'agreement among raft nodes before linearized reading' (duration: 4.660450143s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:11.069507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:06.406062Z","time spent":"4.663440357s","remote":"127.0.0.1:34284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-30T17:57:31.752869Z","caller":"traceutil/trace.go:171","msg":"trace[404618890] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"149.250671ms","start":"2026-03-30T17:57:31.603592Z","end":"2026-03-30T17:57:31.752843Z","steps":["trace[404618890] 'process raft request' (duration: 147.60298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:31.752885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.273479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-30T17:57:31.753083Z","caller":"traceutil/trace.go:171","msg":"trace[1253490145] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1405; }","duration":"392.534585ms","start":"2026-03-30T17:57:31.36052Z","end":"2026-03-30T17:57:31.753054Z","steps":["trace[1253490145] 'range keys from in-memory index tree' (duration: 392.038383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:31.753133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:31.360495Z","time spent":"392.626337ms","remote":"127.0.0.1:34284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "}