{"level":"warn","ts":"2026-05-11T12:17:51.923716Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T12:17:51.923811Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-11T12:17:51.923868Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T12:17:51.923881Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-05-11T12:17:51.923902Z","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-05-11T12:17:51.924514Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-05-11T12:17:51.924585Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-11T12:17:51.928023Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.255587ms"} {"level":"info","ts":"2026-05-11T12:17:51.933535Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-05-11T12:17:51.933659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T12:17:51.933683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-05-11T12:17:51.933697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T12:17:51.933707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-05-11T12:17:51.933735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-05-11T12:17:51.939087Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T12:17:51.94027Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T12:17:51.941316Z","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-05-11T12:17:51.943031Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T12:17:51.943178Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T12:17:51.943823Z","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-05-11T12:17:51.943922Z","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-05-11T12:17:51.943932Z","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-05-11T12:17:51.945134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-05-11T12:17:51.945268Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-05-11T12:17:51.947469Z","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-05-11T12:17:51.947567Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-05-11T12:17:51.947616Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-05-11T12:17:51.947675Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T12:17:51.947716Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T12:17:52.933881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T12:17:52.93395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T12:17:52.933985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-05-11T12:17:52.934007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-05-11T12:17:52.934019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-05-11T12:17:52.934048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-05-11T12:17:52.934062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-05-11T12:17:52.940343Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T12:17:52.946784Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T12:17:52.946888Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T12:17:52.946915Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T12:17:52.946947Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T12:17:52.947061Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T12:17:52.94736Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T12:17:52.949293Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T12:17:52.949341Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T12:17:52.953389Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-05-11T12:17:52.954174Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T12:18:18.508526Z","caller":"traceutil/trace.go:171","msg":"trace[962934861] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"104.990123ms","start":"2026-05-11T12:18:18.403517Z","end":"2026-05-11T12:18:18.508507Z","steps":["trace[962934861] 'process raft request' (duration: 88.684686ms)","trace[962934861] 'compare' (duration: 16.197085ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:18:19.025294Z","caller":"traceutil/trace.go:171","msg":"trace[1587372737] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"145.20835ms","start":"2026-05-11T12:18:18.880066Z","end":"2026-05-11T12:18:19.025275Z","steps":["trace[1587372737] 'process raft request' (duration: 129.298571ms)","trace[1587372737] 'compare' (duration: 15.807576ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:18:19.038094Z","caller":"traceutil/trace.go:171","msg":"trace[1155478796] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"127.871408ms","start":"2026-05-11T12:18:18.910195Z","end":"2026-05-11T12:18:19.038066Z","steps":["trace[1155478796] 'process raft request' (duration: 127.54697ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:18:24.833747Z","caller":"traceutil/trace.go:171","msg":"trace[396212564] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"281.816645ms","start":"2026-05-11T12:18:24.551885Z","end":"2026-05-11T12:18:24.833702Z","steps":["trace[396212564] 'read index received' (duration: 279.646433ms)","trace[396212564] 'applied index is now lower than readState.Index' (duration: 2.168672ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:18:24.834834Z","caller":"traceutil/trace.go:171","msg":"trace[634217707] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"400.778371ms","start":"2026-05-11T12:18:24.434012Z","end":"2026-05-11T12:18:24.83479Z","steps":["trace[634217707] 'process raft request' (duration: 397.995995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:18:24.834961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.414338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-11T12:18:24.834938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.033004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-11T12:18:24.835001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.897339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lm6v9\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-11T12:18:24.835051Z","caller":"traceutil/trace.go:171","msg":"trace[232275230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"226.537461ms","start":"2026-05-11T12:18:24.608493Z","end":"2026-05-11T12:18:24.835031Z","steps":["trace[232275230] 'agreement among raft nodes before linearized reading' (duration: 226.308596ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:18:24.83509Z","caller":"traceutil/trace.go:171","msg":"trace[349873077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:562; }","duration":"283.205858ms","start":"2026-05-11T12:18:24.551843Z","end":"2026-05-11T12:18:24.835049Z","steps":["trace[349873077] 'agreement among raft nodes before linearized reading' (duration: 282.930071ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:18:24.835093Z","caller":"traceutil/trace.go:171","msg":"trace[1769301216] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lm6v9; range_end:; response_count:1; response_revision:562; }","duration":"179.005582ms","start":"2026-05-11T12:18:24.656061Z","end":"2026-05-11T12:18:24.835067Z","steps":["trace[1769301216] 'agreement among raft nodes before linearized reading' (duration: 178.737216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:18:24.835983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:18:24.433981Z","time spent":"401.114309ms","remote":"127.0.0.1:53992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T12:18:25.137322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.290196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T12:18:25.137436Z","caller":"traceutil/trace.go:171","msg":"trace[1834456020] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"295.94727ms","start":"2026-05-11T12:18:24.841459Z","end":"2026-05-11T12:18:25.137406Z","steps":["trace[1834456020] 'process raft request' (duration: 29.357017ms)","trace[1834456020] 'compare' (duration: 266.197634ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:18:25.30198Z","caller":"traceutil/trace.go:171","msg":"trace[758204326] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:582; }","duration":"178.646634ms","start":"2026-05-11T12:18:25.123313Z","end":"2026-05-11T12:18:25.30196Z","steps":["trace[758204326] 'read index received' (duration: 118.535587ms)","trace[758204326] 'applied index is now lower than readState.Index' (duration: 60.108547ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:18:25.302111Z","caller":"traceutil/trace.go:171","msg":"trace[1170655131] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"451.917754ms","start":"2026-05-11T12:18:24.850178Z","end":"2026-05-11T12:18:25.302096Z","steps":["trace[1170655131] 'process raft request' (duration: 451.652138ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:18:25.302138Z","caller":"traceutil/trace.go:171","msg":"trace[881699493] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"459.68465ms","start":"2026-05-11T12:18:24.842436Z","end":"2026-05-11T12:18:25.302121Z","steps":["trace[881699493] 'process raft request' (duration: 399.406838ms)","trace[881699493] 'compare' (duration: 59.845422ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:18:25.302193Z","caller":"traceutil/trace.go:171","msg":"trace[1861246937] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"402.699206ms","start":"2026-05-11T12:18:24.899485Z","end":"2026-05-11T12:18:25.302184Z","steps":["trace[1861246937] 'process raft request' (duration: 402.40088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:18:25.302193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:18:24.850171Z","time spent":"451.971496ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T12:18:25.302222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:18:24.842415Z","time spent":"459.770772ms","remote":"127.0.0.1:54188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T12:18:25.302253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:18:24.899465Z","time spent":"402.749857ms","remote":"127.0.0.1:54450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T12:18:25.302157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.823368ms","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-05-11T12:18:25.302325Z","caller":"traceutil/trace.go:171","msg":"trace[1691651811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:567; }","duration":"179.016243ms","start":"2026-05-11T12:18:25.123298Z","end":"2026-05-11T12:18:25.302315Z","steps":["trace[1691651811] 'agreement among raft nodes before linearized reading' (duration: 178.740926ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:19:59.075067Z","caller":"traceutil/trace.go:171","msg":"trace[283934199] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"116.640151ms","start":"2026-05-11T12:19:58.958401Z","end":"2026-05-11T12:19:59.075041Z","steps":["trace[283934199] 'process raft request' (duration: 75.245364ms)","trace[283934199] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/csidrivers/rbd.csi.ceph.com; req_size:904; } (duration: 41.210412ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:19:59.075063Z","caller":"traceutil/trace.go:171","msg":"trace[129430853] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"115.744649ms","start":"2026-05-11T12:19:58.959294Z","end":"2026-05-11T12:19:59.075038Z","steps":["trace[129430853] 'process raft request' (duration: 115.679508ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:20:02.214066Z","caller":"traceutil/trace.go:171","msg":"trace[1481018503] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"105.365613ms","start":"2026-05-11T12:20:02.108674Z","end":"2026-05-11T12:20:02.214039Z","steps":["trace[1481018503] 'process raft request' (duration: 104.954652ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:20:41.105604Z","caller":"traceutil/trace.go:171","msg":"trace[1951594661] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"196.373083ms","start":"2026-05-11T12:20:40.909208Z","end":"2026-05-11T12:20:41.105581Z","steps":["trace[1951594661] 'process raft request' (duration: 196.223079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:20:41.439787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.157126ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-11T12:20:41.605326Z","caller":"traceutil/trace.go:171","msg":"trace[96785915] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"161.785728ms","start":"2026-05-11T12:20:41.4435Z","end":"2026-05-11T12:20:41.605285Z","steps":["trace[96785915] 'process raft request' (duration: 161.40622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:20:41.60564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.538807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T12:20:41.605128Z","caller":"traceutil/trace.go:171","msg":"trace[744009717] linearizableReadLoop","detail":"{readStateIndex:1440; appliedIndex:1439; }","duration":"152.018505ms","start":"2026-05-11T12:20:41.453088Z","end":"2026-05-11T12:20:41.605106Z","steps":["trace[744009717] 'read index received' (duration: 151.743629ms)","trace[744009717] 'applied index is now lower than readState.Index' (duration: 273.926µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:20:41.605688Z","caller":"traceutil/trace.go:171","msg":"trace[353620026] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1386; }","duration":"152.63512ms","start":"2026-05-11T12:20:41.453044Z","end":"2026-05-11T12:20:41.605679Z","steps":["trace[353620026] 'agreement among raft nodes before linearized reading' (duration: 152.546138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:20:41.605971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.606649ms","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:532"} {"level":"info","ts":"2026-05-11T12:20:41.606023Z","caller":"traceutil/trace.go:171","msg":"trace[772693372] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1386; }","duration":"131.69344ms","start":"2026-05-11T12:20:41.474316Z","end":"2026-05-11T12:20:41.606009Z","steps":["trace[772693372] 'agreement among raft nodes before linearized reading' (duration: 131.483166ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:20:41.744186Z","caller":"traceutil/trace.go:171","msg":"trace[1183799459] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1440; }","duration":"133.421563ms","start":"2026-05-11T12:20:41.610735Z","end":"2026-05-11T12:20:41.744157Z","steps":["trace[1183799459] 'read index received' (duration: 38.484788ms)","trace[1183799459] 'applied index is now lower than readState.Index' (duration: 94.935315ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:20:41.744234Z","caller":"traceutil/trace.go:171","msg":"trace[212286986] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"133.646198ms","start":"2026-05-11T12:20:41.610555Z","end":"2026-05-11T12:20:41.744201Z","steps":["trace[212286986] 'process raft request' (duration: 38.670702ms)","trace[212286986] 'compare' (duration: 94.793401ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T12:20:41.744405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.641208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T12:20:41.744568Z","caller":"traceutil/trace.go:171","msg":"trace[255642843] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1387; }","duration":"133.811061ms","start":"2026-05-11T12:20:41.610706Z","end":"2026-05-11T12:20:41.744517Z","steps":["trace[255642843] 'agreement among raft nodes before linearized reading' (duration: 133.569235ms)"],"step_count":1}