{"level":"warn","ts":"2026-07-30T23:43:26.103705Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T23:43:26.103817Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238: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.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-07-30T23:43:26.103892Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T23:43:26.103908Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-07-30T23:43:26.103933Z","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-07-30T23:43:26.104754Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-07-30T23:43:26.104861Z","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.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-07-30T23:43:26.108705Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.594785ms"} {"level":"info","ts":"2026-07-30T23:43:26.114077Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-07-30T23:43:26.114152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T23:43:26.114176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-07-30T23:43:26.11419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T23:43:26.114205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-07-30T23:43:26.114241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-07-30T23:43:26.126489Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T23:43:26.127658Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T23:43:26.12866Z","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-07-30T23:43:26.130502Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T23:43:26.130977Z","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-07-30T23:43:26.131123Z","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-07-30T23:43:26.131134Z","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-07-30T23:43:26.131169Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T23:43:26.132781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-07-30T23:43:26.133052Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-07-30T23:43:26.13625Z","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-07-30T23:43:26.136392Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-07-30T23:43:26.136456Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-07-30T23:43:26.136659Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T23:43:26.136751Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T23:43:26.414585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T23:43:26.414699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T23:43:26.414739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-07-30T23:43:26.414815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-07-30T23:43:26.414964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-07-30T23:43:26.415066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-07-30T23:43:26.415156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-07-30T23:43:26.41959Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:43:26.420312Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T23:43:26.420302Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T23:43:26.420468Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T23:43:26.420854Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T23:43:26.420917Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T23:43:26.421615Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:43:26.421813Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:43:26.421907Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:43:26.422958Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T23:43:26.423594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"warn","ts":"2026-07-30T23:43:28.258059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.612363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-30T23:43:28.258296Z","caller":"traceutil/trace.go:171","msg":"trace[163842699] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"494.941845ms","start":"2026-07-30T23:43:27.763312Z","end":"2026-07-30T23:43:28.258253Z","steps":["trace[163842699] 'process raft request' (duration: 257.161154ms)","trace[163842699] 'compare' (duration: 236.442369ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:43:28.258537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:27.763298Z","time spent":"495.16418ms","remote":"127.0.0.1:38444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:43:28.258602Z","caller":"traceutil/trace.go:171","msg":"trace[2142016746] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"448.781894ms","start":"2026-07-30T23:43:27.809798Z","end":"2026-07-30T23:43:28.25858Z","steps":["trace[2142016746] 'process raft request' (duration: 448.523688ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:28.259325Z","caller":"traceutil/trace.go:171","msg":"trace[714506799] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"442.512814ms","start":"2026-07-30T23:43:27.816797Z","end":"2026-07-30T23:43:28.25931Z","steps":["trace[714506799] 'process raft request' (duration: 442.441033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:28.259323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:27.809789Z","time spent":"449.442509ms","remote":"127.0.0.1:38508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:43:28.258691Z","caller":"traceutil/trace.go:171","msg":"trace[1526039260] linearizableReadLoop","detail":"{readStateIndex:17; appliedIndex:7; }","duration":"467.90746ms","start":"2026-07-30T23:43:27.790759Z","end":"2026-07-30T23:43:28.258666Z","steps":["trace[1526039260] 'read index received' (duration: 8.996234ms)","trace[1526039260] 'applied index is now lower than readState.Index' (duration: 458.909496ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:43:28.258571Z","caller":"traceutil/trace.go:171","msg":"trace[1284379897] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"450.147727ms","start":"2026-07-30T23:43:27.808352Z","end":"2026-07-30T23:43:28.258499Z","steps":["trace[1284379897] 'process raft request' (duration: 449.821679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:28.259703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:27.80833Z","time spent":"451.338735ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:43:28.25983Z","caller":"traceutil/trace.go:171","msg":"trace[1447066305] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"449.144313ms","start":"2026-07-30T23:43:27.810679Z","end":"2026-07-30T23:43:28.259823Z","steps":["trace[1447066305] 'process raft request' (duration: 448.455776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:28.25988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:27.810667Z","time spent":"449.191493ms","remote":"127.0.0.1:38508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:43:28.2589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.115916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-07-30T23:43:28.260013Z","caller":"traceutil/trace.go:171","msg":"trace[1203935052] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:16; }","duration":"469.282563ms","start":"2026-07-30T23:43:27.790716Z","end":"2026-07-30T23:43:28.259998Z","steps":["trace[1203935052] 'agreement among raft nodes before linearized reading' (duration: 468.042673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:28.260031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.118931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-30T23:43:28.260097Z","caller":"traceutil/trace.go:171","msg":"trace[1636540895] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:21; }","duration":"249.212024ms","start":"2026-07-30T23:43:28.010871Z","end":"2026-07-30T23:43:28.260083Z","steps":["trace[1636540895] 'agreement among raft nodes before linearized reading' (duration: 249.137242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:28.260108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.6169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-30T23:43:28.260148Z","caller":"traceutil/trace.go:171","msg":"trace[1752990245] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:21; }","duration":"448.693282ms","start":"2026-07-30T23:43:27.811444Z","end":"2026-07-30T23:43:28.260137Z","steps":["trace[1752990245] 'agreement among raft nodes before linearized reading' (duration: 448.61561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:28.260143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.69151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-lqb4p\" ","response":"range_response_count:1 size:861"} {"level":"warn","ts":"2026-07-30T23:43:28.260219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.386002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-30T23:43:28.260246Z","caller":"traceutil/trace.go:171","msg":"trace[1634504519] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:21; }","duration":"341.457654ms","start":"2026-07-30T23:43:27.918779Z","end":"2026-07-30T23:43:28.260236Z","steps":["trace[1634504519] 'agreement among raft nodes before linearized reading' (duration: 341.422774ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:28.260223Z","caller":"traceutil/trace.go:171","msg":"trace[74333020] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-lqb4p; range_end:; response_count:1; response_revision:21; }","duration":"450.799132ms","start":"2026-07-30T23:43:27.809412Z","end":"2026-07-30T23:43:28.260211Z","steps":["trace[74333020] 'agreement among raft nodes before linearized reading' (duration: 450.682399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:28.26027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:27.918747Z","time spent":"341.516675ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-07-30T23:43:28.260286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:27.809399Z","time spent":"450.877134ms","remote":"127.0.0.1:38234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":885,"request content":"key:\"/registry/certificatesigningrequests/csr-lqb4p\" "} {"level":"warn","ts":"2026-07-30T23:43:28.260182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:27.811389Z","time spent":"448.785494ms","remote":"127.0.0.1:38080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"info","ts":"2026-07-30T23:43:28.260488Z","caller":"traceutil/trace.go:171","msg":"trace[2041696547] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"448.568379ms","start":"2026-07-30T23:43:27.811908Z","end":"2026-07-30T23:43:28.260476Z","steps":["trace[2041696547] 'process raft request' (duration: 447.282638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:28.260578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:27.811899Z","time spent":"448.649071ms","remote":"127.0.0.1:38508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:43:28.259617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:27.816788Z","time spent":"442.581376ms","remote":"127.0.0.1:38132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:43:28.259953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.652863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-07-30T23:43:28.260835Z","caller":"traceutil/trace.go:171","msg":"trace[391146319] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"452.620635ms","start":"2026-07-30T23:43:27.808202Z","end":"2026-07-30T23:43:28.260823Z","steps":["trace[391146319] 'agreement among raft nodes before linearized reading' (duration: 451.581311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:28.260885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:27.808159Z","time spent":"452.706997ms","remote":"127.0.0.1:38092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-30T23:43:28.258902Z","caller":"traceutil/trace.go:171","msg":"trace[974218559] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"448.891126ms","start":"2026-07-30T23:43:27.809999Z","end":"2026-07-30T23:43:28.25889Z","steps":["trace[974218559] 'process raft request' (duration: 448.389554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:28.261182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:27.809988Z","time spent":"451.16815ms","remote":"127.0.0.1:38508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:43:28.259746Z","caller":"traceutil/trace.go:171","msg":"trace[1264820433] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"450.102555ms","start":"2026-07-30T23:43:27.809629Z","end":"2026-07-30T23:43:28.259732Z","steps":["trace[1264820433] 'process raft request' (duration: 448.792104ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:28.261379Z","caller":"traceutil/trace.go:171","msg":"trace[145261234] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"449.847369ms","start":"2026-07-30T23:43:27.811524Z","end":"2026-07-30T23:43:28.261372Z","steps":["trace[145261234] 'process raft request' (duration: 447.638857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:28.261423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:27.811515Z","time spent":"449.88296ms","remote":"127.0.0.1:38508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:43:28.261408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:27.809615Z","time spent":"451.708573ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:43:28.262083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:27.790703Z","time spent":"469.409256ms","remote":"127.0.0.1:38092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-30T23:43:33.229292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.335292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T23:43:33.229383Z","caller":"traceutil/trace.go:171","msg":"trace[831906965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:280; }","duration":"468.483425ms","start":"2026-07-30T23:43:32.760881Z","end":"2026-07-30T23:43:33.229364Z","steps":["trace[831906965] 'range keys from in-memory index tree' (duration: 468.202348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:33.229378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.701712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-07-30T23:43:33.229444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:32.760856Z","time spent":"468.566097ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T23:43:33.229492Z","caller":"traceutil/trace.go:171","msg":"trace[1286122116] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:280; }","duration":"632.848946ms","start":"2026-07-30T23:43:32.596616Z","end":"2026-07-30T23:43:33.229465Z","steps":["trace[1286122116] 'range keys from in-memory index tree' (duration: 632.60035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:33.229551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:32.596598Z","time spent":"632.940458ms","remote":"127.0.0.1:38092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-30T23:43:33.680968Z","caller":"traceutil/trace.go:171","msg":"trace[2015876390] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"447.972066ms","start":"2026-07-30T23:43:33.232967Z","end":"2026-07-30T23:43:33.680939Z","steps":["trace[2015876390] 'process raft request' (duration: 447.834943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:33.681122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:33.232944Z","time spent":"448.109289ms","remote":"127.0.0.1:38170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:43:34.311694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.503322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:43:34.311803Z","caller":"traceutil/trace.go:171","msg":"trace[1992498052] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:292; }","duration":"627.458887ms","start":"2026-07-30T23:43:33.684329Z","end":"2026-07-30T23:43:34.311788Z","steps":["trace[1992498052] 'read index received' (duration: 94.728679ms)","trace[1992498052] 'applied index is now lower than readState.Index' (duration: 532.728918ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:43:34.311885Z","caller":"traceutil/trace.go:171","msg":"trace[314081508] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"1.077761288s","start":"2026-07-30T23:43:33.234114Z","end":"2026-07-30T23:43:34.311875Z","steps":["trace[314081508] 'process raft request' (duration: 544.991339ms)","trace[314081508] 'compare' (duration: 532.38959ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:43:34.311951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:33.23409Z","time spent":"1.07782914s","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:43:34.312033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.866318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-30T23:43:34.312068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.710023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:43:34.312114Z","caller":"traceutil/trace.go:171","msg":"trace[1720536032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:282; }","duration":"124.992521ms","start":"2026-07-30T23:43:34.187102Z","end":"2026-07-30T23:43:34.312094Z","steps":["trace[1720536032] 'agreement among raft nodes before linearized reading' (duration: 124.867258ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:34.312155Z","caller":"traceutil/trace.go:171","msg":"trace[568456910] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:282; }","duration":"627.851746ms","start":"2026-07-30T23:43:33.684283Z","end":"2026-07-30T23:43:34.312135Z","steps":["trace[568456910] 'agreement among raft nodes before linearized reading' (duration: 627.683792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:34.312208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:33.684263Z","time spent":"627.936889ms","remote":"127.0.0.1:38170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" "} {"level":"warn","ts":"2026-07-30T23:43:34.820347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534095095768219,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T23:43:35.036406Z","caller":"traceutil/trace.go:171","msg":"trace[815208991] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"720.190518ms","start":"2026-07-30T23:43:34.316186Z","end":"2026-07-30T23:43:35.036377Z","steps":["trace[815208991] 'process raft request' (duration: 686.581457ms)","trace[815208991] 'compare' (duration: 33.459668ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:43:35.036567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:34.316168Z","time spent":"720.315622ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:43:35.703801Z","caller":"traceutil/trace.go:171","msg":"trace[346139039] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:293; }","duration":"1.384241347s","start":"2026-07-30T23:43:34.319539Z","end":"2026-07-30T23:43:35.70378Z","steps":["trace[346139039] 'read index received' (duration: 683.156825ms)","trace[346139039] 'applied index is now lower than readState.Index' (duration: 701.083552ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:43:35.703867Z","caller":"traceutil/trace.go:171","msg":"trace[844870206] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"1.385266552s","start":"2026-07-30T23:43:34.31856Z","end":"2026-07-30T23:43:35.703826Z","steps":["trace[844870206] 'process raft request' (duration: 1.385104888s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:35.704008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:34.318548Z","time spent":"1.385392305s","remote":"127.0.0.1:38170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:43:35.704035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37721523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-30T23:43:35.704035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384485413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-30T23:43:35.704073Z","caller":"traceutil/trace.go:171","msg":"trace[1594355993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:284; }","duration":"1.377292672s","start":"2026-07-30T23:43:34.32677Z","end":"2026-07-30T23:43:35.704063Z","steps":["trace[1594355993] 'agreement among raft nodes before linearized reading' (duration: 1.377158478s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:35.704086Z","caller":"traceutil/trace.go:171","msg":"trace[209882557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:284; }","duration":"1.384600366s","start":"2026-07-30T23:43:34.319473Z","end":"2026-07-30T23:43:35.704074Z","steps":["trace[209882557] 'agreement among raft nodes before linearized reading' (duration: 1.384453612s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:35.704109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:34.326764Z","time spent":"1.377337523s","remote":"127.0.0.1:38238","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-07-30T23:43:35.704128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:34.319448Z","time spent":"1.384672838s","remote":"127.0.0.1:38238","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-07-30T23:44:03.715748Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534095095768635,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T23:44:04.124704Z","caller":"traceutil/trace.go:171","msg":"trace[2135847527] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:505; }","duration":"910.034936ms","start":"2026-07-30T23:44:03.214645Z","end":"2026-07-30T23:44:04.12468Z","steps":["trace[2135847527] 'read index received' (duration: 909.837961ms)","trace[2135847527] 'applied index is now lower than readState.Index' (duration: 195.855µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:44:04.124804Z","caller":"traceutil/trace.go:171","msg":"trace[1935861751] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"933.185158ms","start":"2026-07-30T23:44:03.191591Z","end":"2026-07-30T23:44:04.124776Z","steps":["trace[1935861751] 'process raft request' (duration: 932.950503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:04.124933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.436807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:44:04.124984Z","caller":"traceutil/trace.go:171","msg":"trace[486071773] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:487; }","duration":"773.54501ms","start":"2026-07-30T23:44:03.351426Z","end":"2026-07-30T23:44:04.124971Z","steps":["trace[486071773] 'agreement among raft nodes before linearized reading' (duration: 773.411007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:04.124974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.984424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:44:04.125008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.999906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-30T23:44:04.125036Z","caller":"traceutil/trace.go:171","msg":"trace[251831902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:487; }","duration":"282.077257ms","start":"2026-07-30T23:44:03.84295Z","end":"2026-07-30T23:44:04.125027Z","steps":["trace[251831902] 'agreement among raft nodes before linearized reading' (duration: 282.018256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:04.12503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:03.351404Z","time spent":"773.616142ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-07-30T23:44:04.12506Z","caller":"traceutil/trace.go:171","msg":"trace[1866537930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"209.095956ms","start":"2026-07-30T23:44:03.915931Z","end":"2026-07-30T23:44:04.125027Z","steps":["trace[1866537930] 'agreement among raft nodes before linearized reading' (duration: 208.960203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:04.124939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:03.191572Z","time spent":"933.294511ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:44:04.125227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.528588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:44:04.12554Z","caller":"traceutil/trace.go:171","msg":"trace[1748441689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"910.681641ms","start":"2026-07-30T23:44:03.214638Z","end":"2026-07-30T23:44:04.12532Z","steps":["trace[1748441689] 'agreement among raft nodes before linearized reading' (duration: 910.175099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:04.125618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:03.214587Z","time spent":"911.0132ms","remote":"127.0.0.1:37996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T23:44:04.125396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.064659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-30T23:44:04.125855Z","caller":"traceutil/trace.go:171","msg":"trace[362913482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:487; }","duration":"267.635613ms","start":"2026-07-30T23:44:03.858192Z","end":"2026-07-30T23:44:04.125827Z","steps":["trace[362913482] 'agreement among raft nodes before linearized reading' (duration: 266.945727ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:44:04.263634Z","caller":"traceutil/trace.go:171","msg":"trace[1997309145] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"133.830632ms","start":"2026-07-30T23:44:04.129775Z","end":"2026-07-30T23:44:04.263606Z","steps":["trace[1997309145] 'process raft request' (duration: 68.35083ms)","trace[1997309145] 'compare' (duration: 65.300087ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:44:04.277574Z","caller":"traceutil/trace.go:171","msg":"trace[113511756] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"147.441137ms","start":"2026-07-30T23:44:04.130098Z","end":"2026-07-30T23:44:04.27754Z","steps":["trace[113511756] 'process raft request' (duration: 146.841093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:05.416757Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534095095768643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:44:05.769553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:04.883769Z","time spent":"885.752828ms","remote":"127.0.0.1:59114","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-30T23:44:05.770209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.573861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:44:05.770589Z","caller":"traceutil/trace.go:171","msg":"trace[1324638123] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.269719967s","start":"2026-07-30T23:44:04.500825Z","end":"2026-07-30T23:44:05.770545Z","steps":["trace[1324638123] 'process raft request' (duration: 1.269496772s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:44:05.770639Z","caller":"traceutil/trace.go:171","msg":"trace[43923280] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.242879017s","start":"2026-07-30T23:44:04.527721Z","end":"2026-07-30T23:44:05.7706Z","steps":["trace[43923280] 'process raft request' (duration: 1.242723613s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:05.770787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:04.527703Z","time spent":"1.24298845s","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:44:05.770795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:04.500803Z","time spent":"1.269887801s","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:44:05.770902Z","caller":"traceutil/trace.go:171","msg":"trace[2070191643] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.239220929s","start":"2026-07-30T23:44:04.53167Z","end":"2026-07-30T23:44:05.770891Z","steps":["trace[2070191643] 'process raft request' (duration: 1.2388135s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:44:05.770898Z","caller":"traceutil/trace.go:171","msg":"trace[1517913992] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.268316084s","start":"2026-07-30T23:44:04.502543Z","end":"2026-07-30T23:44:05.770859Z","steps":["trace[1517913992] 'process raft request' (duration: 1.267854303s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:05.770951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:04.531662Z","time spent":"1.23926373s","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:44:05.771064Z","caller":"traceutil/trace.go:171","msg":"trace[704335200] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.239293351s","start":"2026-07-30T23:44:04.531739Z","end":"2026-07-30T23:44:05.771033Z","steps":["trace[704335200] 'process raft request' (duration: 1.238780219s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:05.771061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:04.502528Z","time spent":"1.268449557s","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:44:05.77111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:04.531735Z","time spent":"1.239352062s","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:44:05.771215Z","caller":"traceutil/trace.go:171","msg":"trace[321045500] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.230677046s","start":"2026-07-30T23:44:04.540526Z","end":"2026-07-30T23:44:05.771203Z","steps":["trace[321045500] 'process raft request' (duration: 1.230477491s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:44:05.771169Z","caller":"traceutil/trace.go:171","msg":"trace[1383189081] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.230681516s","start":"2026-07-30T23:44:04.540453Z","end":"2026-07-30T23:44:05.771134Z","steps":["trace[1383189081] 'process raft request' (duration: 1.230108453s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:44:05.771206Z","caller":"traceutil/trace.go:171","msg":"trace[455906464] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.22417092s","start":"2026-07-30T23:44:04.547014Z","end":"2026-07-30T23:44:05.771184Z","steps":["trace[455906464] 'process raft request' (duration: 1.224116179s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:05.771261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:04.540505Z","time spent":"1.230734357s","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:44:05.7713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:04.546973Z","time spent":"1.224291073s","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:44:05.771382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:04.540444Z","time spent":"1.230813959s","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:44:05.77152Z","caller":"traceutil/trace.go:171","msg":"trace[2039154613] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.276364046s","start":"2026-07-30T23:44:04.494207Z","end":"2026-07-30T23:44:05.770571Z","steps":["trace[2039154613] 'process raft request' (duration: 1.042345784s)","trace[2039154613] 'compare' (duration: 232.734261ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:44:05.77184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:04.494187Z","time spent":"1.277462152s","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:44:05.825705Z","caller":"traceutil/trace.go:171","msg":"trace[1195624850] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.238310548s","start":"2026-07-30T23:44:04.58736Z","end":"2026-07-30T23:44:05.825671Z","steps":["trace[1195624850] 'process raft request' (duration: 1.236556936s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:05.825847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:04.587316Z","time spent":"1.238476312s","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:44:05.826298Z","caller":"traceutil/trace.go:171","msg":"trace[504669331] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.262702209s","start":"2026-07-30T23:44:04.56142Z","end":"2026-07-30T23:44:05.824122Z","steps":["trace[504669331] 'process raft request' (duration: 1.262121065s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:05.826343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:04.561395Z","time spent":"1.264927262s","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:44:05.83659Z","caller":"traceutil/trace.go:171","msg":"trace[656504567] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"1.138985538s","start":"2026-07-30T23:44:04.697583Z","end":"2026-07-30T23:44:05.836569Z","steps":["trace[656504567] 'process raft request' (duration: 1.138114537s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:05.837814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:04.697552Z","time spent":"1.140151996s","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:44:05.837121Z","caller":"traceutil/trace.go:171","msg":"trace[885245776] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:509; }","duration":"921.305686ms","start":"2026-07-30T23:44:04.915776Z","end":"2026-07-30T23:44:05.837082Z","steps":["trace[885245776] 'read index received' (duration: 620.955832ms)","trace[885245776] 'applied index is now lower than readState.Index' (duration: 300.347494ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:44:05.837208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.427689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:44:05.837896Z","caller":"traceutil/trace.go:171","msg":"trace[1826482613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"922.134375ms","start":"2026-07-30T23:44:04.915743Z","end":"2026-07-30T23:44:05.837877Z","steps":["trace[1826482613] 'agreement among raft nodes before linearized reading' (duration: 921.415868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:05.837963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:04.915729Z","time spent":"922.212258ms","remote":"127.0.0.1:37996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T23:44:12.674692Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534095095768879,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T23:44:12.744723Z","caller":"traceutil/trace.go:171","msg":"trace[42029590] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"657.494203ms","start":"2026-07-30T23:44:12.087207Z","end":"2026-07-30T23:44:12.744701Z","steps":["trace[42029590] 'process raft request' (duration: 656.989561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:12.744874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:12.087177Z","time spent":"657.613416ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:44:12.778371Z","caller":"traceutil/trace.go:171","msg":"trace[1114087595] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:614; }","duration":"604.10981ms","start":"2026-07-30T23:44:12.17424Z","end":"2026-07-30T23:44:12.77835Z","steps":["trace[1114087595] 'read index received' (duration: 569.75569ms)","trace[1114087595] 'applied index is now lower than readState.Index' (duration: 34.35343ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:44:12.77853Z","caller":"traceutil/trace.go:171","msg":"trace[952032864] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"677.058819ms","start":"2026-07-30T23:44:12.101441Z","end":"2026-07-30T23:44:12.7785Z","steps":["trace[952032864] 'process raft request' (duration: 676.797583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:12.778644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:12.10142Z","time spent":"677.142572ms","remote":"127.0.0.1:38146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:44:12.778757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.563332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-30T23:44:12.778824Z","caller":"traceutil/trace.go:171","msg":"trace[1196247696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"415.676965ms","start":"2026-07-30T23:44:12.363132Z","end":"2026-07-30T23:44:12.778809Z","steps":["trace[1196247696] 'agreement among raft nodes before linearized reading' (duration: 415.505651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:12.778867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:12.363113Z","time spent":"415.745557ms","remote":"127.0.0.1:38238","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-07-30T23:44:12.779052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.826767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-30T23:44:12.779078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.288496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-30T23:44:12.779113Z","caller":"traceutil/trace.go:171","msg":"trace[1845112489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"415.362338ms","start":"2026-07-30T23:44:12.363742Z","end":"2026-07-30T23:44:12.779104Z","steps":["trace[1845112489] 'agreement among raft nodes before linearized reading' (duration: 415.276256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:12.779143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:12.363721Z","time spent":"415.416129ms","remote":"127.0.0.1:38238","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-07-30T23:44:12.779257Z","caller":"traceutil/trace.go:171","msg":"trace[1935557700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"604.94095ms","start":"2026-07-30T23:44:12.174153Z","end":"2026-07-30T23:44:12.779094Z","steps":["trace[1935557700] 'agreement among raft nodes before linearized reading' (duration: 604.802386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:12.779336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:12.174117Z","time spent":"605.211486ms","remote":"127.0.0.1:38238","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-07-30T23:44:12.779209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.115833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T23:44:12.779446Z","caller":"traceutil/trace.go:171","msg":"trace[1300121244] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"187.535593ms","start":"2026-07-30T23:44:12.591901Z","end":"2026-07-30T23:44:12.779436Z","steps":["trace[1300121244] 'agreement among raft nodes before linearized reading' (duration: 186.745374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:14.329215Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.06272469s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T23:44:14.331291Z","caller":"traceutil/trace.go:171","msg":"trace[1859640513] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.06569971s","start":"2026-07-30T23:44:13.26557Z","end":"2026-07-30T23:44:14.33127Z","steps":["trace[1859640513] 'process raft request' (duration: 1.06401163s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:44:14.331251Z","caller":"traceutil/trace.go:171","msg":"trace[497866772] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"414.949728ms","start":"2026-07-30T23:44:13.91628Z","end":"2026-07-30T23:44:14.33123Z","steps":["trace[497866772] 'read index received' (duration: 413.027432ms)","trace[497866772] 'applied index is now lower than readState.Index' (duration: 1.921256ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:44:14.331722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:13.265541Z","time spent":"1.065783642s","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:44:14.331826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.530502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:44:14.331923Z","caller":"traceutil/trace.go:171","msg":"trace[1666375168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"415.613474ms","start":"2026-07-30T23:44:13.916268Z","end":"2026-07-30T23:44:14.331881Z","steps":["trace[1666375168] 'agreement among raft nodes before linearized reading' (duration: 415.108452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:14.331965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:13.916193Z","time spent":"415.763387ms","remote":"127.0.0.1:37996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T23:44:15.450219Z","caller":"traceutil/trace.go:171","msg":"trace[394114302] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"159.942615ms","start":"2026-07-30T23:44:15.290244Z","end":"2026-07-30T23:44:15.450187Z","steps":["trace[394114302] 'process raft request' (duration: 159.272029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:31.561245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.510934ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:44:31.561557Z","caller":"traceutil/trace.go:171","msg":"trace[300894223] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:768; }","duration":"693.845803ms","start":"2026-07-30T23:44:30.867691Z","end":"2026-07-30T23:44:31.561536Z","steps":["trace[300894223] 'range keys from in-memory index tree' (duration: 693.491484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:31.561575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.760074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:44:31.561659Z","caller":"traceutil/trace.go:171","msg":"trace[1576886554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"646.905208ms","start":"2026-07-30T23:44:30.914736Z","end":"2026-07-30T23:44:31.561641Z","steps":["trace[1576886554] 'range keys from in-memory index tree' (duration: 646.635432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:31.561706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:30.914704Z","time spent":"646.993931ms","remote":"127.0.0.1:38004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T23:44:31.561679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.441548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-30T23:44:31.561759Z","caller":"traceutil/trace.go:171","msg":"trace[936842370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"621.547991ms","start":"2026-07-30T23:44:30.940192Z","end":"2026-07-30T23:44:31.56174Z","steps":["trace[936842370] 'range keys from in-memory index tree' (duration: 621.312706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:31.561737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.913471ms","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-07-30T23:44:31.5618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:30.940172Z","time spent":"621.620393ms","remote":"127.0.0.1:38238","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-07-30T23:44:31.561816Z","caller":"traceutil/trace.go:171","msg":"trace[1900952320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:768; }","duration":"597.073385ms","start":"2026-07-30T23:44:30.964725Z","end":"2026-07-30T23:44:31.561798Z","steps":["trace[1900952320] 'range keys from in-memory index tree' (duration: 596.742067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:31.561856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:30.964703Z","time spent":"597.144766ms","remote":"127.0.0.1:38238","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-07-30T23:44:31.561957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.225574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T23:44:31.562068Z","caller":"traceutil/trace.go:171","msg":"trace[1471725645] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"363.354239ms","start":"2026-07-30T23:44:31.198699Z","end":"2026-07-30T23:44:31.562054Z","steps":["trace[1471725645] 'process raft request' (duration: 317.091982ms)","trace[1471725645] 'compare' (duration: 45.336325ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:44:31.561345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.64461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-30T23:44:31.562072Z","caller":"traceutil/trace.go:171","msg":"trace[829568723] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:768; }","duration":"616.390098ms","start":"2026-07-30T23:44:30.94565Z","end":"2026-07-30T23:44:31.56204Z","steps":["trace[829568723] 'range keys from in-memory index tree' (duration: 615.970638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:31.56227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:30.945635Z","time spent":"616.616593ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T23:44:31.562288Z","caller":"traceutil/trace.go:171","msg":"trace[1570520086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:768; }","duration":"621.626343ms","start":"2026-07-30T23:44:30.940595Z","end":"2026-07-30T23:44:31.562221Z","steps":["trace[1570520086] 'range keys from in-memory index tree' (duration: 620.482666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:44:31.563754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:30.940584Z","time spent":"623.14871ms","remote":"127.0.0.1:38238","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-07-30T23:44:31.562167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:44:31.198667Z","time spent":"363.442202ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:45:07.378071Z","caller":"traceutil/trace.go:171","msg":"trace[2039777557] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1024; }","duration":"463.285592ms","start":"2026-07-30T23:45:06.91476Z","end":"2026-07-30T23:45:07.378046Z","steps":["trace[2039777557] 'read index received' (duration: 463.114008ms)","trace[2039777557] 'applied index is now lower than readState.Index' (duration: 170.864µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:45:07.378099Z","caller":"traceutil/trace.go:171","msg":"trace[1051656020] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"683.800282ms","start":"2026-07-30T23:45:06.694275Z","end":"2026-07-30T23:45:07.378076Z","steps":["trace[1051656020] 'process raft request' (duration: 683.647018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:07.378226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:06.694239Z","time spent":"683.925035ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:45:07.378232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.435726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:45:07.37827Z","caller":"traceutil/trace.go:171","msg":"trace[1377953766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"463.541189ms","start":"2026-07-30T23:45:06.914716Z","end":"2026-07-30T23:45:07.378258Z","steps":["trace[1377953766] 'agreement among raft nodes before linearized reading' (duration: 463.432106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:07.378294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:06.914695Z","time spent":"463.59463ms","remote":"127.0.0.1:37996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T23:45:07.994724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.722497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T23:45:07.994777Z","caller":"traceutil/trace.go:171","msg":"trace[1378551360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:986; }","duration":"142.831519ms","start":"2026-07-30T23:45:07.851933Z","end":"2026-07-30T23:45:07.994764Z","steps":["trace[1378551360] 'range keys from in-memory index tree' (duration: 142.564063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:07.99518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.457067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T23:45:07.995203Z","caller":"traceutil/trace.go:171","msg":"trace[1619220773] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:986; }","duration":"278.509308ms","start":"2026-07-30T23:45:07.716687Z","end":"2026-07-30T23:45:07.995196Z","steps":["trace[1619220773] 'count revisions from in-memory index tree' (duration: 278.407195ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:31.955913Z","caller":"traceutil/trace.go:171","msg":"trace[1600078526] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"202.121249ms","start":"2026-07-30T23:45:31.753771Z","end":"2026-07-30T23:45:31.955892Z","steps":["trace[1600078526] 'read index received' (duration: 201.935375ms)","trace[1600078526] 'applied index is now lower than readState.Index' (duration: 185.024µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:45:31.956336Z","caller":"traceutil/trace.go:171","msg":"trace[2045869792] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"388.166733ms","start":"2026-07-30T23:45:31.568129Z","end":"2026-07-30T23:45:31.956296Z","steps":["trace[2045869792] 'process raft request' (duration: 387.63972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:31.956519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:31.568104Z","time spent":"388.320047ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:45:31.956706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.908188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T23:45:31.956804Z","caller":"traceutil/trace.go:171","msg":"trace[1165475248] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1066; }","duration":"203.080822ms","start":"2026-07-30T23:45:31.7537Z","end":"2026-07-30T23:45:31.956781Z","steps":["trace[1165475248] 'agreement among raft nodes before linearized reading' (duration: 202.934258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:32.350919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.500365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-30T23:45:32.350981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.307824ms","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-07-30T23:45:32.351044Z","caller":"traceutil/trace.go:171","msg":"trace[1509861484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1066; }","duration":"196.7071ms","start":"2026-07-30T23:45:32.154307Z","end":"2026-07-30T23:45:32.351014Z","steps":["trace[1509861484] 'range keys from in-memory index tree' (duration: 196.220528ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:32.351063Z","caller":"traceutil/trace.go:171","msg":"trace[1673971254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1066; }","duration":"182.471988ms","start":"2026-07-30T23:45:32.168567Z","end":"2026-07-30T23:45:32.351039Z","steps":["trace[1673971254] 'range keys from in-memory index tree' (duration: 182.107469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:32.351125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.763529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-30T23:45:32.351267Z","caller":"traceutil/trace.go:171","msg":"trace[1279093279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1066; }","duration":"183.988305ms","start":"2026-07-30T23:45:32.167247Z","end":"2026-07-30T23:45:32.351235Z","steps":["trace[1279093279] 'range keys from in-memory index tree' (duration: 183.312029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:32.351411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.284806ms","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-07-30T23:45:32.351135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.467328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-30T23:45:32.351522Z","caller":"traceutil/trace.go:171","msg":"trace[601933347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1066; }","duration":"188.034811ms","start":"2026-07-30T23:45:32.163475Z","end":"2026-07-30T23:45:32.35151Z","steps":["trace[601933347] 'range keys from in-memory index tree' (duration: 187.214592ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:32.35154Z","caller":"traceutil/trace.go:171","msg":"trace[116278413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1066; }","duration":"180.395619ms","start":"2026-07-30T23:45:32.171087Z","end":"2026-07-30T23:45:32.351482Z","steps":["trace[116278413] 'range keys from in-memory index tree' (duration: 180.114312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:32.352094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.801483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:45:32.352218Z","caller":"traceutil/trace.go:171","msg":"trace[232428561] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1066; }","duration":"228.021119ms","start":"2026-07-30T23:45:32.124167Z","end":"2026-07-30T23:45:32.352189Z","steps":["trace[232428561] 'count revisions from in-memory index tree' (duration: 227.66748ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:32.474968Z","caller":"traceutil/trace.go:171","msg":"trace[1223465006] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"118.24068ms","start":"2026-07-30T23:45:32.356696Z","end":"2026-07-30T23:45:32.474937Z","steps":["trace[1223465006] 'process raft request' (duration: 118.143638ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:32.475082Z","caller":"traceutil/trace.go:171","msg":"trace[1939631380] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"118.065136ms","start":"2026-07-30T23:45:32.356995Z","end":"2026-07-30T23:45:32.47506Z","steps":["trace[1939631380] 'process raft request' (duration: 117.987015ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:32.475205Z","caller":"traceutil/trace.go:171","msg":"trace[517305225] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"118.466336ms","start":"2026-07-30T23:45:32.356732Z","end":"2026-07-30T23:45:32.475198Z","steps":["trace[517305225] 'process raft request' (duration: 118.159538ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:32.475286Z","caller":"traceutil/trace.go:171","msg":"trace[1834232305] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"118.61944ms","start":"2026-07-30T23:45:32.35666Z","end":"2026-07-30T23:45:32.475279Z","steps":["trace[1834232305] 'process raft request' (duration: 116.992491ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:34.091057Z","caller":"traceutil/trace.go:171","msg":"trace[1535573777] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"128.984648ms","start":"2026-07-30T23:45:33.962042Z","end":"2026-07-30T23:45:34.091027Z","steps":["trace[1535573777] 'process raft request' (duration: 128.847365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:34.374926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.071845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:45:34.375143Z","caller":"traceutil/trace.go:171","msg":"trace[1471338568] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"255.742282ms","start":"2026-07-30T23:45:34.119373Z","end":"2026-07-30T23:45:34.375115Z","steps":["trace[1471338568] 'process raft request' (duration: 150.289168ms)","trace[1471338568] 'compare' (duration: 104.84812ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:45:34.84328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.334414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:45:34.843472Z","caller":"traceutil/trace.go:171","msg":"trace[664861550] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"364.739453ms","start":"2026-07-30T23:45:34.478715Z","end":"2026-07-30T23:45:34.843454Z","steps":["trace[664861550] 'read index received' (duration: 35.136472ms)","trace[664861550] 'applied index is now lower than readState.Index' (duration: 329.601531ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:45:34.843483Z","caller":"traceutil/trace.go:171","msg":"trace[776137467] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"389.475435ms","start":"2026-07-30T23:45:34.453983Z","end":"2026-07-30T23:45:34.843458Z","steps":["trace[776137467] 'process raft request' (duration: 59.870174ms)","trace[776137467] 'compare' (duration: 329.213931ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:45:34.843597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:34.453965Z","time spent":"389.568847ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:45:34.843679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.960118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-30T23:45:34.843734Z","caller":"traceutil/trace.go:171","msg":"trace[2074188182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1074; }","duration":"365.06478ms","start":"2026-07-30T23:45:34.478658Z","end":"2026-07-30T23:45:34.843723Z","steps":["trace[2074188182] 'agreement among raft nodes before linearized reading' (duration: 364.880886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:34.843774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:34.478631Z","time spent":"365.136242ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T23:45:35.717985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.474585ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-30T23:45:35.718086Z","caller":"traceutil/trace.go:171","msg":"trace[128815148] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"874.559458ms","start":"2026-07-30T23:45:34.843507Z","end":"2026-07-30T23:45:35.718067Z","steps":["trace[128815148] 'read index received' (duration: 28.115323ms)","trace[128815148] 'applied index is now lower than readState.Index' (duration: 846.440385ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:45:35.7185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239198478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-30T23:45:35.718547Z","caller":"traceutil/trace.go:171","msg":"trace[513704631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1074; }","duration":"1.239273889s","start":"2026-07-30T23:45:34.479262Z","end":"2026-07-30T23:45:35.718536Z","steps":["trace[513704631] 'agreement among raft nodes before linearized reading' (duration: 1.239083624s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:35.71855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239067085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-30T23:45:35.718583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:34.479251Z","time spent":"1.239324091s","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-30T23:45:35.71861Z","caller":"traceutil/trace.go:171","msg":"trace[1579180211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1074; }","duration":"1.239179307s","start":"2026-07-30T23:45:34.479418Z","end":"2026-07-30T23:45:35.718597Z","steps":["trace[1579180211] 'agreement among raft nodes before linearized reading' (duration: 1.238955942s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:35.718651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:34.479394Z","time spent":"1.239247678s","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T23:45:35.718752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239388272s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-30T23:45:35.718827Z","caller":"traceutil/trace.go:171","msg":"trace[1284460772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1074; }","duration":"1.239487584s","start":"2026-07-30T23:45:34.479323Z","end":"2026-07-30T23:45:35.718811Z","steps":["trace[1284460772] 'agreement among raft nodes before linearized reading' (duration: 1.239015843s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:35.718886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:34.479316Z","time spent":"1.239558906s","remote":"127.0.0.1:38238","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-07-30T23:45:35.71893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.760576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T23:45:35.718975Z","caller":"traceutil/trace.go:171","msg":"trace[1323099179] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1074; }","duration":"923.857838ms","start":"2026-07-30T23:45:34.795105Z","end":"2026-07-30T23:45:35.718963Z","steps":["trace[1323099179] 'agreement among raft nodes before linearized reading' (duration: 923.047989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:35.719014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:34.795074Z","time spent":"923.93188ms","remote":"127.0.0.1:60422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-07-30T23:45:35.756813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.365773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:45:35.756887Z","caller":"traceutil/trace.go:171","msg":"trace[1439116292] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1075; }","duration":"841.455395ms","start":"2026-07-30T23:45:34.915417Z","end":"2026-07-30T23:45:35.756873Z","steps":["trace[1439116292] 'agreement among raft nodes before linearized reading' (duration: 841.323972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:35.756932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:34.915331Z","time spent":"841.590168ms","remote":"127.0.0.1:50260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-07-30T23:45:35.756944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.43633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:45:35.756999Z","caller":"traceutil/trace.go:171","msg":"trace[618275899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1075; }","duration":"840.524803ms","start":"2026-07-30T23:45:34.916458Z","end":"2026-07-30T23:45:35.756983Z","steps":["trace[618275899] 'agreement among raft nodes before linearized reading' (duration: 840.442451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:35.757044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:34.916434Z","time spent":"840.601355ms","remote":"127.0.0.1:37996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T23:45:35.756985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.307561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:45:35.75716Z","caller":"traceutil/trace.go:171","msg":"trace[1238649079] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1075; }","duration":"752.541226ms","start":"2026-07-30T23:45:35.004595Z","end":"2026-07-30T23:45:35.757137Z","steps":["trace[1238649079] 'agreement among raft nodes before linearized reading' (duration: 752.26652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:35.757225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:35.004531Z","time spent":"752.67251ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-07-30T23:45:35.757349Z","caller":"traceutil/trace.go:171","msg":"trace[1442940023] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"909.345441ms","start":"2026-07-30T23:45:34.847986Z","end":"2026-07-30T23:45:35.757332Z","steps":["trace[1442940023] 'process raft request' (duration: 908.614833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:35.757456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:34.847962Z","time spent":"909.433872ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:45:38.002381Z","caller":"traceutil/trace.go:171","msg":"trace[1222986293] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1125; }","duration":"202.659671ms","start":"2026-07-30T23:45:37.799684Z","end":"2026-07-30T23:45:38.002344Z","steps":["trace[1222986293] 'read index received' (duration: 202.546418ms)","trace[1222986293] 'applied index is now lower than readState.Index' (duration: 112.253µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:45:38.00251Z","caller":"traceutil/trace.go:171","msg":"trace[1988622498] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"237.463355ms","start":"2026-07-30T23:45:37.765035Z","end":"2026-07-30T23:45:38.002498Z","steps":["trace[1988622498] 'process raft request' (duration: 237.145577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:38.002744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.848222ms","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-07-30T23:45:38.002793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.085712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T23:45:38.002879Z","caller":"traceutil/trace.go:171","msg":"trace[827458417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1081; }","duration":"202.022116ms","start":"2026-07-30T23:45:37.80083Z","end":"2026-07-30T23:45:38.002853Z","steps":["trace[827458417] 'agreement among raft nodes before linearized reading' (duration: 201.722049ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:38.002966Z","caller":"traceutil/trace.go:171","msg":"trace[783843590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1081; }","duration":"203.305187ms","start":"2026-07-30T23:45:37.799642Z","end":"2026-07-30T23:45:38.002947Z","steps":["trace[783843590] 'agreement among raft nodes before linearized reading' (duration: 203.00951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:38.003187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.247132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-30T23:45:38.003232Z","caller":"traceutil/trace.go:171","msg":"trace[1912048190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1081; }","duration":"202.337454ms","start":"2026-07-30T23:45:37.800874Z","end":"2026-07-30T23:45:38.003212Z","steps":["trace[1912048190] 'agreement among raft nodes before linearized reading' (duration: 202.198991ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:38.109124Z","caller":"traceutil/trace.go:171","msg":"trace[800874018] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"101.188252ms","start":"2026-07-30T23:45:38.007916Z","end":"2026-07-30T23:45:38.109104Z","steps":["trace[800874018] 'process raft request' (duration: 96.162542ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:38.109407Z","caller":"traceutil/trace.go:171","msg":"trace[2132725385] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"100.6537ms","start":"2026-07-30T23:45:38.008728Z","end":"2026-07-30T23:45:38.109382Z","steps":["trace[2132725385] 'process raft request' (duration: 100.556888ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:38.109491Z","caller":"traceutil/trace.go:171","msg":"trace[1132113492] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"101.055139ms","start":"2026-07-30T23:45:38.008419Z","end":"2026-07-30T23:45:38.109474Z","steps":["trace[1132113492] 'process raft request' (duration: 100.647209ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:54.40343Z","caller":"traceutil/trace.go:171","msg":"trace[1273078787] linearizableReadLoop","detail":"{readStateIndex:1235; appliedIndex:1234; }","duration":"218.149663ms","start":"2026-07-30T23:45:54.185256Z","end":"2026-07-30T23:45:54.403405Z","steps":["trace[1273078787] 'read index received' (duration: 217.948148ms)","trace[1273078787] 'applied index is now lower than readState.Index' (duration: 200.435µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:45:54.40352Z","caller":"traceutil/trace.go:171","msg":"trace[1503554254] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"291.368446ms","start":"2026-07-30T23:45:54.112117Z","end":"2026-07-30T23:45:54.403486Z","steps":["trace[1503554254] 'process raft request' (duration: 291.096119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:54.40367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.395369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-30T23:45:54.403737Z","caller":"traceutil/trace.go:171","msg":"trace[1366748070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1185; }","duration":"218.501512ms","start":"2026-07-30T23:45:54.185219Z","end":"2026-07-30T23:45:54.40372Z","steps":["trace[1366748070] 'agreement among raft nodes before linearized reading' (duration: 218.291196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:54.904175Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534095095770190,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:45:55.404703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534095095770190,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:45:55.410647Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.00754117s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T23:45:55.410914Z","caller":"traceutil/trace.go:171","msg":"trace[208424334] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"1.154500949s","start":"2026-07-30T23:45:54.256391Z","end":"2026-07-30T23:45:55.410892Z","steps":["trace[208424334] 'process raft request' (duration: 1.154382056s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:55.41103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:54.256372Z","time spent":"1.15458745s","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:45:55.411785Z","caller":"traceutil/trace.go:171","msg":"trace[233649366] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"1.003823291s","start":"2026-07-30T23:45:54.407946Z","end":"2026-07-30T23:45:55.411769Z","steps":["trace[233649366] 'process raft request' (duration: 1.003670677s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:55.411851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:54.407923Z","time spent":"1.003888143s","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:45:55.413501Z","caller":"traceutil/trace.go:171","msg":"trace[1285205732] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1235; }","duration":"1.009988679s","start":"2026-07-30T23:45:54.403501Z","end":"2026-07-30T23:45:55.41349Z","steps":["trace[1285205732] 'read index received' (duration: 1.007260164s)","trace[1285205732] 'applied index is now lower than readState.Index' (duration: 2.727425ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:45:55.413579Z","caller":"traceutil/trace.go:171","msg":"trace[373043548] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"606.494909ms","start":"2026-07-30T23:45:54.807072Z","end":"2026-07-30T23:45:55.413567Z","steps":["trace[373043548] 'process raft request' (duration: 606.321955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:55.413631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:54.807047Z","time spent":"606.553791ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:45:55.41372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.226502913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-30T23:45:55.413769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.959606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:45:55.413796Z","caller":"traceutil/trace.go:171","msg":"trace[407853387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1188; }","duration":"1.226624755s","start":"2026-07-30T23:45:54.187153Z","end":"2026-07-30T23:45:55.413777Z","steps":["trace[407853387] 'agreement among raft nodes before linearized reading' (duration: 1.22641824s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:55.413807Z","caller":"traceutil/trace.go:171","msg":"trace[1368401965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1188; }","duration":"497.040298ms","start":"2026-07-30T23:45:54.916756Z","end":"2026-07-30T23:45:55.413796Z","steps":["trace[1368401965] 'agreement among raft nodes before linearized reading' (duration: 496.981797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:55.413837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:54.91673Z","time spent":"497.10218ms","remote":"127.0.0.1:37996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T23:45:55.413835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:54.187134Z","time spent":"1.226694907s","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T23:45:55.413856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.512084ms","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":"info","ts":"2026-07-30T23:45:55.413896Z","caller":"traceutil/trace.go:171","msg":"trace[2072255713] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1188; }","duration":"391.555795ms","start":"2026-07-30T23:45:55.02233Z","end":"2026-07-30T23:45:55.413886Z","steps":["trace[2072255713] 'agreement among raft nodes before linearized reading' (duration: 391.426911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:55.413921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:55.022261Z","time spent":"391.654676ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T23:45:55.414042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225320724s","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-07-30T23:45:55.414133Z","caller":"traceutil/trace.go:171","msg":"trace[317330216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1188; }","duration":"1.225430336s","start":"2026-07-30T23:45:54.188693Z","end":"2026-07-30T23:45:55.414123Z","steps":["trace[317330216] 'agreement among raft nodes before linearized reading' (duration: 1.225015266s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:55.414222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:54.188682Z","time spent":"1.225530979s","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T23:46:13.979654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534095095770378,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:46:14.480182Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534095095770378,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:46:14.792927Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.325394551s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T23:46:14.793373Z","caller":"traceutil/trace.go:171","msg":"trace[239123087] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"1.314178653s","start":"2026-07-30T23:46:13.479148Z","end":"2026-07-30T23:46:14.793327Z","steps":["trace[239123087] 'read index received' (duration: 1.314046559s)","trace[239123087] 'applied index is now lower than readState.Index' (duration: 130.854µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:46:14.793561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314393558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-30T23:46:14.793489Z","caller":"traceutil/trace.go:171","msg":"trace[532869332] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"1.326022316s","start":"2026-07-30T23:46:13.46741Z","end":"2026-07-30T23:46:14.793432Z","steps":["trace[532869332] 'process raft request' (duration: 1.32576622s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:14.793603Z","caller":"traceutil/trace.go:171","msg":"trace[2043666523] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1273; }","duration":"1.31449238s","start":"2026-07-30T23:46:13.4791Z","end":"2026-07-30T23:46:14.793593Z","steps":["trace[2043666523] 'agreement among raft nodes before linearized reading' (duration: 1.314335936s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:14.793645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:13.479079Z","time spent":"1.314557622s","remote":"127.0.0.1:38238","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-07-30T23:46:14.793701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:13.467382Z","time spent":"1.326239131s","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:46:15.297519Z","caller":"traceutil/trace.go:171","msg":"trace[931406575] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"499.424067ms","start":"2026-07-30T23:46:14.798069Z","end":"2026-07-30T23:46:15.297493Z","steps":["trace[931406575] 'process raft request' (duration: 499.293854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:15.297772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.721812492s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T23:46:15.297864Z","caller":"traceutil/trace.go:171","msg":"trace[653865391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1274; }","duration":"1.721962785s","start":"2026-07-30T23:46:13.575883Z","end":"2026-07-30T23:46:15.297846Z","steps":["trace[653865391] 'agreement among raft nodes before linearized reading' (duration: 1.721704339s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:15.29788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.194372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T23:46:15.297922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.874817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-30T23:46:15.297941Z","caller":"traceutil/trace.go:171","msg":"trace[1563124151] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1274; }","duration":"796.269213ms","start":"2026-07-30T23:46:14.501659Z","end":"2026-07-30T23:46:15.297928Z","steps":["trace[1563124151] 'agreement among raft nodes before linearized reading' (duration: 796.13965ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:15.297959Z","caller":"traceutil/trace.go:171","msg":"trace[497838616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1274; }","duration":"801.939759ms","start":"2026-07-30T23:46:14.496009Z","end":"2026-07-30T23:46:15.297949Z","steps":["trace[497838616] 'agreement among raft nodes before linearized reading' (duration: 801.825536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:15.297973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:14.501603Z","time spent":"796.363395ms","remote":"127.0.0.1:38434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-30T23:46:15.297984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:14.49599Z","time spent":"801.98875ms","remote":"127.0.0.1:38238","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-07-30T23:46:15.29795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.708892942s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-30T23:46:15.298017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.722002986s","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-07-30T23:46:15.298031Z","caller":"traceutil/trace.go:171","msg":"trace[1315969043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1274; }","duration":"1.709011095s","start":"2026-07-30T23:46:13.588999Z","end":"2026-07-30T23:46:15.29801Z","steps":["trace[1315969043] 'agreement among raft nodes before linearized reading' (duration: 1.70880202s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:15.298051Z","caller":"traceutil/trace.go:171","msg":"trace[564591857] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1274; }","duration":"1.722073678s","start":"2026-07-30T23:46:13.575969Z","end":"2026-07-30T23:46:15.298043Z","steps":["trace[564591857] 'agreement among raft nodes before linearized reading' (duration: 1.721976736s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:15.298079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:13.575945Z","time spent":"1.72212718s","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T23:46:15.297807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.104394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:46:15.298123Z","caller":"traceutil/trace.go:171","msg":"trace[1495777066] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1274; }","duration":"886.481483ms","start":"2026-07-30T23:46:14.411634Z","end":"2026-07-30T23:46:15.298116Z","steps":["trace[1495777066] 'agreement among raft nodes before linearized reading' (duration: 886.107884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:15.298127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.220426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:46:15.298151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:14.411607Z","time spent":"886.535944ms","remote":"127.0.0.1:60406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-30T23:46:15.298156Z","caller":"traceutil/trace.go:171","msg":"trace[348550235] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1274; }","duration":"901.288898ms","start":"2026-07-30T23:46:14.396859Z","end":"2026-07-30T23:46:15.298148Z","steps":["trace[348550235] 'agreement among raft nodes before linearized reading' (duration: 901.238816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:15.298075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:13.588986Z","time spent":"1.709080137s","remote":"127.0.0.1:38238","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-07-30T23:46:15.298188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:14.396841Z","time spent":"901.339428ms","remote":"127.0.0.1:38106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-07-30T23:46:15.297532Z","caller":"traceutil/trace.go:171","msg":"trace[630217626] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"504.079548ms","start":"2026-07-30T23:46:14.793415Z","end":"2026-07-30T23:46:15.297495Z","steps":["trace[630217626] 'read index received' (duration: 422.675719ms)","trace[630217626] 'applied index is now lower than readState.Index' (duration: 81.396869ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:46:15.298281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:14.798048Z","time spent":"499.760874ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:46:15.297914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:13.575862Z","time spent":"1.722039818s","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T23:46:15.298372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.388229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:46:15.298636Z","caller":"traceutil/trace.go:171","msg":"trace[920341745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1274; }","duration":"383.688316ms","start":"2026-07-30T23:46:14.914938Z","end":"2026-07-30T23:46:15.298627Z","steps":["trace[920341745] 'agreement among raft nodes before linearized reading' (duration: 383.410139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:15.298664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:14.914917Z","time spent":"383.739267ms","remote":"127.0.0.1:37996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T23:46:16.110757Z","caller":"traceutil/trace.go:171","msg":"trace[652613807] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"129.832399ms","start":"2026-07-30T23:46:15.980906Z","end":"2026-07-30T23:46:16.110738Z","steps":["trace[652613807] 'process raft request' (duration: 129.720946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:16.316661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.066164ms","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-07-30T23:46:16.316755Z","caller":"traceutil/trace.go:171","msg":"trace[1381373331] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1279; }","duration":"158.259708ms","start":"2026-07-30T23:46:16.158474Z","end":"2026-07-30T23:46:16.316734Z","steps":["trace[1381373331] 'count revisions from in-memory index tree' (duration: 157.965042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:17.890175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.200246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-07-30T23:46:17.89021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.435255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T23:46:17.890255Z","caller":"traceutil/trace.go:171","msg":"trace[1979207266] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1280; }","duration":"338.31622ms","start":"2026-07-30T23:46:17.55192Z","end":"2026-07-30T23:46:17.890236Z","steps":["trace[1979207266] 'range keys from in-memory index tree' (duration: 338.022783ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:17.890283Z","caller":"traceutil/trace.go:171","msg":"trace[2090979337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1280; }","duration":"572.529807ms","start":"2026-07-30T23:46:17.317739Z","end":"2026-07-30T23:46:17.890269Z","steps":["trace[2090979337] 'range keys from in-memory index tree' (duration: 572.323332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:17.890295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:17.551903Z","time spent":"338.383321ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T23:46:17.890288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.200322ms","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-07-30T23:46:17.890317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:17.317729Z","time spent":"572.578018ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T23:46:17.890367Z","caller":"traceutil/trace.go:171","msg":"trace[814894148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1280; }","duration":"588.328875ms","start":"2026-07-30T23:46:17.302018Z","end":"2026-07-30T23:46:17.890347Z","steps":["trace[814894148] 'range keys from in-memory index tree' (duration: 588.003408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:17.89041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:17.301998Z","time spent":"588.402276ms","remote":"127.0.0.1:38238","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-07-30T23:46:17.890405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.350635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-30T23:46:17.890471Z","caller":"traceutil/trace.go:171","msg":"trace[2036850329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1280; }","duration":"537.443577ms","start":"2026-07-30T23:46:17.352996Z","end":"2026-07-30T23:46:17.890439Z","steps":["trace[2036850329] 'range keys from in-memory index tree' (duration: 537.12876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:17.890495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:17.352973Z","time spent":"537.516639ms","remote":"127.0.0.1:38238","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-07-30T23:46:17.890581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.448217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-30T23:46:17.890593Z","caller":"traceutil/trace.go:171","msg":"trace[1676783639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1280; }","duration":"537.474998ms","start":"2026-07-30T23:46:17.353114Z","end":"2026-07-30T23:46:17.890589Z","steps":["trace[1676783639] 'range keys from in-memory index tree' (duration: 537.386775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:17.890604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:17.353107Z","time spent":"537.494318ms","remote":"127.0.0.1:38238","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-07-30T23:46:17.890637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.440853ms","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-07-30T23:46:17.890686Z","caller":"traceutil/trace.go:171","msg":"trace[433163647] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1280; }","duration":"536.505944ms","start":"2026-07-30T23:46:17.354171Z","end":"2026-07-30T23:46:17.890677Z","steps":["trace[433163647] 'range keys from in-memory index tree' (duration: 536.352781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:17.89072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:17.354162Z","time spent":"536.551126ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-30T23:46:18.133649Z","caller":"traceutil/trace.go:171","msg":"trace[1493053101] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"239.072623ms","start":"2026-07-30T23:46:17.894553Z","end":"2026-07-30T23:46:18.133626Z","steps":["trace[1493053101] 'process raft request' (duration: 238.9404ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:18.516746Z","caller":"traceutil/trace.go:171","msg":"trace[513215161] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"620.043204ms","start":"2026-07-30T23:46:17.896681Z","end":"2026-07-30T23:46:18.516725Z","steps":["trace[513215161] 'process raft request' (duration: 619.961272ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:18.517177Z","caller":"traceutil/trace.go:171","msg":"trace[1978593643] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"618.694143ms","start":"2026-07-30T23:46:17.898471Z","end":"2026-07-30T23:46:18.517166Z","steps":["trace[1978593643] 'process raft request' (duration: 618.523589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:18.517191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.677179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:46:18.517226Z","caller":"traceutil/trace.go:171","msg":"trace[717038106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1286; }","duration":"602.75379ms","start":"2026-07-30T23:46:17.914467Z","end":"2026-07-30T23:46:18.517221Z","steps":["trace[717038106] 'agreement among raft nodes before linearized reading' (duration: 602.679008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:18.517243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:17.91442Z","time spent":"602.819071ms","remote":"127.0.0.1:37996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T23:46:18.516785Z","caller":"traceutil/trace.go:171","msg":"trace[772582595] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"620.494976ms","start":"2026-07-30T23:46:17.896251Z","end":"2026-07-30T23:46:18.516746Z","steps":["trace[772582595] 'process raft request' (duration: 619.770878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:18.517317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:17.89846Z","time spent":"618.768734ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:46:18.516942Z","caller":"traceutil/trace.go:171","msg":"trace[683069480] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"620.517976ms","start":"2026-07-30T23:46:17.896414Z","end":"2026-07-30T23:46:18.516932Z","steps":["trace[683069480] 'process raft request' (duration: 620.27663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:18.517443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:17.89624Z","time spent":"621.09721ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:46:18.517476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:17.89639Z","time spent":"621.044958ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:46:18.517112Z","caller":"traceutil/trace.go:171","msg":"trace[1455763083] linearizableReadLoop","detail":"{readStateIndex:1341; appliedIndex:1336; }","duration":"602.593266ms","start":"2026-07-30T23:46:17.914505Z","end":"2026-07-30T23:46:18.517098Z","steps":["trace[1455763083] 'read index received' (duration: 219.116925ms)","trace[1455763083] 'applied index is now lower than readState.Index' (duration: 383.475621ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:46:18.517063Z","caller":"traceutil/trace.go:171","msg":"trace[1176587086] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"618.970369ms","start":"2026-07-30T23:46:17.898064Z","end":"2026-07-30T23:46:18.517034Z","steps":["trace[1176587086] 'process raft request' (duration: 618.803805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:18.517751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:17.898055Z","time spent":"619.660236ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:46:18.518239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:17.896672Z","time spent":"620.510376ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:46:18.53691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.997892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T23:46:18.536941Z","caller":"traceutil/trace.go:171","msg":"trace[1519893973] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1287; }","duration":"541.072854ms","start":"2026-07-30T23:46:17.99586Z","end":"2026-07-30T23:46:18.536933Z","steps":["trace[1519893973] 'agreement among raft nodes before linearized reading' (duration: 541.012202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:18.536961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:17.995842Z","time spent":"541.114224ms","remote":"127.0.0.1:38132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-07-30T23:46:19.015052Z","caller":"traceutil/trace.go:171","msg":"trace[1125141483] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"100.267441ms","start":"2026-07-30T23:46:18.91476Z","end":"2026-07-30T23:46:19.015028Z","steps":["trace[1125141483] 'read index received' (duration: 100.106947ms)","trace[1125141483] 'applied index is now lower than readState.Index' (duration: 159.424µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:46:19.015148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.376793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:46:19.015194Z","caller":"traceutil/trace.go:171","msg":"trace[2058534410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"100.458035ms","start":"2026-07-30T23:46:18.914729Z","end":"2026-07-30T23:46:19.015187Z","steps":["trace[2058534410] 'agreement among raft nodes before linearized reading' (duration: 100.375663ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:19.015086Z","caller":"traceutil/trace.go:171","msg":"trace[666561881] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"112.920783ms","start":"2026-07-30T23:46:18.902139Z","end":"2026-07-30T23:46:19.01506Z","steps":["trace[666561881] 'process raft request' (duration: 112.706978ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:20.507999Z","caller":"traceutil/trace.go:171","msg":"trace[1752733178] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"543.818879ms","start":"2026-07-30T23:46:19.964145Z","end":"2026-07-30T23:46:20.507964Z","steps":["trace[1752733178] 'process raft request' (duration: 543.559063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:20.50814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.032805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:46:20.508162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:19.964126Z","time spent":"543.961522ms","remote":"127.0.0.1:38066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:46:20.507877Z","caller":"traceutil/trace.go:171","msg":"trace[1569125871] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"462.765269ms","start":"2026-07-30T23:46:20.045086Z","end":"2026-07-30T23:46:20.507851Z","steps":["trace[1569125871] 'read index received' (duration: 462.548044ms)","trace[1569125871] 'applied index is now lower than readState.Index' (duration: 216.015µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:46:20.508196Z","caller":"traceutil/trace.go:171","msg":"trace[2102747604] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1290; }","duration":"463.131418ms","start":"2026-07-30T23:46:20.04505Z","end":"2026-07-30T23:46:20.508181Z","steps":["trace[2102747604] 'agreement among raft nodes before linearized reading' (duration: 463.011925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:20.50824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:20.045037Z","time spent":"463.193929ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T23:46:20.508403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.560216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:46:20.508449Z","caller":"traceutil/trace.go:171","msg":"trace[159770478] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1290; }","duration":"323.654238ms","start":"2026-07-30T23:46:20.184781Z","end":"2026-07-30T23:46:20.508435Z","steps":["trace[159770478] 'agreement among raft nodes before linearized reading' (duration: 323.578876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:20.508481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:20.184753Z","time spent":"323.7178ms","remote":"127.0.0.1:45846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-07-30T23:46:20.508523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.220504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-30T23:46:20.508598Z","caller":"traceutil/trace.go:171","msg":"trace[1142893817] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1290; }","duration":"370.341327ms","start":"2026-07-30T23:46:20.138237Z","end":"2026-07-30T23:46:20.508578Z","steps":["trace[1142893817] 'agreement among raft nodes before linearized reading' (duration: 370.113671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:20.508641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:20.138218Z","time spent":"370.415438ms","remote":"127.0.0.1:38238","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-07-30T23:46:21.000134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.946769ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:46:21.000286Z","caller":"traceutil/trace.go:171","msg":"trace[957520341] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1291; }","duration":"132.119373ms","start":"2026-07-30T23:46:20.868147Z","end":"2026-07-30T23:46:21.000266Z","steps":["trace[957520341] 'range keys from in-memory index tree' (duration: 131.925888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:21.000726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.888314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:46:21.00093Z","caller":"traceutil/trace.go:171","msg":"trace[117792451] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"473.487855ms","start":"2026-07-30T23:46:20.52743Z","end":"2026-07-30T23:46:21.000917Z","steps":["trace[117792451] 'process raft request' (duration: 473.439084ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:21.000937Z","caller":"traceutil/trace.go:171","msg":"trace[917876583] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"478.026194ms","start":"2026-07-30T23:46:20.522874Z","end":"2026-07-30T23:46:21.0009Z","steps":["trace[917876583] 'process raft request' (duration: 16.899144ms)","trace[917876583] 'compare' (duration: 460.150267ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:46:21.000994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:20.527413Z","time spent":"473.545176ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:46:21.001063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:20.522852Z","time spent":"478.144867ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:46:21.001265Z","caller":"traceutil/trace.go:171","msg":"trace[263835241] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1347; }","duration":"477.645615ms","start":"2026-07-30T23:46:20.523607Z","end":"2026-07-30T23:46:21.001252Z","steps":["trace[263835241] 'read index received' (duration: 16.258699ms)","trace[263835241] 'applied index is now lower than readState.Index' (duration: 461.385536ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:46:21.001493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.878591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T23:46:21.001523Z","caller":"traceutil/trace.go:171","msg":"trace[1473541452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1294; }","duration":"477.937822ms","start":"2026-07-30T23:46:20.523575Z","end":"2026-07-30T23:46:21.001513Z","steps":["trace[1473541452] 'agreement among raft nodes before linearized reading' (duration: 477.770668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:21.001552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:20.523509Z","time spent":"478.036534ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T23:46:21.001453Z","caller":"traceutil/trace.go:171","msg":"trace[354778846] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"475.694709ms","start":"2026-07-30T23:46:20.525731Z","end":"2026-07-30T23:46:21.001426Z","steps":["trace[354778846] 'process raft request' (duration: 475.064274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:21.001703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.941312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-30T23:46:21.001739Z","caller":"traceutil/trace.go:171","msg":"trace[1064862596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1294; }","duration":"477.974573ms","start":"2026-07-30T23:46:20.523753Z","end":"2026-07-30T23:46:21.001728Z","steps":["trace[1064862596] 'agreement among raft nodes before linearized reading' (duration: 477.878671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:21.001764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:20.523721Z","time spent":"478.036795ms","remote":"127.0.0.1:38238","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-07-30T23:46:21.001775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:20.525705Z","time spent":"476.008286ms","remote":"127.0.0.1:38066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:46:21.001876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.967743ms","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-07-30T23:46:21.001936Z","caller":"traceutil/trace.go:171","msg":"trace[123470757] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1294; }","duration":"478.034294ms","start":"2026-07-30T23:46:20.523875Z","end":"2026-07-30T23:46:21.00191Z","steps":["trace[123470757] 'agreement among raft nodes before linearized reading' (duration: 477.868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:21.001981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:20.523867Z","time spent":"478.105936ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-30T23:46:21.197839Z","caller":"traceutil/trace.go:171","msg":"trace[1548730736] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"190.89246ms","start":"2026-07-30T23:46:21.006926Z","end":"2026-07-30T23:46:21.197818Z","steps":["trace[1548730736] 'process raft request' (duration: 143.276381ms)","trace[1548730736] 'compare' (duration: 47.508147ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:46:21.212044Z","caller":"traceutil/trace.go:171","msg":"trace[1946581333] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"193.753099ms","start":"2026-07-30T23:46:21.018268Z","end":"2026-07-30T23:46:21.212021Z","steps":["trace[1946581333] 'process raft request' (duration: 193.722838ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:21.212801Z","caller":"traceutil/trace.go:171","msg":"trace[109891775] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"205.226783ms","start":"2026-07-30T23:46:21.007565Z","end":"2026-07-30T23:46:21.212792Z","steps":["trace[109891775] 'process raft request' (duration: 204.166368ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:21.212902Z","caller":"traceutil/trace.go:171","msg":"trace[1460271767] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"205.289225ms","start":"2026-07-30T23:46:21.007606Z","end":"2026-07-30T23:46:21.212896Z","steps":["trace[1460271767] 'process raft request' (duration: 204.26882ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:21.212998Z","caller":"traceutil/trace.go:171","msg":"trace[1543780129] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"204.527837ms","start":"2026-07-30T23:46:21.008463Z","end":"2026-07-30T23:46:21.212991Z","steps":["trace[1543780129] 'process raft request' (duration: 203.448591ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:21.213017Z","caller":"traceutil/trace.go:171","msg":"trace[944370620] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"201.050993ms","start":"2026-07-30T23:46:21.0119Z","end":"2026-07-30T23:46:21.212951Z","steps":["trace[944370620] 'process raft request' (duration: 200.067679ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:21.213053Z","caller":"traceutil/trace.go:171","msg":"trace[849774226] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"203.82894ms","start":"2026-07-30T23:46:21.009218Z","end":"2026-07-30T23:46:21.213047Z","steps":["trace[849774226] 'process raft request' (duration: 202.719614ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:24.835206Z","caller":"traceutil/trace.go:171","msg":"trace[398716178] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"299.386047ms","start":"2026-07-30T23:46:24.5358Z","end":"2026-07-30T23:46:24.835186Z","steps":["trace[398716178] 'process raft request' (duration: 299.269074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:26.408791Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.182672394s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-30T23:46:26.457807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.422624788s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-30T23:46:26.458095Z","caller":"traceutil/trace.go:171","msg":"trace[1951170528] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"1.541703999s","start":"2026-07-30T23:46:24.91636Z","end":"2026-07-30T23:46:26.458064Z","steps":["trace[1951170528] 'read index received' (duration: 118.730022ms)","trace[1951170528] 'applied index is now lower than readState.Index' (duration: 1.422969957s)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:46:26.458322Z","caller":"traceutil/trace.go:171","msg":"trace[1386229812] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"1.232254721s","start":"2026-07-30T23:46:25.226002Z","end":"2026-07-30T23:46:26.458257Z","steps":["trace[1386229812] 'process raft request' (duration: 1.231913143s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:26.458347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541942965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:46:26.458419Z","caller":"traceutil/trace.go:171","msg":"trace[674277691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1318; }","duration":"1.542085469s","start":"2026-07-30T23:46:24.916306Z","end":"2026-07-30T23:46:26.458392Z","steps":["trace[674277691] 'agreement among raft nodes before linearized reading' (duration: 1.541859814s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:26.458487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:24.916288Z","time spent":"1.542189351s","remote":"127.0.0.1:37996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T23:46:26.458492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:25.225975Z","time spent":"1.232396214s","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:46:26.458582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.442871614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-30T23:46:26.458645Z","caller":"traceutil/trace.go:171","msg":"trace[900111797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1319; }","duration":"1.442983886s","start":"2026-07-30T23:46:25.015642Z","end":"2026-07-30T23:46:26.458625Z","steps":["trace[900111797] 'agreement among raft nodes before linearized reading' (duration: 1.442789021s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:26.458684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:25.015618Z","time spent":"1.443056808s","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T23:46:26.458781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229591777s","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":"info","ts":"2026-07-30T23:46:26.458853Z","caller":"traceutil/trace.go:171","msg":"trace[537610833] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1319; }","duration":"1.22970233s","start":"2026-07-30T23:46:25.229132Z","end":"2026-07-30T23:46:26.458835Z","steps":["trace[537610833] 'agreement among raft nodes before linearized reading' (duration: 1.229495625s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:26.458904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:25.229121Z","time spent":"1.229771472s","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T23:46:26.458998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233960402s","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-07-30T23:46:26.459104Z","caller":"traceutil/trace.go:171","msg":"trace[854317920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1319; }","duration":"1.234087845s","start":"2026-07-30T23:46:25.224984Z","end":"2026-07-30T23:46:26.459072Z","steps":["trace[854317920] 'agreement among raft nodes before linearized reading' (duration: 1.23386049s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:26.45903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.685382ms","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":"warn","ts":"2026-07-30T23:46:26.459179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:25.22497Z","time spent":"1.234194967s","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T23:46:26.459191Z","caller":"traceutil/trace.go:171","msg":"trace[258563594] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1319; }","duration":"240.881517ms","start":"2026-07-30T23:46:26.218285Z","end":"2026-07-30T23:46:26.459167Z","steps":["trace[258563594] 'agreement among raft nodes before linearized reading' (duration: 240.59104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:26.460727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23472983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-30T23:46:26.460787Z","caller":"traceutil/trace.go:171","msg":"trace[2060973773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1319; }","duration":"1.234852133s","start":"2026-07-30T23:46:25.225921Z","end":"2026-07-30T23:46:26.460773Z","steps":["trace[2060973773] 'agreement among raft nodes before linearized reading' (duration: 1.232882916s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:26.460827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:25.225889Z","time spent":"1.234924535s","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T23:47:05.060744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.942414ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-30T23:47:05.061339Z","caller":"traceutil/trace.go:171","msg":"trace[728115515] linearizableReadLoop","detail":"{readStateIndex:1582; appliedIndex:1580; }","duration":"145.263669ms","start":"2026-07-30T23:47:04.916049Z","end":"2026-07-30T23:47:05.061313Z","steps":["trace[728115515] 'read index received' (duration: 109.79441ms)","trace[728115515] 'applied index is now lower than readState.Index' (duration: 35.467579ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:47:05.06143Z","caller":"traceutil/trace.go:171","msg":"trace[173898078] transaction","detail":"{read_only:false; response_revision:1517; number_of_response:1; }","duration":"205.032231ms","start":"2026-07-30T23:47:04.856373Z","end":"2026-07-30T23:47:05.061406Z","steps":["trace[173898078] 'process raft request' (duration: 204.697343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:47:05.061554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.478934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:47:05.06164Z","caller":"traceutil/trace.go:171","msg":"trace[265447146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1517; }","duration":"145.621748ms","start":"2026-07-30T23:47:04.916Z","end":"2026-07-30T23:47:05.061621Z","steps":["trace[265447146] 'agreement among raft nodes before linearized reading' (duration: 145.452374ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:47:52.756635Z","caller":"traceutil/trace.go:171","msg":"trace[129521664] transaction","detail":"{read_only:false; response_revision:1733; number_of_response:1; }","duration":"140.303289ms","start":"2026-07-30T23:47:52.616306Z","end":"2026-07-30T23:47:52.756609Z","steps":["trace[129521664] 'process raft request' (duration: 140.141845ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:47:53.143555Z","caller":"traceutil/trace.go:171","msg":"trace[1174733034] transaction","detail":"{read_only:false; response_revision:1737; number_of_response:1; }","duration":"111.371965ms","start":"2026-07-30T23:47:53.032143Z","end":"2026-07-30T23:47:53.143515Z","steps":["trace[1174733034] 'process raft request' (duration: 109.870659ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:47:53.353613Z","caller":"traceutil/trace.go:171","msg":"trace[1746326441] linearizableReadLoop","detail":"{readStateIndex:1812; appliedIndex:1811; }","duration":"164.072768ms","start":"2026-07-30T23:47:53.189519Z","end":"2026-07-30T23:47:53.353592Z","steps":["trace[1746326441] 'read index received' (duration: 148.981476ms)","trace[1746326441] 'applied index is now lower than readState.Index' (duration: 15.090222ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:47:53.353685Z","caller":"traceutil/trace.go:171","msg":"trace[1493707355] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"170.251706ms","start":"2026-07-30T23:47:53.183396Z","end":"2026-07-30T23:47:53.353647Z","steps":["trace[1493707355] 'process raft request' (duration: 155.170935ms)","trace[1493707355] 'compare' (duration: 14.919667ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:47:53.353955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.432121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:47:53.354033Z","caller":"traceutil/trace.go:171","msg":"trace[1176528493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1738; }","duration":"137.550373ms","start":"2026-07-30T23:47:53.216463Z","end":"2026-07-30T23:47:53.354013Z","steps":["trace[1176528493] 'agreement among raft nodes before linearized reading' (duration: 137.379889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:47:53.354169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.521739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T23:47:53.354228Z","caller":"traceutil/trace.go:171","msg":"trace[170164004] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1738; }","duration":"164.705074ms","start":"2026-07-30T23:47:53.189506Z","end":"2026-07-30T23:47:53.354211Z","steps":["trace[170164004] 'agreement among raft nodes before linearized reading' (duration: 164.213852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:24.626197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.349134ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-30T23:50:22.148531Z","caller":"traceutil/trace.go:171","msg":"trace[1789832974] transaction","detail":"{read_only:false; response_revision:2416; number_of_response:1; }","duration":"115.222063ms","start":"2026-07-30T23:50:22.033264Z","end":"2026-07-30T23:50:22.148486Z","steps":["trace[1789832974] 'process raft request' (duration: 114.928386ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:22.274331Z","caller":"traceutil/trace.go:171","msg":"trace[1259743906] transaction","detail":"{read_only:false; response_revision:2417; number_of_response:1; }","duration":"121.486083ms","start":"2026-07-30T23:50:22.152814Z","end":"2026-07-30T23:50:22.2743Z","steps":["trace[1259743906] 'process raft request' (duration: 104.32031ms)","trace[1259743906] 'compare' (duration: 16.918487ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:50:34.576433Z","caller":"traceutil/trace.go:171","msg":"trace[1747398151] linearizableReadLoop","detail":"{readStateIndex:2607; appliedIndex:2606; }","duration":"174.402448ms","start":"2026-07-30T23:50:34.402006Z","end":"2026-07-30T23:50:34.576408Z","steps":["trace[1747398151] 'read index received' (duration: 174.185192ms)","trace[1747398151] 'applied index is now lower than readState.Index' (duration: 215.936µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:50:34.576528Z","caller":"traceutil/trace.go:171","msg":"trace[1460258801] transaction","detail":"{read_only:false; response_revision:2500; number_of_response:1; }","duration":"184.376728ms","start":"2026-07-30T23:50:34.39212Z","end":"2026-07-30T23:50:34.576497Z","steps":["trace[1460258801] 'process raft request' (duration: 184.125742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:34.576681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.648053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-30T23:50:34.576743Z","caller":"traceutil/trace.go:171","msg":"trace[1258389018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2500; }","duration":"174.772406ms","start":"2026-07-30T23:50:34.401957Z","end":"2026-07-30T23:50:34.57673Z","steps":["trace[1258389018] 'agreement among raft nodes before linearized reading' (duration: 174.584662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:39.147056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.302406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:50:39.147418Z","caller":"traceutil/trace.go:171","msg":"trace[615785225] transaction","detail":"{read_only:false; response_revision:2522; number_of_response:1; }","duration":"456.180068ms","start":"2026-07-30T23:50:38.691208Z","end":"2026-07-30T23:50:39.147388Z","steps":["trace[615785225] 'process raft request' (duration: 47.460442ms)","trace[615785225] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:419; } (duration: 407.956328ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:50:39.147531Z","caller":"traceutil/trace.go:171","msg":"trace[564756974] linearizableReadLoop","detail":"{readStateIndex:2631; appliedIndex:2629; }","duration":"413.45735ms","start":"2026-07-30T23:50:38.734051Z","end":"2026-07-30T23:50:39.147508Z","steps":["trace[564756974] 'read index received' (duration: 4.680072ms)","trace[564756974] 'applied index is now lower than readState.Index' (duration: 408.775288ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:50:39.147573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:38.691183Z","time spent":"456.295692ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:50:39.147598Z","caller":"traceutil/trace.go:171","msg":"trace[1565023802] transaction","detail":"{read_only:false; response_revision:2523; number_of_response:1; }","duration":"455.116283ms","start":"2026-07-30T23:50:38.692447Z","end":"2026-07-30T23:50:39.147564Z","steps":["trace[1565023802] 'process raft request' (duration: 454.785335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:39.147782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:38.692426Z","time spent":"455.264407ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:50:39.147819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.741527ms","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":"warn","ts":"2026-07-30T23:50:39.14789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.785888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:50:39.147909Z","caller":"traceutil/trace.go:171","msg":"trace[886705214] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2523; }","duration":"413.843799ms","start":"2026-07-30T23:50:38.734044Z","end":"2026-07-30T23:50:39.147888Z","steps":["trace[886705214] 'agreement among raft nodes before linearized reading' (duration: 413.577673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:39.14796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:38.733989Z","time spent":"413.961292ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-30T23:50:39.147957Z","caller":"traceutil/trace.go:171","msg":"trace[1375980319] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2523; }","duration":"403.87414ms","start":"2026-07-30T23:50:38.744064Z","end":"2026-07-30T23:50:39.147938Z","steps":["trace[1375980319] 'agreement among raft nodes before linearized reading' (duration: 403.776447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:39.148189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:38.744051Z","time spent":"404.034524ms","remote":"127.0.0.1:38110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-07-30T23:50:39.147988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.785218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:50:39.148434Z","caller":"traceutil/trace.go:171","msg":"trace[1823608091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2523; }","duration":"232.24428ms","start":"2026-07-30T23:50:38.916152Z","end":"2026-07-30T23:50:39.148397Z","steps":["trace[1823608091] 'agreement among raft nodes before linearized reading' (duration: 231.785199ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:39.397646Z","caller":"traceutil/trace.go:171","msg":"trace[672546658] transaction","detail":"{read_only:false; response_revision:2525; number_of_response:1; }","duration":"150.921712ms","start":"2026-07-30T23:50:39.246697Z","end":"2026-07-30T23:50:39.397618Z","steps":["trace[672546658] 'process raft request' (duration: 101.412131ms)","trace[672546658] 'compare' (duration: 49.117332ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:50:39.5529Z","caller":"traceutil/trace.go:171","msg":"trace[286437950] transaction","detail":"{read_only:false; response_revision:2526; number_of_response:1; }","duration":"116.856883ms","start":"2026-07-30T23:50:39.436008Z","end":"2026-07-30T23:50:39.552865Z","steps":["trace[286437950] 'process raft request' (duration: 68.55678ms)","trace[286437950] 'compare' (duration: 48.147759ms)"],"step_count":2}