{"level":"warn","ts":"2026-03-31T01:17:18.185303Z","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-31T01:17:18.185387Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70: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://162.253.55.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70: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-31T01:17:18.185444Z","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-31T01:17:18.185455Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-03-31T01:17:18.185472Z","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-31T01:17:18.186133Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-03-31T01:17:18.186232Z","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://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70: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-31T01:17:18.190021Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.526064ms"} {"level":"info","ts":"2026-03-31T01:17:18.199435Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-03-31T01:17:18.199498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T01:17:18.19952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-03-31T01:17:18.199536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T01:17:18.199547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-03-31T01:17:18.199585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-03-31T01:17:18.20534Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T01:17:18.206502Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T01:17:18.207406Z","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-31T01:17:18.209378Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T01:17:18.20961Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T01:17:18.209712Z","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-31T01:17:18.209806Z","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-31T01:17:18.209867Z","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-31T01:17:18.210116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-03-31T01:17:18.210294Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-03-31T01:17:18.216006Z","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-31T01:17:18.216273Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T01:17:18.216323Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T01:17:18.216814Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-03-31T01:17:18.216849Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-03-31T01:17:19.199982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T01:17:19.200043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T01:17:19.200066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-03-31T01:17:19.200122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-03-31T01:17:19.20017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-03-31T01:17:19.200246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-03-31T01:17:19.200303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-03-31T01:17:19.201312Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T01:17:19.201328Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T01:17:19.201405Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T01:17:19.201475Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T01:17:19.201678Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T01:17:19.201765Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T01:17:19.202264Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T01:17:19.202392Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T01:17:19.202435Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T01:17:19.204722Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-31T01:17:19.2054Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-03-31T01:17:28.134574Z","caller":"traceutil/trace.go:171","msg":"trace[1640461284] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"108.7589ms","start":"2026-03-31T01:17:28.025787Z","end":"2026-03-31T01:17:28.134546Z","steps":["trace[1640461284] 'process raft request' (duration: 54.107049ms)","trace[1640461284] 'compare' (duration: 54.472097ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T01:17:34.087784Z","caller":"traceutil/trace.go:171","msg":"trace[2139412109] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"270.38094ms","start":"2026-03-31T01:17:33.817381Z","end":"2026-03-31T01:17:34.087762Z","steps":["trace[2139412109] 'read index received' (duration: 270.250947ms)","trace[2139412109] 'applied index is now lower than readState.Index' (duration: 128.633µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T01:17:34.087856Z","caller":"traceutil/trace.go:171","msg":"trace[1766892525] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"282.311049ms","start":"2026-03-31T01:17:33.805492Z","end":"2026-03-31T01:17:34.087803Z","steps":["trace[1766892525] 'process raft request' (duration: 282.146325ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:17:34.087961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.278532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T01:17:34.088028Z","caller":"traceutil/trace.go:171","msg":"trace[1290287828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:411; }","duration":"228.385895ms","start":"2026-03-31T01:17:33.859632Z","end":"2026-03-31T01:17:34.088018Z","steps":["trace[1290287828] 'agreement among raft nodes before linearized reading' (duration: 228.259702ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:17:34.088021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.612716ms","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-31T01:17:34.088101Z","caller":"traceutil/trace.go:171","msg":"trace[212595062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:411; }","duration":"270.733428ms","start":"2026-03-31T01:17:33.817347Z","end":"2026-03-31T01:17:34.08808Z","steps":["trace[212595062] 'agreement among raft nodes before linearized reading' (duration: 270.531583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:17:34.610551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.880601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T01:17:34.61122Z","caller":"traceutil/trace.go:171","msg":"trace[955818612] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"519.618014ms","start":"2026-03-31T01:17:34.091578Z","end":"2026-03-31T01:17:34.611196Z","steps":["trace[955818612] 'process raft request' (duration: 253.160466ms)","trace[955818612] 'compare' (duration: 264.758088ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T01:17:34.611344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:17:34.091546Z","time spent":"519.756427ms","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T01:17:34.612255Z","caller":"traceutil/trace.go:171","msg":"trace[15695517] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:424; }","duration":"407.373352ms","start":"2026-03-31T01:17:34.204866Z","end":"2026-03-31T01:17:34.61224Z","steps":["trace[15695517] 'read index received' (duration: 139.88216ms)","trace[15695517] 'applied index is now lower than readState.Index' (duration: 267.490332ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T01:17:34.612431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.542546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T01:17:34.612539Z","caller":"traceutil/trace.go:171","msg":"trace[1900286908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"407.68479ms","start":"2026-03-31T01:17:34.204802Z","end":"2026-03-31T01:17:34.612486Z","steps":["trace[1900286908] 'agreement among raft nodes before linearized reading' (duration: 407.543837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:17:34.612611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:17:34.204781Z","time spent":"407.819283ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T01:17:34.613017Z","caller":"traceutil/trace.go:171","msg":"trace[789069352] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"520.708359ms","start":"2026-03-31T01:17:34.092296Z","end":"2026-03-31T01:17:34.613005Z","steps":["trace[789069352] 'process raft request' (duration: 519.806628ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:17:34.613372Z","caller":"traceutil/trace.go:171","msg":"trace[1653401667] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"517.574796ms","start":"2026-03-31T01:17:34.09578Z","end":"2026-03-31T01:17:34.613355Z","steps":["trace[1653401667] 'process raft request' (duration: 516.385679ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:17:34.613454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:17:34.095757Z","time spent":"517.656138ms","remote":"127.0.0.1:58824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T01:17:34.613585Z","caller":"traceutil/trace.go:171","msg":"trace[1432039714] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"293.549963ms","start":"2026-03-31T01:17:34.320004Z","end":"2026-03-31T01:17:34.613554Z","steps":["trace[1432039714] 'process raft request' (duration: 292.203922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:17:34.613826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:17:34.092278Z","time spent":"520.956886ms","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T01:17:48.658582Z","caller":"traceutil/trace.go:171","msg":"trace[1795612487] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"153.351596ms","start":"2026-03-31T01:17:48.505195Z","end":"2026-03-31T01:17:48.658546Z","steps":["trace[1795612487] 'process raft request' (duration: 153.246513ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:17:48.662765Z","caller":"traceutil/trace.go:171","msg":"trace[2138718707] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"145.839879ms","start":"2026-03-31T01:17:48.516883Z","end":"2026-03-31T01:17:48.662723Z","steps":["trace[2138718707] 'process raft request' (duration: 145.703126ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:17:48.662821Z","caller":"traceutil/trace.go:171","msg":"trace[1612116586] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"146.125376ms","start":"2026-03-31T01:17:48.516671Z","end":"2026-03-31T01:17:48.662796Z","steps":["trace[1612116586] 'process raft request' (duration: 145.833299ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:17:48.663084Z","caller":"traceutil/trace.go:171","msg":"trace[1427028472] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"141.55391ms","start":"2026-03-31T01:17:48.521512Z","end":"2026-03-31T01:17:48.663066Z","steps":["trace[1427028472] 'process raft request' (duration: 141.201991ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:17:48.663082Z","caller":"traceutil/trace.go:171","msg":"trace[1191301991] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"141.618711ms","start":"2026-03-31T01:17:48.521418Z","end":"2026-03-31T01:17:48.663037Z","steps":["trace[1191301991] 'process raft request' (duration: 141.279403ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:17:48.663185Z","caller":"traceutil/trace.go:171","msg":"trace[577531468] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"141.697322ms","start":"2026-03-31T01:17:48.521483Z","end":"2026-03-31T01:17:48.66318Z","steps":["trace[577531468] 'process raft request' (duration: 141.249712ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:17:48.663248Z","caller":"traceutil/trace.go:171","msg":"trace[1418375884] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"138.264033ms","start":"2026-03-31T01:17:48.524977Z","end":"2026-03-31T01:17:48.663242Z","steps":["trace[1418375884] 'process raft request' (duration: 137.776821ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:17:48.663301Z","caller":"traceutil/trace.go:171","msg":"trace[1780706656] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"136.574493ms","start":"2026-03-31T01:17:48.526722Z","end":"2026-03-31T01:17:48.663297Z","steps":["trace[1780706656] 'process raft request' (duration: 136.067601ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:17:48.663351Z","caller":"traceutil/trace.go:171","msg":"trace[744861320] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"133.06788ms","start":"2026-03-31T01:17:48.530279Z","end":"2026-03-31T01:17:48.663347Z","steps":["trace[744861320] 'process raft request' (duration: 132.531948ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:17:48.663523Z","caller":"traceutil/trace.go:171","msg":"trace[1455964017] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"146.545346ms","start":"2026-03-31T01:17:48.516965Z","end":"2026-03-31T01:17:48.663511Z","steps":["trace[1455964017] 'process raft request' (duration: 145.662875ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:17:48.663604Z","caller":"traceutil/trace.go:171","msg":"trace[1071633248] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"146.598617ms","start":"2026-03-31T01:17:48.516997Z","end":"2026-03-31T01:17:48.663595Z","steps":["trace[1071633248] 'process raft request' (duration: 145.664445ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:17:48.663688Z","caller":"traceutil/trace.go:171","msg":"trace[1924071198] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"142.267236ms","start":"2026-03-31T01:17:48.521409Z","end":"2026-03-31T01:17:48.663676Z","steps":["trace[1924071198] 'process raft request' (duration: 141.271442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:17:49.99997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.547138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-03-31T01:17:50.000207Z","caller":"traceutil/trace.go:171","msg":"trace[2118747119] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:583; }","duration":"242.795544ms","start":"2026-03-31T01:17:49.75738Z","end":"2026-03-31T01:17:50.000175Z","steps":["trace[2118747119] 'range keys from in-memory index tree' (duration: 242.281651ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:17:54.792283Z","caller":"traceutil/trace.go:171","msg":"trace[487131006] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"163.287929ms","start":"2026-03-31T01:17:54.628953Z","end":"2026-03-31T01:17:54.792241Z","steps":["trace[487131006] 'process raft request' (duration: 144.5943ms)","trace[487131006] 'compare' (duration: 18.210197ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T01:17:55.513493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.582273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T01:17:55.513837Z","caller":"traceutil/trace.go:171","msg":"trace[2094275668] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"604.014503ms","start":"2026-03-31T01:17:54.909795Z","end":"2026-03-31T01:17:55.51381Z","steps":["trace[2094275668] 'range keys from in-memory index tree' (duration: 603.384799ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:17:55.514024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:17:54.909763Z","time spent":"604.190627ms","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T01:17:55.518893Z","caller":"traceutil/trace.go:171","msg":"trace[895089983] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"440.935679ms","start":"2026-03-31T01:17:55.077941Z","end":"2026-03-31T01:17:55.518877Z","steps":["trace[895089983] 'process raft request' (duration: 440.818196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:17:55.519011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:17:55.077922Z","time spent":"441.039152ms","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T01:17:55.519684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.354211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vvl9b\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-31T01:17:55.519777Z","caller":"traceutil/trace.go:171","msg":"trace[1506900418] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vvl9b; range_end:; response_count:1; response_revision:617; }","duration":"401.513694ms","start":"2026-03-31T01:17:55.11824Z","end":"2026-03-31T01:17:55.519754Z","steps":["trace[1506900418] 'agreement among raft nodes before linearized reading' (duration: 401.32274ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:17:55.519829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:17:55.11822Z","time spent":"401.600637ms","remote":"127.0.0.1:58824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-vvl9b\" "} {"level":"info","ts":"2026-03-31T01:17:55.522345Z","caller":"traceutil/trace.go:171","msg":"trace[858550616] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:635; }","duration":"401.184517ms","start":"2026-03-31T01:17:55.118301Z","end":"2026-03-31T01:17:55.519485Z","steps":["trace[858550616] 'read index received' (duration: 400.90211ms)","trace[858550616] 'applied index is now lower than readState.Index' (duration: 277.977µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T01:17:55.53737Z","caller":"traceutil/trace.go:171","msg":"trace[1476610887] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"304.984201ms","start":"2026-03-31T01:17:55.232366Z","end":"2026-03-31T01:17:55.53735Z","steps":["trace[1476610887] 'process raft request' (duration: 304.331435ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:17:55.537606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:17:55.232338Z","time spent":"305.115794ms","remote":"127.0.0.1:58734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T01:17:55.544662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.531055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T01:17:55.544719Z","caller":"traceutil/trace.go:171","msg":"trace[1897721716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"340.624467ms","start":"2026-03-31T01:17:55.20408Z","end":"2026-03-31T01:17:55.544705Z","steps":["trace[1897721716] 'agreement among raft nodes before linearized reading' (duration: 340.531975ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:17:55.544747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:17:55.204061Z","time spent":"340.681848ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T01:17:55.545077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.618682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T01:17:55.545108Z","caller":"traceutil/trace.go:171","msg":"trace[1944135603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"263.692774ms","start":"2026-03-31T01:17:55.281406Z","end":"2026-03-31T01:17:55.545099Z","steps":["trace[1944135603] 'agreement among raft nodes before linearized reading' (duration: 263.587741ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:18:07.438151Z","caller":"traceutil/trace.go:171","msg":"trace[200195327] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"190.397984ms","start":"2026-03-31T01:18:07.247725Z","end":"2026-03-31T01:18:07.438123Z","steps":["trace[200195327] 'process raft request' (duration: 190.166969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:18:10.586947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.014154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T01:18:10.587028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.005235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xmmw8\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-03-31T01:18:10.587071Z","caller":"traceutil/trace.go:171","msg":"trace[1701221559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"381.198138ms","start":"2026-03-31T01:18:10.205843Z","end":"2026-03-31T01:18:10.587041Z","steps":["trace[1701221559] 'range keys from in-memory index tree' (duration: 380.927272ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:18:10.587099Z","caller":"traceutil/trace.go:171","msg":"trace[1056644506] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xmmw8; range_end:; response_count:1; response_revision:778; }","duration":"361.115608ms","start":"2026-03-31T01:18:10.22596Z","end":"2026-03-31T01:18:10.587076Z","steps":["trace[1056644506] 'range keys from in-memory index tree' (duration: 360.812281ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:18:10.58713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:18:10.205818Z","time spent":"381.29777ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T01:18:10.587166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:18:10.225937Z","time spent":"361.196939ms","remote":"127.0.0.1:58824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xmmw8\" "} {"level":"info","ts":"2026-03-31T01:18:45.226984Z","caller":"traceutil/trace.go:171","msg":"trace[42681349] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"178.166393ms","start":"2026-03-31T01:18:45.048794Z","end":"2026-03-31T01:18:45.22696Z","steps":["trace[42681349] 'read index received' (duration: 177.999479ms)","trace[42681349] 'applied index is now lower than readState.Index' (duration: 165.864µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T01:18:45.227044Z","caller":"traceutil/trace.go:171","msg":"trace[1633718053] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"178.244015ms","start":"2026-03-31T01:18:45.048771Z","end":"2026-03-31T01:18:45.227015Z","steps":["trace[1633718053] 'process raft request' (duration: 178.03511ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:18:45.227184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.361287ms","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-31T01:18:45.227252Z","caller":"traceutil/trace.go:171","msg":"trace[654688552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:991; }","duration":"178.448129ms","start":"2026-03-31T01:18:45.048788Z","end":"2026-03-31T01:18:45.227236Z","steps":["trace[654688552] 'agreement among raft nodes before linearized reading' (duration: 178.217824ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:18:45.537625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.478221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T01:18:45.537743Z","caller":"traceutil/trace.go:171","msg":"trace[771634587] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"305.922946ms","start":"2026-03-31T01:18:45.231806Z","end":"2026-03-31T01:18:45.537729Z","steps":["trace[771634587] 'process raft request' (duration: 305.891175ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:18:45.537779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:18:45.231792Z","time spent":"305.972617ms","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T01:18:45.537982Z","caller":"traceutil/trace.go:171","msg":"trace[483608538] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"306.801577ms","start":"2026-03-31T01:18:45.231132Z","end":"2026-03-31T01:18:45.537934Z","steps":["trace[483608538] 'process raft request' (duration: 61.945307ms)","trace[483608538] 'compare' (duration: 244.138603ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T01:18:45.538126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:18:45.231114Z","time spent":"306.9378ms","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T01:18:45.605461Z","caller":"traceutil/trace.go:171","msg":"trace[1840190971] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"198.089484ms","start":"2026-03-31T01:18:45.407337Z","end":"2026-03-31T01:18:45.605426Z","steps":["trace[1840190971] 'process raft request' (duration: 197.8972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:07.648727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.415157ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-31T01:19:07.648878Z","caller":"traceutil/trace.go:171","msg":"trace[712255978] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"296.978788ms","start":"2026-03-31T01:19:07.35188Z","end":"2026-03-31T01:19:07.648858Z","steps":["trace[712255978] 'read index received' (duration: 6.321466ms)","trace[712255978] 'applied index is now lower than readState.Index' (duration: 290.656132ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T01:19:07.649042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.891249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T01:19:07.649066Z","caller":"traceutil/trace.go:171","msg":"trace[597593995] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1063; }","duration":"159.974391ms","start":"2026-03-31T01:19:07.489081Z","end":"2026-03-31T01:19:07.649056Z","steps":["trace[597593995] 'agreement among raft nodes before linearized reading' (duration: 159.897029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:07.649128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.234475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T01:19:07.649209Z","caller":"traceutil/trace.go:171","msg":"trace[1411647174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1063; }","duration":"297.358958ms","start":"2026-03-31T01:19:07.351826Z","end":"2026-03-31T01:19:07.649185Z","steps":["trace[1411647174] 'agreement among raft nodes before linearized reading' (duration: 297.126752ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:08.050071Z","caller":"traceutil/trace.go:171","msg":"trace[722730764] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1099; }","duration":"383.861614ms","start":"2026-03-31T01:19:07.666182Z","end":"2026-03-31T01:19:08.050044Z","steps":["trace[722730764] 'read index received' (duration: 383.702091ms)","trace[722730764] 'applied index is now lower than readState.Index' (duration: 158.313µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T01:19:08.050279Z","caller":"traceutil/trace.go:171","msg":"trace[305715442] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"396.557929ms","start":"2026-03-31T01:19:07.653634Z","end":"2026-03-31T01:19:08.050192Z","steps":["trace[305715442] 'process raft request' (duration: 396.237751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:08.05049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:07.653614Z","time spent":"396.775633ms","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T01:19:08.093092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.884142ms","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-31T01:19:08.093187Z","caller":"traceutil/trace.go:171","msg":"trace[1505778525] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1064; }","duration":"427.001785ms","start":"2026-03-31T01:19:07.666159Z","end":"2026-03-31T01:19:08.09316Z","steps":["trace[1505778525] 'agreement among raft nodes before linearized reading' (duration: 384.031228ms)","trace[1505778525] 'range keys from in-memory index tree' (duration: 42.735532ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T01:19:08.093234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:07.666142Z","time spent":"427.082797ms","remote":"127.0.0.1:58922","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":"info","ts":"2026-03-31T01:19:08.093454Z","caller":"traceutil/trace.go:171","msg":"trace[56853547] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"407.405341ms","start":"2026-03-31T01:19:07.686022Z","end":"2026-03-31T01:19:08.093427Z","steps":["trace[56853547] 'process raft request' (duration: 379.220057ms)","trace[56853547] 'compare' (duration: 27.837386ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T01:19:08.093594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:07.686006Z","time spent":"407.498313ms","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T01:19:08.093693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.524294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T01:19:08.093729Z","caller":"traceutil/trace.go:171","msg":"trace[1594393584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1065; }","duration":"163.593415ms","start":"2026-03-31T01:19:07.930125Z","end":"2026-03-31T01:19:08.093718Z","steps":["trace[1594393584] 'agreement among raft nodes before linearized reading' (duration: 163.464342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:08.094009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.747632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T01:19:08.094063Z","caller":"traceutil/trace.go:171","msg":"trace[1693212923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1065; }","duration":"183.815554ms","start":"2026-03-31T01:19:07.910223Z","end":"2026-03-31T01:19:08.094038Z","steps":["trace[1693212923] 'agreement among raft nodes before linearized reading' (duration: 183.694491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:08.094225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.182693ms","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-31T01:19:08.094263Z","caller":"traceutil/trace.go:171","msg":"trace[396152273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1065; }","duration":"422.220694ms","start":"2026-03-31T01:19:07.672031Z","end":"2026-03-31T01:19:08.094251Z","steps":["trace[396152273] 'agreement among raft nodes before linearized reading' (duration: 421.362344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:08.094353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:07.672025Z","time spent":"422.258605ms","remote":"127.0.0.1:58922","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":"info","ts":"2026-03-31T01:19:21.518255Z","caller":"traceutil/trace.go:171","msg":"trace[1715508932] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"125.44534ms","start":"2026-03-31T01:19:21.392777Z","end":"2026-03-31T01:19:21.518222Z","steps":["trace[1715508932] 'process raft request' (duration: 81.513181ms)","trace[1715508932] 'compare' (duration: 43.589361ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T01:19:21.518509Z","caller":"traceutil/trace.go:171","msg":"trace[187355818] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"119.626935ms","start":"2026-03-31T01:19:21.398829Z","end":"2026-03-31T01:19:21.518456Z","steps":["trace[187355818] 'process raft request' (duration: 119.292687ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:21.694403Z","caller":"traceutil/trace.go:171","msg":"trace[412281070] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"169.378879ms","start":"2026-03-31T01:19:21.52498Z","end":"2026-03-31T01:19:21.694359Z","steps":["trace[412281070] 'process raft request' (duration: 169.311637ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:21.694422Z","caller":"traceutil/trace.go:171","msg":"trace[122905800] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"170.73341ms","start":"2026-03-31T01:19:21.523635Z","end":"2026-03-31T01:19:21.694369Z","steps":["trace[122905800] 'process raft request' (duration: 98.418753ms)","trace[122905800] 'compare' (duration: 72.047001ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T01:19:26.3435Z","caller":"traceutil/trace.go:171","msg":"trace[8655340] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"137.98638ms","start":"2026-03-31T01:19:26.205485Z","end":"2026-03-31T01:19:26.343472Z","steps":["trace[8655340] 'process raft request' (duration: 137.830817ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:26.344766Z","caller":"traceutil/trace.go:171","msg":"trace[1454036550] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"117.633878ms","start":"2026-03-31T01:19:26.227087Z","end":"2026-03-31T01:19:26.344721Z","steps":["trace[1454036550] 'process raft request' (duration: 116.976783ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:38.563402Z","caller":"traceutil/trace.go:171","msg":"trace[348931376] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"105.001907ms","start":"2026-03-31T01:19:38.458374Z","end":"2026-03-31T01:19:38.563376Z","steps":["trace[348931376] 'process raft request' (duration: 104.776891ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:38.563425Z","caller":"traceutil/trace.go:171","msg":"trace[1783609593] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"104.983206ms","start":"2026-03-31T01:19:38.458424Z","end":"2026-03-31T01:19:38.563407Z","steps":["trace[1783609593] 'process raft request' (duration: 104.897884ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:38.563749Z","caller":"traceutil/trace.go:171","msg":"trace[720414679] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"104.633958ms","start":"2026-03-31T01:19:38.459093Z","end":"2026-03-31T01:19:38.563727Z","steps":["trace[720414679] 'process raft request' (duration: 104.515335ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:38.563743Z","caller":"traceutil/trace.go:171","msg":"trace[1239986878] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"105.098819ms","start":"2026-03-31T01:19:38.458615Z","end":"2026-03-31T01:19:38.563714Z","steps":["trace[1239986878] 'process raft request' (duration: 104.7531ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:40.595647Z","caller":"traceutil/trace.go:171","msg":"trace[452395932] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"100.517553ms","start":"2026-03-31T01:19:40.495106Z","end":"2026-03-31T01:19:40.595623Z","steps":["trace[452395932] 'process raft request' (duration: 23.371845ms)","trace[452395932] 'compare' (duration: 76.884401ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T01:19:40.763911Z","caller":"traceutil/trace.go:171","msg":"trace[1787268956] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"161.047553ms","start":"2026-03-31T01:19:40.602831Z","end":"2026-03-31T01:19:40.763879Z","steps":["trace[1787268956] 'process raft request' (duration: 161.004482ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:40.764068Z","caller":"traceutil/trace.go:171","msg":"trace[2005300808] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"161.308919ms","start":"2026-03-31T01:19:40.602741Z","end":"2026-03-31T01:19:40.76405Z","steps":["trace[2005300808] 'process raft request' (duration: 161.045383ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:40.764076Z","caller":"traceutil/trace.go:171","msg":"trace[2139777762] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"163.435449ms","start":"2026-03-31T01:19:40.600594Z","end":"2026-03-31T01:19:40.76403Z","steps":["trace[2139777762] 'process raft request' (duration: 102.5581ms)","trace[2139777762] 'compare' (duration: 60.407508ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T01:19:44.64657Z","caller":"traceutil/trace.go:171","msg":"trace[1942106681] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"145.384827ms","start":"2026-03-31T01:19:44.501152Z","end":"2026-03-31T01:19:44.646537Z","steps":["trace[1942106681] 'process raft request' (duration: 145.195783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:45.35095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.284706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T01:19:45.3511Z","caller":"traceutil/trace.go:171","msg":"trace[1131319893] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1306; }","duration":"516.541777ms","start":"2026-03-31T01:19:44.834543Z","end":"2026-03-31T01:19:45.351084Z","steps":["trace[1131319893] 'read index received' (duration: 162.137498ms)","trace[1131319893] 'applied index is now lower than readState.Index' (duration: 354.403409ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T01:19:45.351157Z","caller":"traceutil/trace.go:171","msg":"trace[2030882198] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"700.070064ms","start":"2026-03-31T01:19:44.651078Z","end":"2026-03-31T01:19:45.351148Z","steps":["trace[2030882198] 'process raft request' (duration: 345.515362ms)","trace[2030882198] 'compare' (duration: 354.188334ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T01:19:45.351197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:44.651022Z","time spent":"700.153506ms","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T01:19:45.351324Z","caller":"traceutil/trace.go:171","msg":"trace[1286770169] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"700.043603ms","start":"2026-03-31T01:19:44.651248Z","end":"2026-03-31T01:19:45.351292Z","steps":["trace[1286770169] 'process raft request' (duration: 699.792138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:45.351359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.795982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-31T01:19:45.351399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.533022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T01:19:45.35142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.837954ms","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-31T01:19:45.351436Z","caller":"traceutil/trace.go:171","msg":"trace[1534091319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1263; }","duration":"516.914895ms","start":"2026-03-31T01:19:44.834501Z","end":"2026-03-31T01:19:45.351415Z","steps":["trace[1534091319] 'agreement among raft nodes before linearized reading' (duration: 516.70434ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:45.351455Z","caller":"traceutil/trace.go:171","msg":"trace[1572732704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1263; }","duration":"145.634454ms","start":"2026-03-31T01:19:45.205805Z","end":"2026-03-31T01:19:45.35144Z","steps":["trace[1572732704] 'agreement among raft nodes before linearized reading' (duration: 145.545732ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:45.351469Z","caller":"traceutil/trace.go:171","msg":"trace[2061710315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1263; }","duration":"513.912935ms","start":"2026-03-31T01:19:44.837541Z","end":"2026-03-31T01:19:45.351454Z","steps":["trace[2061710315] 'agreement among raft nodes before linearized reading' (duration: 513.785632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:45.35146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:44.651228Z","time spent":"700.154616ms","remote":"127.0.0.1:58922","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-03-31T01:19:45.351484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:44.834474Z","time spent":"517.001388ms","remote":"127.0.0.1:58922","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-31T01:19:45.351505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:44.837526Z","time spent":"513.971687ms","remote":"127.0.0.1:58922","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-03-31T01:19:45.351505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.832023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T01:19:45.351537Z","caller":"traceutil/trace.go:171","msg":"trace[1365000675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1263; }","duration":"513.888075ms","start":"2026-03-31T01:19:44.83764Z","end":"2026-03-31T01:19:45.351528Z","steps":["trace[1365000675] 'agreement among raft nodes before linearized reading' (duration: 513.794253ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:45.351558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:44.83762Z","time spent":"513.932517ms","remote":"127.0.0.1:58922","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":"info","ts":"2026-03-31T01:19:45.498652Z","caller":"traceutil/trace.go:171","msg":"trace[1904229349] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"140.489533ms","start":"2026-03-31T01:19:45.358131Z","end":"2026-03-31T01:19:45.498621Z","steps":["trace[1904229349] 'process raft request' (duration: 140.434201ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:45.49875Z","caller":"traceutil/trace.go:171","msg":"trace[1430378309] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"142.341456ms","start":"2026-03-31T01:19:45.356367Z","end":"2026-03-31T01:19:45.498709Z","steps":["trace[1430378309] 'process raft request' (duration: 141.906516ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:47.405061Z","caller":"traceutil/trace.go:171","msg":"trace[1243602740] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1315; }","duration":"121.553613ms","start":"2026-03-31T01:19:47.283475Z","end":"2026-03-31T01:19:47.405029Z","steps":["trace[1243602740] 'read index received' (duration: 110.519636ms)","trace[1243602740] 'applied index is now lower than readState.Index' (duration: 11.031617ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T01:19:47.405096Z","caller":"traceutil/trace.go:171","msg":"trace[1109290128] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"143.934085ms","start":"2026-03-31T01:19:47.261137Z","end":"2026-03-31T01:19:47.405071Z","steps":["trace[1109290128] 'process raft request' (duration: 132.865337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:47.405343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.84862ms","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:507"} {"level":"info","ts":"2026-03-31T01:19:47.40543Z","caller":"traceutil/trace.go:171","msg":"trace[1569582201] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1271; }","duration":"121.972993ms","start":"2026-03-31T01:19:47.283433Z","end":"2026-03-31T01:19:47.405406Z","steps":["trace[1569582201] 'agreement among raft nodes before linearized reading' (duration: 121.687286ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:47.424391Z","caller":"traceutil/trace.go:171","msg":"trace[67007052] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"137.541875ms","start":"2026-03-31T01:19:47.286825Z","end":"2026-03-31T01:19:47.424366Z","steps":["trace[67007052] 'process raft request' (duration: 137.352101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:47.500914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.857763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T01:19:47.500989Z","caller":"traceutil/trace.go:171","msg":"trace[201399696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1272; }","duration":"143.972486ms","start":"2026-03-31T01:19:47.356998Z","end":"2026-03-31T01:19:47.50097Z","steps":["trace[201399696] 'agreement among raft nodes before linearized reading' (duration: 143.788571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:47.501191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.418889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T01:19:47.501314Z","caller":"traceutil/trace.go:171","msg":"trace[8077886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1273; }","duration":"145.600893ms","start":"2026-03-31T01:19:47.355694Z","end":"2026-03-31T01:19:47.501295Z","steps":["trace[8077886] 'agreement among raft nodes before linearized reading' (duration: 145.142983ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:47.635892Z","caller":"traceutil/trace.go:171","msg":"trace[1100389810] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"130.288196ms","start":"2026-03-31T01:19:47.505575Z","end":"2026-03-31T01:19:47.635863Z","steps":["trace[1100389810] 'process raft request' (duration: 130.133372ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:47.64727Z","caller":"traceutil/trace.go:171","msg":"trace[1740356898] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"140.314349ms","start":"2026-03-31T01:19:47.506936Z","end":"2026-03-31T01:19:47.647251Z","steps":["trace[1740356898] 'process raft request' (duration: 140.173906ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:47.647614Z","caller":"traceutil/trace.go:171","msg":"trace[1564994990] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"140.585786ms","start":"2026-03-31T01:19:47.506999Z","end":"2026-03-31T01:19:47.647585Z","steps":["trace[1564994990] 'process raft request' (duration: 140.199927ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:47.647788Z","caller":"traceutil/trace.go:171","msg":"trace[569857689] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"140.347691ms","start":"2026-03-31T01:19:47.507427Z","end":"2026-03-31T01:19:47.647775Z","steps":["trace[569857689] 'process raft request' (duration: 140.075734ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:47.64795Z","caller":"traceutil/trace.go:171","msg":"trace[1936416658] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"139.573953ms","start":"2026-03-31T01:19:47.508367Z","end":"2026-03-31T01:19:47.647941Z","steps":["trace[1936416658] 'process raft request' (duration: 139.364518ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:48.647487Z","caller":"traceutil/trace.go:171","msg":"trace[1766445740] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"159.722373ms","start":"2026-03-31T01:19:48.487734Z","end":"2026-03-31T01:19:48.647456Z","steps":["trace[1766445740] 'process raft request' (duration: 159.544729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:48.937242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.984068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T01:19:48.937354Z","caller":"traceutil/trace.go:171","msg":"trace[1296983555] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"257.143461ms","start":"2026-03-31T01:19:48.680192Z","end":"2026-03-31T01:19:48.937335Z","steps":["trace[1296983555] 'compare' (duration: 247.854375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:56.116622Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133728590791236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T01:19:56.618347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133728590791236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T01:19:56.818078Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.594760839s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T01:19:56.818593Z","caller":"traceutil/trace.go:171","msg":"trace[1693109521] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1378; }","duration":"1.202616622s","start":"2026-03-31T01:19:55.615953Z","end":"2026-03-31T01:19:56.81857Z","steps":["trace[1693109521] 'read index received' (duration: 1.202501449s)","trace[1693109521] 'applied index is now lower than readState.Index' (duration: 114.453µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T01:19:56.818627Z","caller":"traceutil/trace.go:171","msg":"trace[1604001219] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"1.595541616s","start":"2026-03-31T01:19:55.223063Z","end":"2026-03-31T01:19:56.818605Z","steps":["trace[1604001219] 'process raft request' (duration: 1.595390893s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:56.818772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:55.223033Z","time spent":"1.595657289s","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T01:19:56.818805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202839407s","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-03-31T01:19:56.818859Z","caller":"traceutil/trace.go:171","msg":"trace[1193515063] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1332; }","duration":"1.202927089s","start":"2026-03-31T01:19:55.615911Z","end":"2026-03-31T01:19:56.818838Z","steps":["trace[1193515063] 'agreement among raft nodes before linearized reading' (duration: 1.202791036s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:56.818908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:55.615888Z","time spent":"1.203006721s","remote":"127.0.0.1:58922","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\" "} {"level":"warn","ts":"2026-03-31T01:19:56.819147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.637493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T01:19:56.819181Z","caller":"traceutil/trace.go:171","msg":"trace[1825314699] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1332; }","duration":"630.345889ms","start":"2026-03-31T01:19:56.188826Z","end":"2026-03-31T01:19:56.819172Z","steps":["trace[1825314699] 'agreement among raft nodes before linearized reading' (duration: 630.280887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:56.81921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:56.188806Z","time spent":"630.39613ms","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":13,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-03-31T01:19:56.819307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.952155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T01:19:56.819515Z","caller":"traceutil/trace.go:171","msg":"trace[2085624475] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1332; }","duration":"416.20211ms","start":"2026-03-31T01:19:56.4033Z","end":"2026-03-31T01:19:56.819502Z","steps":["trace[2085624475] 'agreement among raft nodes before linearized reading' (duration: 415.979285ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:56.819543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:56.403275Z","time spent":"416.260331ms","remote":"127.0.0.1:47270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-31T01:19:56.819542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.481531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T01:19:56.819651Z","caller":"traceutil/trace.go:171","msg":"trace[1568057482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1332; }","duration":"339.632485ms","start":"2026-03-31T01:19:56.479986Z","end":"2026-03-31T01:19:56.819619Z","steps":["trace[1568057482] 'agreement among raft nodes before linearized reading' (duration: 339.271937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:56.819704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:56.479954Z","time spent":"339.740318ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T01:19:56.819758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106648476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-31T01:19:56.819786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106633636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T01:19:56.8198Z","caller":"traceutil/trace.go:171","msg":"trace[2067580424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1332; }","duration":"1.106715088s","start":"2026-03-31T01:19:55.713073Z","end":"2026-03-31T01:19:56.819788Z","steps":["trace[2067580424] 'agreement among raft nodes before linearized reading' (duration: 1.106597255s)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:19:56.819818Z","caller":"traceutil/trace.go:171","msg":"trace[783242586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1332; }","duration":"1.106697037s","start":"2026-03-31T01:19:55.713112Z","end":"2026-03-31T01:19:56.819809Z","steps":["trace[783242586] 'agreement among raft nodes before linearized reading' (duration: 1.106602795s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:56.81984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:55.713095Z","time spent":"1.106740638s","remote":"127.0.0.1:58922","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-31T01:19:56.819834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:55.713062Z","time spent":"1.106765699s","remote":"127.0.0.1:58922","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-31T01:19:56.819872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.982225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-31T01:19:56.819912Z","caller":"traceutil/trace.go:171","msg":"trace[312458022] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1332; }","duration":"307.055096ms","start":"2026-03-31T01:19:56.512847Z","end":"2026-03-31T01:19:56.819902Z","steps":["trace[312458022] 'agreement among raft nodes before linearized reading' (duration: 306.957343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:56.819944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:56.512824Z","time spent":"307.110437ms","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-31T01:19:56.819973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112614915s","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-31T01:19:56.82Z","caller":"traceutil/trace.go:171","msg":"trace[1185339815] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1332; }","duration":"1.112671817s","start":"2026-03-31T01:19:55.70732Z","end":"2026-03-31T01:19:56.819992Z","steps":["trace[1185339815] 'agreement among raft nodes before linearized reading' (duration: 1.112593565s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:56.819992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112214836s","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-03-31T01:19:56.820036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:55.707261Z","time spent":"1.112768329s","remote":"127.0.0.1:58922","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":"info","ts":"2026-03-31T01:19:56.82007Z","caller":"traceutil/trace.go:171","msg":"trace[1364585140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1332; }","duration":"1.112314168s","start":"2026-03-31T01:19:55.707735Z","end":"2026-03-31T01:19:56.82005Z","steps":["trace[1364585140] 'agreement among raft nodes before linearized reading' (duration: 1.112075232s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:56.820125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:55.70773Z","time spent":"1.11238734s","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T01:19:56.822538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127634105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T01:19:56.822575Z","caller":"traceutil/trace.go:171","msg":"trace[977695942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1332; }","duration":"1.127706407s","start":"2026-03-31T01:19:55.69486Z","end":"2026-03-31T01:19:56.822566Z","steps":["trace[977695942] 'agreement among raft nodes before linearized reading' (duration: 1.12439652s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:19:56.822598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T01:19:55.694844Z","time spent":"1.127748158s","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T01:20:44.823578Z","caller":"traceutil/trace.go:171","msg":"trace[207657457] transaction","detail":"{read_only:false; response_revision:1555; number_of_response:1; }","duration":"111.978604ms","start":"2026-03-31T01:20:44.71157Z","end":"2026-03-31T01:20:44.823549Z","steps":["trace[207657457] 'process raft request' (duration: 111.79014ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:20:59.372732Z","caller":"traceutil/trace.go:171","msg":"trace[1179444261] linearizableReadLoop","detail":"{readStateIndex:1683; appliedIndex:1681; }","duration":"124.988681ms","start":"2026-03-31T01:20:59.247719Z","end":"2026-03-31T01:20:59.372708Z","steps":["trace[1179444261] 'read index received' (duration: 18.588889ms)","trace[1179444261] 'applied index is now lower than readState.Index' (duration: 106.398762ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T01:20:59.372758Z","caller":"traceutil/trace.go:171","msg":"trace[176219099] transaction","detail":"{read_only:false; response_revision:1624; number_of_response:1; }","duration":"111.389559ms","start":"2026-03-31T01:20:59.261343Z","end":"2026-03-31T01:20:59.372732Z","steps":["trace[176219099] 'process raft request' (duration: 111.319988ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:20:59.372955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.211296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-31T01:20:59.372998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.313414ms","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-31T01:20:59.373042Z","caller":"traceutil/trace.go:171","msg":"trace[84131170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1624; }","duration":"121.393605ms","start":"2026-03-31T01:20:59.251638Z","end":"2026-03-31T01:20:59.373031Z","steps":["trace[84131170] 'agreement among raft nodes before linearized reading' (duration: 121.251732ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:20:59.373006Z","caller":"traceutil/trace.go:171","msg":"trace[909534553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1624; }","duration":"125.284618ms","start":"2026-03-31T01:20:59.247707Z","end":"2026-03-31T01:20:59.372991Z","steps":["trace[909534553] 'agreement among raft nodes before linearized reading' (duration: 125.134574ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T01:20:59.373124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.973021ms","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-31T01:20:59.373165Z","caller":"traceutil/trace.go:171","msg":"trace[2130079966] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1624; }","duration":"106.039033ms","start":"2026-03-31T01:20:59.267114Z","end":"2026-03-31T01:20:59.373153Z","steps":["trace[2130079966] 'agreement among raft nodes before linearized reading' (duration: 105.932411ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:20:59.373359Z","caller":"traceutil/trace.go:171","msg":"trace[1616510090] transaction","detail":"{read_only:false; response_revision:1623; number_of_response:1; }","duration":"139.494243ms","start":"2026-03-31T01:20:59.233814Z","end":"2026-03-31T01:20:59.373308Z","steps":["trace[1616510090] 'process raft request' (duration: 137.085866ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:22:11.985525Z","caller":"traceutil/trace.go:171","msg":"trace[751824396] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"103.393309ms","start":"2026-03-31T01:22:11.882101Z","end":"2026-03-31T01:22:11.985494Z","steps":["trace[751824396] 'process raft request' (duration: 103.269826ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:22:11.985656Z","caller":"traceutil/trace.go:171","msg":"trace[838099241] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"101.279534ms","start":"2026-03-31T01:22:11.884351Z","end":"2026-03-31T01:22:11.98563Z","steps":["trace[838099241] 'process raft request' (duration: 101.084689ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:22:11.985546Z","caller":"traceutil/trace.go:171","msg":"trace[1077775593] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"104.677716ms","start":"2026-03-31T01:22:11.880822Z","end":"2026-03-31T01:22:11.985499Z","steps":["trace[1077775593] 'process raft request' (duration: 32.827951ms)","trace[1077775593] 'compare' (duration: 71.551408ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T01:23:04.041896Z","caller":"traceutil/trace.go:171","msg":"trace[192084960] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"185.791836ms","start":"2026-03-31T01:23:03.856074Z","end":"2026-03-31T01:23:04.041866Z","steps":["trace[192084960] 'process raft request' (duration: 185.613042ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:23:04.042439Z","caller":"traceutil/trace.go:171","msg":"trace[1802732372] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"159.287987ms","start":"2026-03-31T01:23:03.883126Z","end":"2026-03-31T01:23:04.042414Z","steps":["trace[1802732372] 'process raft request' (duration: 159.180034ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T01:23:10.507662Z","caller":"traceutil/trace.go:171","msg":"trace[1053709518] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"117.103713ms","start":"2026-03-31T01:23:10.390527Z","end":"2026-03-31T01:23:10.507631Z","steps":["trace[1053709518] 'process raft request' (duration: 103.406564ms)","trace[1053709518] 'compare' (duration: 13.550506ms)"],"step_count":2}