{"level":"warn","ts":"2026-03-11T01:41:24.261427Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-11T01:41:24.261547Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.110:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.110:2380","--initial-cluster=instance=https://162.253.55.110:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.110:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.110:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-11T01:41:24.261633Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-11T01:41:24.261648Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-03-11T01:41:24.26167Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-11T01:41:24.263716Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"]} {"level":"info","ts":"2026-03-11T01:41:24.263849Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.110:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-11T01:41:24.269818Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.642527ms"} {"level":"info","ts":"2026-03-11T01:41:24.281104Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f52f30d5714009d5","cluster-id":"650981a7a383c714"} {"level":"info","ts":"2026-03-11T01:41:24.281198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=()"} {"level":"info","ts":"2026-03-11T01:41:24.281224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 0"} {"level":"info","ts":"2026-03-11T01:41:24.281243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f52f30d5714009d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-11T01:41:24.281255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 1"} {"level":"info","ts":"2026-03-11T01:41:24.281302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"warn","ts":"2026-03-11T01:41:24.289328Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-11T01:41:24.291159Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-11T01:41:24.292593Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-11T01:41:24.296576Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f52f30d5714009d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-11T01:41:24.296981Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f52f30d5714009d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-11T01:41:24.297058Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-11T01:41:24.297139Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-11T01:41:24.297239Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-11T01:41:24.297848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"info","ts":"2026-03-11T01:41:24.298054Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","added-peer-id":"f52f30d5714009d5","added-peer-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-03-11T01:41:24.308139Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-11T01:41:24.308261Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-03-11T01:41:24.308297Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-03-11T01:41:24.308555Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f52f30d5714009d5","initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-11T01:41:24.308613Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-11T01:41:25.181784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 is starting a new election at term 1"} {"level":"info","ts":"2026-03-11T01:41:25.181834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-11T01:41:25.181868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgPreVoteResp from f52f30d5714009d5 at term 1"} {"level":"info","ts":"2026-03-11T01:41:25.181884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became candidate at term 2"} {"level":"info","ts":"2026-03-11T01:41:25.181893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgVoteResp from f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-03-11T01:41:25.181904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became leader at term 2"} {"level":"info","ts":"2026-03-11T01:41:25.181914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f52f30d5714009d5 elected leader f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-03-11T01:41:25.182901Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f52f30d5714009d5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.110:2379]}","request-path":"/0/members/f52f30d5714009d5/attributes","cluster-id":"650981a7a383c714","publish-timeout":"7s"} {"level":"info","ts":"2026-03-11T01:41:25.182911Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-11T01:41:25.183082Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-11T01:41:25.183388Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-11T01:41:25.183408Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-11T01:41:25.18458Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T01:41:25.185873Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T01:41:25.186063Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T01:41:25.186101Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T01:41:25.186693Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.110:2379"} {"level":"info","ts":"2026-03-11T01:41:25.187536Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-11T01:41:26.690748Z","caller":"traceutil/trace.go:171","msg":"trace[445473197] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"120.038083ms","start":"2026-03-11T01:41:26.570686Z","end":"2026-03-11T01:41:26.690724Z","steps":["trace[445473197] 'process raft request' (duration: 119.828097ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:41:26.691152Z","caller":"traceutil/trace.go:171","msg":"trace[423947317] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:5; }","duration":"120.169704ms","start":"2026-03-11T01:41:26.570953Z","end":"2026-03-11T01:41:26.691123Z","steps":["trace[423947317] 'read index received' (duration: 120.161364ms)","trace[423947317] 'applied index is now lower than readState.Index' (duration: 6.74µs)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:41:26.691462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.436372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-11T01:41:26.691515Z","caller":"traceutil/trace.go:171","msg":"trace[79411097] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"120.575436ms","start":"2026-03-11T01:41:26.570924Z","end":"2026-03-11T01:41:26.691499Z","steps":["trace[79411097] 'agreement among raft nodes before linearized reading' (duration: 120.34809ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.191408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644978884786561,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-11T01:41:27.193305Z","caller":"traceutil/trace.go:171","msg":"trace[1577982916] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"525.600266ms","start":"2026-03-11T01:41:26.66765Z","end":"2026-03-11T01:41:27.19325Z","steps":["trace[1577982916] 'process raft request' (duration: 525.389629ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:41:27.193514Z","caller":"traceutil/trace.go:171","msg":"trace[1784890368] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"525.04584ms","start":"2026-03-11T01:41:26.668442Z","end":"2026-03-11T01:41:27.193487Z","steps":["trace[1784890368] 'process raft request' (duration: 524.732883ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:41:27.193521Z","caller":"traceutil/trace.go:171","msg":"trace[710246864] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"524.955198ms","start":"2026-03-11T01:41:26.668544Z","end":"2026-03-11T01:41:27.1935Z","steps":["trace[710246864] 'process raft request' (duration: 524.680701ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:41:27.193594Z","caller":"traceutil/trace.go:171","msg":"trace[145684915] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"524.807905ms","start":"2026-03-11T01:41:26.668764Z","end":"2026-03-11T01:41:27.193572Z","steps":["trace[145684915] 'process raft request' (duration: 524.535218ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:41:27.193749Z","caller":"traceutil/trace.go:171","msg":"trace[1946408370] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"524.637579ms","start":"2026-03-11T01:41:26.669034Z","end":"2026-03-11T01:41:27.193672Z","steps":["trace[1946408370] 'process raft request' (duration: 524.339512ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:41:27.1938Z","caller":"traceutil/trace.go:171","msg":"trace[1103297641] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"524.207668ms","start":"2026-03-11T01:41:26.669582Z","end":"2026-03-11T01:41:27.193789Z","steps":["trace[1103297641] 'process raft request' (duration: 523.823668ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:41:27.193887Z","caller":"traceutil/trace.go:171","msg":"trace[273468663] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"524.199337ms","start":"2026-03-11T01:41:26.66963Z","end":"2026-03-11T01:41:27.193829Z","steps":["trace[273468663] 'process raft request' (duration: 524.011412ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:41:27.193853Z","caller":"traceutil/trace.go:171","msg":"trace[1991151029] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"502.590562ms","start":"2026-03-11T01:41:26.69124Z","end":"2026-03-11T01:41:27.193831Z","steps":["trace[1991151029] 'read index received' (duration: 501.720009ms)","trace[1991151029] 'applied index is now lower than readState.Index' (duration: 869.713µs)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:41:27.195285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.667631Z","time spent":"525.80833ms","remote":"127.0.0.1:42640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T01:41:27.195609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.669617Z","time spent":"524.309711ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T01:41:27.195992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.865944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-11T01:41:27.196078Z","caller":"traceutil/trace.go:171","msg":"trace[77961915] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:10; }","duration":"624.956506ms","start":"2026-03-11T01:41:26.571088Z","end":"2026-03-11T01:41:27.196045Z","steps":["trace[77961915] 'agreement among raft nodes before linearized reading' (duration: 622.81517ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.196215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.571085Z","time spent":"625.112049ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-03-11T01:41:27.196382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.668433Z","time spent":"525.152353ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T01:41:27.197019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.668534Z","time spent":"525.077321ms","remote":"127.0.0.1:42390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T01:41:27.197281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.668758Z","time spent":"524.892786ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T01:41:27.197419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.470789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-11T01:41:27.193929Z","caller":"traceutil/trace.go:171","msg":"trace[1995877480] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"524.212109ms","start":"2026-03-11T01:41:26.669705Z","end":"2026-03-11T01:41:27.193917Z","steps":["trace[1995877480] 'process raft request' (duration: 524.056534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.197527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.177785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-11T01:41:27.197561Z","caller":"traceutil/trace.go:171","msg":"trace[1685408360] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"570.680085ms","start":"2026-03-11T01:41:26.626828Z","end":"2026-03-11T01:41:27.197508Z","steps":["trace[1685408360] 'agreement among raft nodes before linearized reading' (duration: 567.140022ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:41:27.19764Z","caller":"traceutil/trace.go:171","msg":"trace[1505670127] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"530.318949ms","start":"2026-03-11T01:41:26.667275Z","end":"2026-03-11T01:41:27.197594Z","steps":["trace[1505670127] 'agreement among raft nodes before linearized reading' (duration: 527.68657ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.197654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.626801Z","time spent":"570.834248ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-11T01:41:27.197704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.667251Z","time spent":"530.442701ms","remote":"127.0.0.1:42268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-03-11T01:41:27.197874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.669027Z","time spent":"524.818283ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T01:41:27.198138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.669574Z","time spent":"524.292311ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T01:41:27.197881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.669697Z","time spent":"528.049769ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T01:41:27.197483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.925168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-11T01:41:27.198609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.772862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-11T01:41:27.198725Z","caller":"traceutil/trace.go:171","msg":"trace[389562491] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"531.162409ms","start":"2026-03-11T01:41:26.667504Z","end":"2026-03-11T01:41:27.198666Z","steps":["trace[389562491] 'agreement among raft nodes before linearized reading' (duration: 526.411036ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.19881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.667476Z","time spent":"531.290004ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-11T01:41:27.19875Z","caller":"traceutil/trace.go:171","msg":"trace[1241219336] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"571.975628ms","start":"2026-03-11T01:41:26.62676Z","end":"2026-03-11T01:41:27.198735Z","steps":["trace[1241219336] 'agreement among raft nodes before linearized reading' (duration: 567.253195ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.199935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.626728Z","time spent":"573.19041ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-11T01:41:27.200056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.2819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-11T01:41:27.200091Z","caller":"traceutil/trace.go:171","msg":"trace[962417398] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:0; response_revision:10; }","duration":"532.33945ms","start":"2026-03-11T01:41:26.667739Z","end":"2026-03-11T01:41:27.200078Z","steps":["trace[962417398] 'agreement among raft nodes before linearized reading' (duration: 526.224541ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.20012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.667734Z","time spent":"532.380091ms","remote":"127.0.0.1:42206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/162.253.55.110\" "} {"level":"warn","ts":"2026-03-11T01:41:27.200322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.579533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-11T01:41:27.200355Z","caller":"traceutil/trace.go:171","msg":"trace[91456918] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"494.699116ms","start":"2026-03-11T01:41:26.705648Z","end":"2026-03-11T01:41:27.200347Z","steps":["trace[91456918] 'agreement among raft nodes before linearized reading' (duration: 494.649845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.200392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.705627Z","time spent":"494.758188ms","remote":"127.0.0.1:42588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-03-11T01:41:27.20057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.724993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-11T01:41:27.200596Z","caller":"traceutil/trace.go:171","msg":"trace[109561740] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"479.771105ms","start":"2026-03-11T01:41:26.720818Z","end":"2026-03-11T01:41:27.200589Z","steps":["trace[109561740] 'agreement among raft nodes before linearized reading' (duration: 479.734234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.200618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.720808Z","time spent":"479.804226ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-11T01:41:27.200779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.835329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-11T01:41:27.20081Z","caller":"traceutil/trace.go:171","msg":"trace[276586601] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"481.90522ms","start":"2026-03-11T01:41:26.718896Z","end":"2026-03-11T01:41:27.200801Z","steps":["trace[276586601] 'agreement among raft nodes before linearized reading' (duration: 481.830138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.200837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:26.718871Z","time spent":"481.959952ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-11T01:41:27.555076Z","caller":"traceutil/trace.go:171","msg":"trace[1138296794] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:13; }","duration":"349.717892ms","start":"2026-03-11T01:41:27.20534Z","end":"2026-03-11T01:41:27.555057Z","steps":["trace[1138296794] 'read index received' (duration: 345.874742ms)","trace[1138296794] 'applied index is now lower than readState.Index' (duration: 3.84164ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:41:27.555154Z","caller":"traceutil/trace.go:171","msg":"trace[746807464] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"350.03187ms","start":"2026-03-11T01:41:27.205113Z","end":"2026-03-11T01:41:27.555145Z","steps":["trace[746807464] 'process raft request' (duration: 346.129897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.555234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:27.205096Z","time spent":"350.076232ms","remote":"127.0.0.1:42640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T01:41:27.555258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.898677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-11T01:41:27.555338Z","caller":"traceutil/trace.go:171","msg":"trace[1361683558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"349.991669ms","start":"2026-03-11T01:41:27.205325Z","end":"2026-03-11T01:41:27.555317Z","steps":["trace[1361683558] 'agreement among raft nodes before linearized reading' (duration: 349.820795ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.55538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:27.205312Z","time spent":"350.058101ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-11T01:41:27.555542Z","caller":"traceutil/trace.go:171","msg":"trace[209685078] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"350.160393ms","start":"2026-03-11T01:41:27.205361Z","end":"2026-03-11T01:41:27.555521Z","steps":["trace[209685078] 'process raft request' (duration: 350.0141ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:41:27.555593Z","caller":"traceutil/trace.go:171","msg":"trace[1257650822] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"348.672295ms","start":"2026-03-11T01:41:27.206908Z","end":"2026-03-11T01:41:27.55558Z","steps":["trace[1257650822] 'process raft request' (duration: 348.539821ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:41:27.555628Z","caller":"traceutil/trace.go:171","msg":"trace[1674945817] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"348.86388ms","start":"2026-03-11T01:41:27.206751Z","end":"2026-03-11T01:41:27.555614Z","steps":["trace[1674945817] 'process raft request' (duration: 348.659235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.555672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:27.206902Z","time spent":"348.724836ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-11T01:41:27.555709Z","caller":"traceutil/trace.go:171","msg":"trace[1219802768] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"348.789677ms","start":"2026-03-11T01:41:27.206881Z","end":"2026-03-11T01:41:27.55567Z","steps":["trace[1219802768] 'process raft request' (duration: 348.550861ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.555729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:27.206742Z","time spent":"348.920201ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-11T01:41:27.555898Z","caller":"traceutil/trace.go:171","msg":"trace[2030396741] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"348.843049ms","start":"2026-03-11T01:41:27.207004Z","end":"2026-03-11T01:41:27.555847Z","steps":["trace[2030396741] 'process raft request' (duration: 348.459799ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:41:27.555974Z","caller":"traceutil/trace.go:171","msg":"trace[155531234] transaction","detail":"{read_only:false; number_of_response:0; response_revision:16; }","duration":"348.755966ms","start":"2026-03-11T01:41:27.207212Z","end":"2026-03-11T01:41:27.555968Z","steps":["trace[155531234] 'process raft request' (duration: 348.270853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.555798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:27.206877Z","time spent":"348.85984ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T01:41:27.556023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:27.207204Z","time spent":"348.793047ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T01:41:27.556066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:27.205344Z","time spent":"350.253566ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:41:27.556105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:27.207001Z","time spent":"348.973892ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-11T01:41:27.556361Z","caller":"traceutil/trace.go:171","msg":"trace[1874381568] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"344.060333ms","start":"2026-03-11T01:41:27.212293Z","end":"2026-03-11T01:41:27.556353Z","steps":["trace[1874381568] 'process raft request' (duration: 343.205541ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.556418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:27.212285Z","time spent":"344.100934ms","remote":"127.0.0.1:42390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:41:27.556516Z","caller":"traceutil/trace.go:171","msg":"trace[1467849790] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"344.059693ms","start":"2026-03-11T01:41:27.212442Z","end":"2026-03-11T01:41:27.556502Z","steps":["trace[1467849790] 'process raft request' (duration: 343.49849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.556752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:27.212437Z","time spent":"344.27417ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T01:41:27.55676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.385186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-pw2m2\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-03-11T01:41:27.556789Z","caller":"traceutil/trace.go:171","msg":"trace[825765222] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-pw2m2; range_end:; response_count:1; response_revision:19; }","duration":"351.421606ms","start":"2026-03-11T01:41:27.20536Z","end":"2026-03-11T01:41:27.556782Z","steps":["trace[825765222] 'agreement among raft nodes before linearized reading' (duration: 351.338744ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:41:27.556658Z","caller":"traceutil/trace.go:171","msg":"trace[2067903681] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"344.175717ms","start":"2026-03-11T01:41:27.212471Z","end":"2026-03-11T01:41:27.556647Z","steps":["trace[2067903681] 'process raft request' (duration: 344.111595ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.556926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:27.212468Z","time spent":"344.380272ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T01:41:27.557007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.139923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"warn","ts":"2026-03-11T01:41:27.556813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:27.205357Z","time spent":"351.447717ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-pw2m2\" "} {"level":"warn","ts":"2026-03-11T01:41:27.556981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.574879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-11T01:41:27.557052Z","caller":"traceutil/trace.go:171","msg":"trace[951967434] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"291.646571ms","start":"2026-03-11T01:41:27.265396Z","end":"2026-03-11T01:41:27.557043Z","steps":["trace[951967434] 'agreement among raft nodes before linearized reading' (duration: 291.551598ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.557198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.668167ms","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-03-11T01:41:27.557237Z","caller":"traceutil/trace.go:171","msg":"trace[2017320261] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:19; }","duration":"146.920184ms","start":"2026-03-11T01:41:27.410305Z","end":"2026-03-11T01:41:27.557225Z","steps":["trace[2017320261] 'agreement among raft nodes before linearized reading' (duration: 146.695408ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:41:27.557228Z","caller":"traceutil/trace.go:171","msg":"trace[956285620] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"350.176163ms","start":"2026-03-11T01:41:27.206853Z","end":"2026-03-11T01:41:27.557029Z","steps":["trace[956285620] 'agreement among raft nodes before linearized reading' (duration: 350.128652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:41:27.557348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:41:27.20685Z","time spent":"350.490432ms","remote":"127.0.0.1:42390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3514,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-11T01:42:00.877832Z","caller":"traceutil/trace.go:171","msg":"trace[1705173398] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"604.942711ms","start":"2026-03-11T01:42:00.27287Z","end":"2026-03-11T01:42:00.877813Z","steps":["trace[1705173398] 'process raft request' (duration: 604.792458ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:00.878062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:00.272848Z","time spent":"605.040734ms","remote":"127.0.0.1:42488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:42:01.131096Z","caller":"traceutil/trace.go:171","msg":"trace[1608704937] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:540; }","duration":"314.942652ms","start":"2026-03-11T01:42:00.816126Z","end":"2026-03-11T01:42:01.131069Z","steps":["trace[1608704937] 'read index received' (duration: 61.589142ms)","trace[1608704937] 'applied index is now lower than readState.Index' (duration: 253.35205ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:42:01.131186Z","caller":"traceutil/trace.go:171","msg":"trace[1871136214] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"326.885144ms","start":"2026-03-11T01:42:00.80426Z","end":"2026-03-11T01:42:01.131146Z","steps":["trace[1871136214] 'process raft request' (duration: 326.740881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:01.131271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.129197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:42:01.131342Z","caller":"traceutil/trace.go:171","msg":"trace[286670012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"315.24822ms","start":"2026-03-11T01:42:00.816075Z","end":"2026-03-11T01:42:01.131323Z","steps":["trace[286670012] 'agreement among raft nodes before linearized reading' (duration: 315.142047ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:42:01.13135Z","caller":"traceutil/trace.go:171","msg":"trace[1766203644] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"685.408068ms","start":"2026-03-11T01:42:00.44592Z","end":"2026-03-11T01:42:01.131328Z","steps":["trace[1766203644] 'process raft request' (duration: 684.905644ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:01.131377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:00.804235Z","time spent":"327.034578ms","remote":"127.0.0.1:42390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:42:01.131387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:00.816051Z","time spent":"315.324452ms","remote":"127.0.0.1:42184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-11T01:42:01.131469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:00.445895Z","time spent":"685.501189ms","remote":"127.0.0.1:42488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:42:01.132084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.593235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-11T01:42:01.132109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.46688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-11T01:42:01.132164Z","caller":"traceutil/trace.go:171","msg":"trace[707286344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"191.705048ms","start":"2026-03-11T01:42:00.940433Z","end":"2026-03-11T01:42:01.132138Z","steps":["trace[707286344] 'agreement among raft nodes before linearized reading' (duration: 191.457322ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:42:01.132203Z","caller":"traceutil/trace.go:171","msg":"trace[682868686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:523; }","duration":"134.562841ms","start":"2026-03-11T01:42:00.997589Z","end":"2026-03-11T01:42:01.132152Z","steps":["trace[682868686] 'agreement among raft nodes before linearized reading' (duration: 134.350226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:01.682034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.694506ms","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-03-11T01:42:01.682154Z","caller":"traceutil/trace.go:171","msg":"trace[1653525160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"494.83352ms","start":"2026-03-11T01:42:01.187281Z","end":"2026-03-11T01:42:01.682115Z","steps":["trace[1653525160] 'range keys from in-memory index tree' (duration: 494.583164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:01.682206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:01.187218Z","time spent":"494.974904ms","remote":"127.0.0.1:42488","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-03-11T01:42:02.246404Z","caller":"traceutil/trace.go:171","msg":"trace[245374140] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"560.193809ms","start":"2026-03-11T01:42:01.686183Z","end":"2026-03-11T01:42:02.246377Z","steps":["trace[245374140] 'process raft request' (duration: 560.056846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:02.246553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:01.686165Z","time spent":"560.309983ms","remote":"127.0.0.1:42488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:42:03.949024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.007354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:42:03.949102Z","caller":"traceutil/trace.go:171","msg":"trace[821283584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"132.136908ms","start":"2026-03-11T01:42:03.816951Z","end":"2026-03-11T01:42:03.949088Z","steps":["trace[821283584] 'range keys from in-memory index tree' (duration: 131.760378ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:42:21.391479Z","caller":"traceutil/trace.go:171","msg":"trace[1337159426] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:734; }","duration":"153.251801ms","start":"2026-03-11T01:42:21.238204Z","end":"2026-03-11T01:42:21.391456Z","steps":["trace[1337159426] 'read index received' (duration: 153.160118ms)","trace[1337159426] 'applied index is now lower than readState.Index' (duration: 91.103µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:42:21.391571Z","caller":"traceutil/trace.go:171","msg":"trace[1993194351] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"155.711214ms","start":"2026-03-11T01:42:21.235844Z","end":"2026-03-11T01:42:21.391555Z","steps":["trace[1993194351] 'process raft request' (duration: 155.493479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:21.391654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.444835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-11T01:42:21.391859Z","caller":"traceutil/trace.go:171","msg":"trace[1923852464] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:709; }","duration":"153.662781ms","start":"2026-03-11T01:42:21.238189Z","end":"2026-03-11T01:42:21.391852Z","steps":["trace[1923852464] 'agreement among raft nodes before linearized reading' (duration: 153.380144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:21.783496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.572103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:42:21.783574Z","caller":"traceutil/trace.go:171","msg":"trace[1403743860] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"388.292301ms","start":"2026-03-11T01:42:21.395268Z","end":"2026-03-11T01:42:21.78356Z","steps":["trace[1403743860] 'process raft request' (duration: 26.504544ms)","trace[1403743860] 'compare' (duration: 361.242343ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:42:21.783613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:21.395254Z","time spent":"388.341422ms","remote":"127.0.0.1:42488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:42:21.851026Z","caller":"traceutil/trace.go:171","msg":"trace[1533316413] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"239.028106ms","start":"2026-03-11T01:42:21.611963Z","end":"2026-03-11T01:42:21.850991Z","steps":["trace[1533316413] 'process raft request' (duration: 238.735238ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:42:22.532335Z","caller":"traceutil/trace.go:171","msg":"trace[1830766173] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:737; }","duration":"197.989791ms","start":"2026-03-11T01:42:22.334323Z","end":"2026-03-11T01:42:22.532312Z","steps":["trace[1830766173] 'read index received' (duration: 197.809127ms)","trace[1830766173] 'applied index is now lower than readState.Index' (duration: 179.764µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:42:22.532472Z","caller":"traceutil/trace.go:171","msg":"trace[674164864] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"257.827027ms","start":"2026-03-11T01:42:22.274604Z","end":"2026-03-11T01:42:22.532431Z","steps":["trace[674164864] 'process raft request' (duration: 257.578401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:22.532571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.233767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-11T01:42:22.532615Z","caller":"traceutil/trace.go:171","msg":"trace[1392350215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:712; }","duration":"198.31963ms","start":"2026-03-11T01:42:22.334282Z","end":"2026-03-11T01:42:22.532602Z","steps":["trace[1392350215] 'agreement among raft nodes before linearized reading' (duration: 198.149345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:45.957444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.198462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:42:45.957588Z","caller":"traceutil/trace.go:171","msg":"trace[1997380075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"140.394587ms","start":"2026-03-11T01:42:45.817169Z","end":"2026-03-11T01:42:45.957563Z","steps":["trace[1997380075] 'range keys from in-memory index tree' (duration: 140.118169ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:42:47.107808Z","caller":"traceutil/trace.go:171","msg":"trace[1088040546] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"411.024652ms","start":"2026-03-11T01:42:46.696761Z","end":"2026-03-11T01:42:47.107785Z","steps":["trace[1088040546] 'read index received' (duration: 410.805137ms)","trace[1088040546] 'applied index is now lower than readState.Index' (duration: 218.645µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:42:47.108035Z","caller":"traceutil/trace.go:171","msg":"trace[1023972664] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"449.424686ms","start":"2026-03-11T01:42:46.65855Z","end":"2026-03-11T01:42:47.107974Z","steps":["trace[1023972664] 'process raft request' (duration: 449.101988ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:47.108209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.362601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T01:42:47.108313Z","caller":"traceutil/trace.go:171","msg":"trace[1004248709] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:784; }","duration":"411.522655ms","start":"2026-03-11T01:42:46.69672Z","end":"2026-03-11T01:42:47.108243Z","steps":["trace[1004248709] 'agreement among raft nodes before linearized reading' (duration: 411.228067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:47.108329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:46.658524Z","time spent":"449.6339ms","remote":"127.0.0.1:42488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:42:47.108372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.171392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-11T01:42:47.108379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:46.696705Z","time spent":"411.661599ms","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-03-11T01:42:47.108511Z","caller":"traceutil/trace.go:171","msg":"trace[1987946876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"384.373317ms","start":"2026-03-11T01:42:46.724094Z","end":"2026-03-11T01:42:47.108467Z","steps":["trace[1987946876] 'agreement among raft nodes before linearized reading' (duration: 384.028017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:47.108569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:46.724073Z","time spent":"384.48478ms","remote":"127.0.0.1:42488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-11T01:42:47.808836Z","caller":"traceutil/trace.go:171","msg":"trace[726923346] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"210.285161ms","start":"2026-03-11T01:42:47.598526Z","end":"2026-03-11T01:42:47.808811Z","steps":["trace[726923346] 'process raft request' (duration: 210.105226ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:42:55.273819Z","caller":"traceutil/trace.go:171","msg":"trace[1494225521] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"103.699178ms","start":"2026-03-11T01:42:55.170098Z","end":"2026-03-11T01:42:55.273797Z","steps":["trace[1494225521] 'read index received' (duration: 103.537464ms)","trace[1494225521] 'applied index is now lower than readState.Index' (duration: 160.594µs)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:42:55.274832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.717424ms","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-03-11T01:42:55.274943Z","caller":"traceutil/trace.go:171","msg":"trace[1642570147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:807; }","duration":"104.872459ms","start":"2026-03-11T01:42:55.170041Z","end":"2026-03-11T01:42:55.274913Z","steps":["trace[1642570147] 'agreement among raft nodes before linearized reading' (duration: 104.327324ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:42:55.274777Z","caller":"traceutil/trace.go:171","msg":"trace[552054274] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"111.837889ms","start":"2026-03-11T01:42:55.162899Z","end":"2026-03-11T01:42:55.274737Z","steps":["trace[552054274] 'process raft request' (duration: 110.748691ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:02.564604Z","caller":"traceutil/trace.go:171","msg":"trace[1873552941] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"370.862085ms","start":"2026-03-11T01:43:02.193717Z","end":"2026-03-11T01:43:02.564579Z","steps":["trace[1873552941] 'process raft request' (duration: 370.721671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:02.56476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:43:02.193703Z","time spent":"370.982777ms","remote":"127.0.0.1:42488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:43:16.100305Z","caller":"traceutil/trace.go:171","msg":"trace[1199499584] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"149.396451ms","start":"2026-03-11T01:43:15.950885Z","end":"2026-03-11T01:43:16.100282Z","steps":["trace[1199499584] 'process raft request' (duration: 149.281348ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:19.501059Z","caller":"traceutil/trace.go:171","msg":"trace[8516151] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"116.309237ms","start":"2026-03-11T01:43:19.384734Z","end":"2026-03-11T01:43:19.501043Z","steps":["trace[8516151] 'read index received' (duration: 116.233156ms)","trace[8516151] 'applied index is now lower than readState.Index' (duration: 75.311µs)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:43:19.501209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.464231ms","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-03-11T01:43:19.501229Z","caller":"traceutil/trace.go:171","msg":"trace[1410005731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:939; }","duration":"116.521922ms","start":"2026-03-11T01:43:19.3847Z","end":"2026-03-11T01:43:19.501221Z","steps":["trace[1410005731] 'agreement among raft nodes before linearized reading' (duration: 116.43529ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:19.501214Z","caller":"traceutil/trace.go:171","msg":"trace[520873767] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"284.13459ms","start":"2026-03-11T01:43:19.216999Z","end":"2026-03-11T01:43:19.501134Z","steps":["trace[520873767] 'process raft request' (duration: 283.947025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:19.501345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.469308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-11T01:43:19.501399Z","caller":"traceutil/trace.go:171","msg":"trace[1906126141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:939; }","duration":"104.54883ms","start":"2026-03-11T01:43:19.396835Z","end":"2026-03-11T01:43:19.501384Z","steps":["trace[1906126141] 'agreement among raft nodes before linearized reading' (duration: 104.343315ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:19.748489Z","caller":"traceutil/trace.go:171","msg":"trace[297088894] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"242.41063ms","start":"2026-03-11T01:43:19.506056Z","end":"2026-03-11T01:43:19.748467Z","steps":["trace[297088894] 'process raft request' (duration: 230.345895ms)","trace[297088894] 'compare' (duration: 11.803079ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:43:19.78563Z","caller":"traceutil/trace.go:171","msg":"trace[1592101274] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"279.250802ms","start":"2026-03-11T01:43:19.506362Z","end":"2026-03-11T01:43:19.785613Z","steps":["trace[1592101274] 'process raft request' (duration: 279.117859ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:19.78586Z","caller":"traceutil/trace.go:171","msg":"trace[1056273254] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:979; }","duration":"276.863259ms","start":"2026-03-11T01:43:19.508976Z","end":"2026-03-11T01:43:19.785839Z","steps":["trace[1056273254] 'read index received' (duration: 227.371998ms)","trace[1056273254] 'applied index is now lower than readState.Index' (duration: 49.490541ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:43:19.785945Z","caller":"traceutil/trace.go:171","msg":"trace[1907699089] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"279.474068ms","start":"2026-03-11T01:43:19.506443Z","end":"2026-03-11T01:43:19.785917Z","steps":["trace[1907699089] 'process raft request' (duration: 279.136889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:19.786053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.056405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-11T01:43:19.786115Z","caller":"traceutil/trace.go:171","msg":"trace[2006275285] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:943; }","duration":"277.153938ms","start":"2026-03-11T01:43:19.508945Z","end":"2026-03-11T01:43:19.786099Z","steps":["trace[2006275285] 'agreement among raft nodes before linearized reading' (duration: 277.030205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:19.786513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.512943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-hl9p5\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-03-11T01:43:19.78656Z","caller":"traceutil/trace.go:171","msg":"trace[510329605] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-hl9p5; range_end:; response_count:1; response_revision:943; }","duration":"260.591175ms","start":"2026-03-11T01:43:19.525958Z","end":"2026-03-11T01:43:19.786549Z","steps":["trace[510329605] 'agreement among raft nodes before linearized reading' (duration: 260.4479ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:20.262251Z","caller":"traceutil/trace.go:171","msg":"trace[1248269209] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"125.658882ms","start":"2026-03-11T01:43:20.136572Z","end":"2026-03-11T01:43:20.26223Z","steps":["trace[1248269209] 'process raft request' (duration: 125.488848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:21.345749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.118006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:43:21.34583Z","caller":"traceutil/trace.go:171","msg":"trace[2093352757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"529.2558ms","start":"2026-03-11T01:43:20.816555Z","end":"2026-03-11T01:43:21.345811Z","steps":["trace[2093352757] 'range keys from in-memory index tree' (duration: 528.91701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:21.345871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:43:20.816537Z","time spent":"529.324981ms","remote":"127.0.0.1:42184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-11T01:43:21.89495Z","caller":"traceutil/trace.go:171","msg":"trace[1171560981] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"106.125392ms","start":"2026-03-11T01:43:21.788802Z","end":"2026-03-11T01:43:21.894928Z","steps":["trace[1171560981] 'read index received' (duration: 105.872645ms)","trace[1171560981] 'applied index is now lower than readState.Index' (duration: 251.607µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:43:21.895051Z","caller":"traceutil/trace.go:171","msg":"trace[1942761470] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"140.282992ms","start":"2026-03-11T01:43:21.754759Z","end":"2026-03-11T01:43:21.895042Z","steps":["trace[1942761470] 'process raft request' (duration: 140.035416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:21.895246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.424479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-11T01:43:21.895291Z","caller":"traceutil/trace.go:171","msg":"trace[1603741780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:952; }","duration":"106.504732ms","start":"2026-03-11T01:43:21.788775Z","end":"2026-03-11T01:43:21.895279Z","steps":["trace[1603741780] 'agreement among raft nodes before linearized reading' (duration: 106.370618ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:21.895412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.606537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-11T01:43:21.895506Z","caller":"traceutil/trace.go:171","msg":"trace[1652769633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:952; }","duration":"105.756751ms","start":"2026-03-11T01:43:21.789724Z","end":"2026-03-11T01:43:21.895481Z","steps":["trace[1652769633] 'agreement among raft nodes before linearized reading' (duration: 105.482434ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:22.536705Z","caller":"traceutil/trace.go:171","msg":"trace[2134669714] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"267.67348ms","start":"2026-03-11T01:43:22.268998Z","end":"2026-03-11T01:43:22.536672Z","steps":["trace[2134669714] 'process raft request' (duration: 267.430513ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:23.230489Z","caller":"traceutil/trace.go:171","msg":"trace[2117469252] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"375.470896ms","start":"2026-03-11T01:43:22.855Z","end":"2026-03-11T01:43:23.230471Z","steps":["trace[2117469252] 'process raft request' (duration: 354.807695ms)","trace[2117469252] 'compare' (duration: 20.344392ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:43:23.230583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:43:22.85499Z","time spent":"375.546607ms","remote":"127.0.0.1:42488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-11T01:43:23.23079Z","caller":"traceutil/trace.go:171","msg":"trace[1609366192] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"290.647949ms","start":"2026-03-11T01:43:22.940134Z","end":"2026-03-11T01:43:23.230782Z","steps":["trace[1609366192] 'read index received' (duration: 269.762693ms)","trace[1609366192] 'applied index is now lower than readState.Index' (duration: 20.883806ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:43:23.230873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.739351ms","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-03-11T01:43:23.230897Z","caller":"traceutil/trace.go:171","msg":"trace[1136397593] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:959; }","duration":"290.809543ms","start":"2026-03-11T01:43:22.940081Z","end":"2026-03-11T01:43:23.230891Z","steps":["trace[1136397593] 'agreement among raft nodes before linearized reading' (duration: 290.732091ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:32.967251Z","caller":"traceutil/trace.go:171","msg":"trace[1996952847] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"375.217598ms","start":"2026-03-11T01:43:32.592009Z","end":"2026-03-11T01:43:32.967226Z","steps":["trace[1996952847] 'read index received' (duration: 374.703574ms)","trace[1996952847] 'applied index is now lower than readState.Index' (duration: 513.084µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:43:32.967376Z","caller":"traceutil/trace.go:171","msg":"trace[1915273916] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"463.568375ms","start":"2026-03-11T01:43:32.503778Z","end":"2026-03-11T01:43:32.967347Z","steps":["trace[1915273916] 'process raft request' (duration: 463.164304ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:32.967522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:43:32.503761Z","time spent":"463.687049ms","remote":"127.0.0.1:42390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6611,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:43:32.967541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.457564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-11T01:43:32.967583Z","caller":"traceutil/trace.go:171","msg":"trace[775595326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"375.571217ms","start":"2026-03-11T01:43:32.591998Z","end":"2026-03-11T01:43:32.96757Z","steps":["trace[775595326] 'agreement among raft nodes before linearized reading' (duration: 375.352321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:32.967567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.336887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-11T01:43:32.967629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:43:32.591943Z","time spent":"375.676621ms","remote":"127.0.0.1:42488","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":"info","ts":"2026-03-11T01:43:32.967657Z","caller":"traceutil/trace.go:171","msg":"trace[765024930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"150.469969ms","start":"2026-03-11T01:43:32.817163Z","end":"2026-03-11T01:43:32.967633Z","steps":["trace[765024930] 'agreement among raft nodes before linearized reading' (duration: 150.290535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:32.967795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.83606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-11T01:43:32.967903Z","caller":"traceutil/trace.go:171","msg":"trace[785513837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1027; }","duration":"183.984693ms","start":"2026-03-11T01:43:32.783898Z","end":"2026-03-11T01:43:32.967883Z","steps":["trace[785513837] 'agreement among raft nodes before linearized reading' (duration: 183.617144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:32.968099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.45854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-11T01:43:32.96823Z","caller":"traceutil/trace.go:171","msg":"trace[1879110601] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1027; }","duration":"312.628414ms","start":"2026-03-11T01:43:32.655584Z","end":"2026-03-11T01:43:32.968212Z","steps":["trace[1879110601] 'agreement among raft nodes before linearized reading' (duration: 311.787083ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:32.968271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:43:32.655559Z","time spent":"312.699167ms","remote":"127.0.0.1:42488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-11T01:44:11.27285Z","caller":"traceutil/trace.go:171","msg":"trace[832525725] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1233; }","duration":"101.379331ms","start":"2026-03-11T01:44:11.171455Z","end":"2026-03-11T01:44:11.272835Z","steps":["trace[832525725] 'read index received' (duration: 101.209567ms)","trace[832525725] 'applied index is now lower than readState.Index' (duration: 169.194µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:44:11.272878Z","caller":"traceutil/trace.go:171","msg":"trace[2103214401] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"107.377549ms","start":"2026-03-11T01:44:11.165489Z","end":"2026-03-11T01:44:11.272867Z","steps":["trace[2103214401] 'process raft request' (duration: 107.190774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:11.273096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.619499ms","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-03-11T01:44:11.27316Z","caller":"traceutil/trace.go:171","msg":"trace[693070869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1184; }","duration":"101.711551ms","start":"2026-03-11T01:44:11.171432Z","end":"2026-03-11T01:44:11.273144Z","steps":["trace[693070869] 'agreement among raft nodes before linearized reading' (duration: 101.470535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:35.52329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.879875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:44:35.523494Z","caller":"traceutil/trace.go:171","msg":"trace[488593279] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"130.814442ms","start":"2026-03-11T01:44:35.392607Z","end":"2026-03-11T01:44:35.523421Z","steps":["trace[488593279] 'process raft request' (duration: 28.718752ms)","trace[488593279] 'compare' (duration: 101.788803ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:46:06.244535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.008657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T01:46:06.244679Z","caller":"traceutil/trace.go:171","msg":"trace[829319459] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1653; }","duration":"121.236523ms","start":"2026-03-11T01:46:06.123417Z","end":"2026-03-11T01:46:06.244653Z","steps":["trace[829319459] 'count revisions from in-memory index tree' (duration: 120.888474ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:28.551298Z","caller":"traceutil/trace.go:171","msg":"trace[13763683] transaction","detail":"{read_only:false; response_revision:1760; number_of_response:1; }","duration":"141.936422ms","start":"2026-03-11T01:46:28.409328Z","end":"2026-03-11T01:46:28.551264Z","steps":["trace[13763683] 'process raft request' (duration: 141.435959ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:46.363777Z","caller":"traceutil/trace.go:171","msg":"trace[1426922062] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"109.509759ms","start":"2026-03-11T01:46:46.254239Z","end":"2026-03-11T01:46:46.363749Z","steps":["trace[1426922062] 'process raft request' (duration: 109.374206ms)"],"step_count":1}