{"level":"warn","ts":"2026-04-01T15:03:42.802968Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T15:03:42.803113Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-04-01T15:03:42.803207Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T15:03:42.803237Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-04-01T15:03:42.803293Z","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-04-01T15:03:42.804034Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-04-01T15:03:42.804121Z","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.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-04-01T15:03:42.816405Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.77831ms"} {"level":"info","ts":"2026-04-01T15:03:42.828167Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-04-01T15:03:42.828246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-04-01T15:03:42.828266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-04-01T15:03:42.828281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-01T15:03:42.82829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-04-01T15:03:42.828326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-04-01T15:03:42.835037Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-01T15:03:42.836433Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-01T15:03:42.837521Z","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-04-01T15:03:42.839554Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-01T15:03:42.839855Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-01T15:03:42.840084Z","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-04-01T15:03:42.840173Z","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-04-01T15:03:42.840403Z","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-04-01T15:03:42.840798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-04-01T15:03:42.842243Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-04-01T15:03:42.843981Z","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-04-01T15:03:42.844134Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-04-01T15:03:42.84417Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-01T15:03:42.844217Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-01T15:03:42.8442Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-04-01T15:03:42.929126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-04-01T15:03:42.929193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-01T15:03:42.929226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-04-01T15:03:42.929246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-04-01T15:03:42.929258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-04-01T15:03:42.929273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-04-01T15:03:42.929287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-04-01T15:03:42.933427Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T15:03:42.941539Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-01T15:03:42.944299Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T15:03:42.944464Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T15:03:42.944566Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-01T15:03:42.944645Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-01T15:03:42.94758Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-01T15:03:42.966107Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T15:03:42.966295Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T15:03:42.966358Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T15:03:42.972974Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-04-01T15:03:45.647555Z","caller":"traceutil/trace.go:171","msg":"trace[33903798] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"110.604582ms","start":"2026-04-01T15:03:45.536926Z","end":"2026-04-01T15:03:45.647531Z","steps":["trace[33903798] 'read index received' (duration: 110.445669ms)","trace[33903798] 'applied index is now lower than readState.Index' (duration: 157.853µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:03:45.647772Z","caller":"traceutil/trace.go:171","msg":"trace[321324071] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"111.548162ms","start":"2026-04-01T15:03:45.53612Z","end":"2026-04-01T15:03:45.647669Z","steps":["trace[321324071] 'process raft request' (duration: 111.277298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:03:45.647974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.975386ms","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-04-01T15:03:45.648059Z","caller":"traceutil/trace.go:171","msg":"trace[456241101] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"111.162668ms","start":"2026-04-01T15:03:45.536884Z","end":"2026-04-01T15:03:45.648047Z","steps":["trace[456241101] 'agreement among raft nodes before linearized reading' (duration: 110.940725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:03:45.648145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.842038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-01T15:03:45.648318Z","caller":"traceutil/trace.go:171","msg":"trace[2074795570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"105.067231ms","start":"2026-04-01T15:03:45.54318Z","end":"2026-04-01T15:03:45.648247Z","steps":["trace[2074795570] 'agreement among raft nodes before linearized reading' (duration: 104.767437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:03:46.973635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128943672s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-01T15:03:46.973705Z","caller":"traceutil/trace.go:171","msg":"trace[1026256041] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"1.319774258s","start":"2026-04-01T15:03:45.65392Z","end":"2026-04-01T15:03:46.973695Z","steps":["trace[1026256041] 'read index received' (duration: 190.459021ms)","trace[1026256041] 'applied index is now lower than readState.Index' (duration: 1.129314477s)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:03:46.973776Z","caller":"traceutil/trace.go:171","msg":"trace[714181713] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"1.320228894s","start":"2026-04-01T15:03:45.65354Z","end":"2026-04-01T15:03:46.973769Z","steps":["trace[714181713] 'process raft request' (duration: 190.825436ms)","trace[714181713] 'compare' (duration: 1.12882374s)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:03:46.973808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:03:45.653527Z","time spent":"1.320268154s","remote":"127.0.0.1:38550","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-04-01T15:03:46.973939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.830002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-01T15:03:46.974037Z","caller":"traceutil/trace.go:171","msg":"trace[664026595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"365.933124ms","start":"2026-04-01T15:03:46.608051Z","end":"2026-04-01T15:03:46.973984Z","steps":["trace[664026595] 'agreement among raft nodes before linearized reading' (duration: 365.738312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:03:46.974058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.337576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-01T15:03:46.974076Z","caller":"traceutil/trace.go:171","msg":"trace[1460114973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"230.399397ms","start":"2026-04-01T15:03:46.743671Z","end":"2026-04-01T15:03:46.974071Z","steps":["trace[1460114973] 'agreement among raft nodes before linearized reading' (duration: 230.362356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:03:46.974074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:03:46.60803Z","time spent":"366.038625ms","remote":"127.0.0.1:38152","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-04-01T15:03:46.974064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.320129312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-01T15:03:46.974113Z","caller":"traceutil/trace.go:171","msg":"trace[884804455] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:73; }","duration":"1.320192244s","start":"2026-04-01T15:03:45.653907Z","end":"2026-04-01T15:03:46.974099Z","steps":["trace[884804455] 'agreement among raft nodes before linearized reading' (duration: 1.320102332s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:03:46.974151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:03:45.6539Z","time spent":"1.320242345s","remote":"127.0.0.1:38532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"info","ts":"2026-04-01T15:04:26.001562Z","caller":"traceutil/trace.go:171","msg":"trace[2139905037] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"462.277026ms","start":"2026-04-01T15:04:25.539208Z","end":"2026-04-01T15:04:26.001485Z","steps":["trace[2139905037] 'process raft request' (duration: 461.617001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:26.00168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.992152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:04:26.001803Z","caller":"traceutil/trace.go:171","msg":"trace[2085287885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:460; }","duration":"122.169758ms","start":"2026-04-01T15:04:25.879611Z","end":"2026-04-01T15:04:26.001781Z","steps":["trace[2085287885] 'agreement among raft nodes before linearized reading' (duration: 121.940909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:26.00188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:25.539187Z","time spent":"462.535446ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T15:04:26.002098Z","caller":"traceutil/trace.go:171","msg":"trace[884371407] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:476; }","duration":"121.67082ms","start":"2026-04-01T15:04:25.879671Z","end":"2026-04-01T15:04:26.001342Z","steps":["trace[884371407] 'read index received' (duration: 120.819598ms)","trace[884371407] 'applied index is now lower than readState.Index' (duration: 850.562µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:04:28.390912Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741135233282202,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T15:04:28.777967Z","caller":"traceutil/trace.go:171","msg":"trace[734483507] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"895.189846ms","start":"2026-04-01T15:04:27.882761Z","end":"2026-04-01T15:04:28.777951Z","steps":["trace[734483507] 'process raft request' (duration: 894.977438ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:04:28.778121Z","caller":"traceutil/trace.go:171","msg":"trace[2141005049] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"891.557869ms","start":"2026-04-01T15:04:27.886535Z","end":"2026-04-01T15:04:28.778093Z","steps":["trace[2141005049] 'process raft request' (duration: 891.31213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:28.778139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:27.882752Z","time spent":"895.343922ms","remote":"127.0.0.1:38200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T15:04:28.778263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:27.886525Z","time spent":"891.683554ms","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T15:04:28.778357Z","caller":"traceutil/trace.go:171","msg":"trace[789570502] transaction","detail":"{read_only:false; number_of_response:1; response_revision:516; }","duration":"889.019324ms","start":"2026-04-01T15:04:27.88933Z","end":"2026-04-01T15:04:28.778349Z","steps":["trace[789570502] 'process raft request' (duration: 888.572836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:28.778564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:27.889321Z","time spent":"889.092646ms","remote":"127.0.0.1:38200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:04:28.80732Z","caller":"traceutil/trace.go:171","msg":"trace[2112633920] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"908.346749ms","start":"2026-04-01T15:04:27.898749Z","end":"2026-04-01T15:04:28.807096Z","steps":["trace[2112633920] 'process raft request' (duration: 908.076348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:28.807697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:27.898734Z","time spent":"908.824517ms","remote":"127.0.0.1:38200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:04:28.811501Z","caller":"traceutil/trace.go:171","msg":"trace[1948410541] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"909.357448ms","start":"2026-04-01T15:04:27.902109Z","end":"2026-04-01T15:04:28.811466Z","steps":["trace[1948410541] 'process raft request' (duration: 907.179355ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:04:28.811511Z","caller":"traceutil/trace.go:171","msg":"trace[872284301] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"912.509777ms","start":"2026-04-01T15:04:27.898963Z","end":"2026-04-01T15:04:28.811472Z","steps":["trace[872284301] 'process raft request' (duration: 908.019276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:28.811624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:27.90207Z","time spent":"909.505724ms","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T15:04:28.811622Z","caller":"traceutil/trace.go:171","msg":"trace[2070677179] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"891.037854ms","start":"2026-04-01T15:04:27.92054Z","end":"2026-04-01T15:04:28.811578Z","steps":["trace[2070677179] 'process raft request' (duration: 888.962184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:28.811706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:27.8989Z","time spent":"912.681004ms","remote":"127.0.0.1:38200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:04:28.811787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:27.920519Z","time spent":"891.18493ms","remote":"127.0.0.1:38200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:04:28.813214Z","caller":"traceutil/trace.go:171","msg":"trace[1384405581] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"892.381907ms","start":"2026-04-01T15:04:27.920813Z","end":"2026-04-01T15:04:28.813195Z","steps":["trace[1384405581] 'process raft request' (duration: 888.928924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:28.813469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:27.920776Z","time spent":"892.47683ms","remote":"127.0.0.1:38200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:04:28.813595Z","caller":"traceutil/trace.go:171","msg":"trace[1672556909] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:534; }","duration":"922.812039ms","start":"2026-04-01T15:04:27.890741Z","end":"2026-04-01T15:04:28.813553Z","steps":["trace[1672556909] 'read index received' (duration: 886.960355ms)","trace[1672556909] 'applied index is now lower than readState.Index' (duration: 35.850744ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:04:28.813642Z","caller":"traceutil/trace.go:171","msg":"trace[1946809907] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"779.747426ms","start":"2026-04-01T15:04:28.033863Z","end":"2026-04-01T15:04:28.813611Z","steps":["trace[1946809907] 'process raft request' (duration: 779.595741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:28.813725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.979597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-04-01T15:04:28.814812Z","caller":"traceutil/trace.go:171","msg":"trace[831726835] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:523; }","duration":"928.0953ms","start":"2026-04-01T15:04:27.886687Z","end":"2026-04-01T15:04:28.814783Z","steps":["trace[831726835] 'agreement among raft nodes before linearized reading' (duration: 926.950036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:28.814848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:27.886661Z","time spent":"928.176643ms","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":1033,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-04-01T15:04:28.814354Z","caller":"traceutil/trace.go:171","msg":"trace[1263524606] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"888.388045ms","start":"2026-04-01T15:04:27.925145Z","end":"2026-04-01T15:04:28.813533Z","steps":["trace[1263524606] 'process raft request' (duration: 886.478952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:28.81495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:27.925119Z","time spent":"889.778329ms","remote":"127.0.0.1:38200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:04:28.814958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:28.033816Z","time spent":"780.948873ms","remote":"127.0.0.1:38200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T15:04:28.815192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.14285ms","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-04-01T15:04:28.815315Z","caller":"traceutil/trace.go:171","msg":"trace[563597521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"473.294138ms","start":"2026-04-01T15:04:28.341977Z","end":"2026-04-01T15:04:28.815271Z","steps":["trace[563597521] 'agreement among raft nodes before linearized reading' (duration: 472.85103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:28.818668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:28.341949Z","time spent":"476.701487ms","remote":"127.0.0.1:38450","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-04-01T15:04:36.381763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741135233282366,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T15:04:36.508487Z","caller":"traceutil/trace.go:171","msg":"trace[601866049] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"627.1466ms","start":"2026-04-01T15:04:35.881304Z","end":"2026-04-01T15:04:36.50845Z","steps":["trace[601866049] 'read index received' (duration: 625.95476ms)","trace[601866049] 'applied index is now lower than readState.Index' (duration: 1.19061ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:04:36.50864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.313797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:04:36.508676Z","caller":"traceutil/trace.go:171","msg":"trace[1743634922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"627.411021ms","start":"2026-04-01T15:04:35.881252Z","end":"2026-04-01T15:04:36.508663Z","steps":["trace[1743634922] 'agreement among raft nodes before linearized reading' (duration: 627.319688ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:04:36.508655Z","caller":"traceutil/trace.go:171","msg":"trace[1517830839] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"695.704519ms","start":"2026-04-01T15:04:35.812902Z","end":"2026-04-01T15:04:36.508607Z","steps":["trace[1517830839] 'process raft request' (duration: 694.707298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:36.508734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:35.881222Z","time spent":"627.502286ms","remote":"127.0.0.1:38152","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-04-01T15:04:36.508882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:35.81287Z","time spent":"695.891677ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:04:37.378428Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741135233282369,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T15:04:37.879118Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741135233282369,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T15:04:38.209283Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.622090096s","expected-duration":"1s"} {"level":"info","ts":"2026-04-01T15:04:38.209718Z","caller":"traceutil/trace.go:171","msg":"trace[947313707] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"1.332093834s","start":"2026-04-01T15:04:36.877599Z","end":"2026-04-01T15:04:38.209693Z","steps":["trace[947313707] 'read index received' (duration: 1.331871015s)","trace[947313707] 'applied index is now lower than readState.Index' (duration: 221.649µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:04:38.210112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.805302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-01T15:04:38.210214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.331337523s","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-04-01T15:04:38.210227Z","caller":"traceutil/trace.go:171","msg":"trace[1713091899] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:614; }","duration":"561.963009ms","start":"2026-04-01T15:04:37.648241Z","end":"2026-04-01T15:04:38.210204Z","steps":["trace[1713091899] 'agreement among raft nodes before linearized reading' (duration: 561.794362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:38.210248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176848975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:04:38.210268Z","caller":"traceutil/trace.go:171","msg":"trace[359543224] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"1.331450698s","start":"2026-04-01T15:04:36.8788Z","end":"2026-04-01T15:04:38.210251Z","steps":["trace[359543224] 'agreement among raft nodes before linearized reading' (duration: 1.331315732s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:38.210285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:37.648211Z","time spent":"562.061543ms","remote":"127.0.0.1:38178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-04-01T15:04:38.210111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.647452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T15:04:38.210319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:36.878794Z","time spent":"1.33151921s","remote":"127.0.0.1:38450","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-04-01T15:04:38.210224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33176758s","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-04-01T15:04:38.21034Z","caller":"traceutil/trace.go:171","msg":"trace[752134321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"330.936044ms","start":"2026-04-01T15:04:37.879389Z","end":"2026-04-01T15:04:38.210325Z","steps":["trace[752134321] 'agreement among raft nodes before linearized reading' (duration: 330.59409ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:04:38.210419Z","caller":"traceutil/trace.go:171","msg":"trace[1156485857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"1.331953427s","start":"2026-04-01T15:04:36.878409Z","end":"2026-04-01T15:04:38.210363Z","steps":["trace[1156485857] 'agreement among raft nodes before linearized reading' (duration: 1.33154911s)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:04:38.210331Z","caller":"traceutil/trace.go:171","msg":"trace[990993401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"1.176940499s","start":"2026-04-01T15:04:37.03336Z","end":"2026-04-01T15:04:38.210301Z","steps":["trace[990993401] 'agreement among raft nodes before linearized reading' (duration: 1.176837584s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:38.210478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:37.879342Z","time spent":"331.123691ms","remote":"127.0.0.1:38136","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-04-01T15:04:38.210495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:36.878359Z","time spent":"1.332092944s","remote":"127.0.0.1:38450","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-04-01T15:04:38.210141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201531244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-01T15:04:38.210506Z","caller":"traceutil/trace.go:171","msg":"trace[1568713922] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.622804476s","start":"2026-04-01T15:04:36.587071Z","end":"2026-04-01T15:04:38.209875Z","steps":["trace[1568713922] 'process raft request' (duration: 1.622486093s)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:04:38.210546Z","caller":"traceutil/trace.go:171","msg":"trace[1610735072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"1.201949311s","start":"2026-04-01T15:04:37.008575Z","end":"2026-04-01T15:04:38.210524Z","steps":["trace[1610735072] 'agreement among raft nodes before linearized reading' (duration: 1.201463601s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:38.210586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:37.008529Z","time spent":"1.202047785s","remote":"127.0.0.1:38450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T15:04:38.210654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:36.587051Z","time spent":"1.623534657s","remote":"127.0.0.1:38434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T15:04:38.210489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:37.033344Z","time spent":"1.177133557s","remote":"127.0.0.1:38152","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-04-01T15:04:38.210772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.332660598s","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-04-01T15:04:38.210809Z","caller":"traceutil/trace.go:171","msg":"trace[563567530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"1.333236492s","start":"2026-04-01T15:04:36.877561Z","end":"2026-04-01T15:04:38.210797Z","steps":["trace[563567530] 'agreement among raft nodes before linearized reading' (duration: 1.332307812s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:38.210892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:36.877538Z","time spent":"1.333304645s","remote":"127.0.0.1:38450","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":"info","ts":"2026-04-01T15:04:59.745228Z","caller":"traceutil/trace.go:171","msg":"trace[1128060335] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"345.232971ms","start":"2026-04-01T15:04:59.399956Z","end":"2026-04-01T15:04:59.745189Z","steps":["trace[1128060335] 'process raft request' (duration: 345.032281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:04:59.74544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:04:59.399927Z","time spent":"345.410961ms","remote":"127.0.0.1:38434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:06:29.326111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.090465ms","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-04-01T15:06:29.326172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.958106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-01T15:06:29.326181Z","caller":"traceutil/trace.go:171","msg":"trace[1124156689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1216; }","duration":"545.204553ms","start":"2026-04-01T15:06:28.780957Z","end":"2026-04-01T15:06:29.326162Z","steps":["trace[1124156689] 'range keys from in-memory index tree' (duration: 544.877172ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:06:29.326193Z","caller":"traceutil/trace.go:171","msg":"trace[677416634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1216; }","duration":"471.02806ms","start":"2026-04-01T15:06:28.855157Z","end":"2026-04-01T15:06:29.326185Z","steps":["trace[677416634] 'range keys from in-memory index tree' (duration: 470.86712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:06:29.326214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:06:28.855132Z","time spent":"471.077453ms","remote":"127.0.0.1:38450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T15:06:29.326217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:06:28.780929Z","time spent":"545.281267ms","remote":"127.0.0.1:38450","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-04-01T15:06:29.326287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.13644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:06:29.326307Z","caller":"traceutil/trace.go:171","msg":"trace[1983224538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"445.199203ms","start":"2026-04-01T15:06:28.881103Z","end":"2026-04-01T15:06:29.326302Z","steps":["trace[1983224538] 'range keys from in-memory index tree' (duration: 445.055755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:06:29.326321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:06:28.881079Z","time spent":"445.239226ms","remote":"127.0.0.1:38152","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-04-01T15:06:29.326423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244997335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-04-01T15:06:29.326463Z","caller":"traceutil/trace.go:171","msg":"trace[1666180900] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1216; }","duration":"1.24507812s","start":"2026-04-01T15:06:28.081377Z","end":"2026-04-01T15:06:29.326455Z","steps":["trace[1666180900] 'range keys from in-memory index tree' (duration: 1.244672035s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:06:29.32647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072520261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-01T15:06:29.326499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:06:28.081359Z","time spent":"1.245123222s","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329975,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-04-01T15:06:29.326622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.192864ms","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-04-01T15:06:29.326644Z","caller":"traceutil/trace.go:171","msg":"trace[1059683364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1216; }","duration":"367.247017ms","start":"2026-04-01T15:06:28.959392Z","end":"2026-04-01T15:06:29.32664Z","steps":["trace[1059683364] 'range keys from in-memory index tree' (duration: 367.082367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:06:29.326128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.37328ms","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-04-01T15:06:29.326686Z","caller":"traceutil/trace.go:171","msg":"trace[737950220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1216; }","duration":"342.970517ms","start":"2026-04-01T15:06:28.983708Z","end":"2026-04-01T15:06:29.326679Z","steps":["trace[737950220] 'range keys from in-memory index tree' (duration: 342.262583ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:06:29.326501Z","caller":"traceutil/trace.go:171","msg":"trace[2129858013] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1216; }","duration":"1.072596465s","start":"2026-04-01T15:06:28.253896Z","end":"2026-04-01T15:06:29.326493Z","steps":["trace[2129858013] 'range keys from in-memory index tree' (duration: 1.072412103s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:06:29.326709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:06:28.983686Z","time spent":"343.016121ms","remote":"127.0.0.1:38450","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-04-01T15:06:29.32672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:06:28.25387Z","time spent":"1.072841131s","remote":"127.0.0.1:38450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-01T15:06:29.326659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:06:28.959374Z","time spent":"367.281679ms","remote":"127.0.0.1:38450","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-04-01T15:06:29.326973Z","caller":"traceutil/trace.go:171","msg":"trace[343234415] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"156.223554ms","start":"2026-04-01T15:06:29.170732Z","end":"2026-04-01T15:06:29.326956Z","steps":["trace[343234415] 'read index received' (duration: 155.008598ms)","trace[343234415] 'applied index is now lower than readState.Index' (duration: 1.214386ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:06:29.327007Z","caller":"traceutil/trace.go:171","msg":"trace[712063044] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"172.654966ms","start":"2026-04-01T15:06:29.154345Z","end":"2026-04-01T15:06:29.327Z","steps":["trace[712063044] 'process raft request' (duration: 171.454541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:06:29.327108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.339171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:06:29.327397Z","caller":"traceutil/trace.go:171","msg":"trace[1879727295] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1217; }","duration":"156.649601ms","start":"2026-04-01T15:06:29.170725Z","end":"2026-04-01T15:06:29.327375Z","steps":["trace[1879727295] 'agreement among raft nodes before linearized reading' (duration: 156.289608ms)"],"step_count":1}