{"level":"warn","ts":"2026-10-16T00:09:11.940915Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T00:09:11.941028Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.189: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.189:2380","--initial-cluster=instance=https://162.253.55.189:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.189:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.189: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-10-16T00:09:11.941106Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T00:09:11.941121Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.189:2380"]} {"level":"info","ts":"2026-10-16T00:09:11.941147Z","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-10-16T00:09:11.942055Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.189:2379"]} {"level":"info","ts":"2026-10-16T00:09:11.942158Z","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.189:2380"],"listen-peer-urls":["https://162.253.55.189:2380"],"advertise-client-urls":["https://162.253.55.189:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.189:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.189: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-10-16T00:09:11.946989Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.588566ms"} {"level":"info","ts":"2026-10-16T00:09:11.959259Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f14c7d724df9ef94","cluster-id":"f7bde5cbb4d65447"} {"level":"info","ts":"2026-10-16T00:09:11.959333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T00:09:11.95936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became follower at term 0"} {"level":"info","ts":"2026-10-16T00:09:11.95937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f14c7d724df9ef94 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T00:09:11.959383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became follower at term 1"} {"level":"info","ts":"2026-10-16T00:09:11.959423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 switched to configuration voters=(17387410191258611604)"} {"level":"warn","ts":"2026-10-16T00:09:11.964662Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T00:09:11.966473Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T00:09:11.969077Z","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-10-16T00:09:11.974113Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f14c7d724df9ef94","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T00:09:11.974338Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f14c7d724df9ef94","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T00:09:11.97449Z","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-10-16T00:09:11.974537Z","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-10-16T00:09:11.974545Z","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-10-16T00:09:11.975612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 switched to configuration voters=(17387410191258611604)"} {"level":"info","ts":"2026-10-16T00:09:11.975831Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f7bde5cbb4d65447","local-member-id":"f14c7d724df9ef94","added-peer-id":"f14c7d724df9ef94","added-peer-peer-urls":["https://162.253.55.189:2380"]} {"level":"info","ts":"2026-10-16T00:09:11.982511Z","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-10-16T00:09:11.98271Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.189:2380"} {"level":"info","ts":"2026-10-16T00:09:11.982747Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.189:2380"} {"level":"info","ts":"2026-10-16T00:09:11.983789Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f14c7d724df9ef94","initial-advertise-peer-urls":["https://162.253.55.189:2380"],"listen-peer-urls":["https://162.253.55.189:2380"],"advertise-client-urls":["https://162.253.55.189:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.189:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T00:09:11.983844Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T00:09:12.059828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T00:09:12.059893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T00:09:12.059917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 received MsgPreVoteResp from f14c7d724df9ef94 at term 1"} {"level":"info","ts":"2026-10-16T00:09:12.059944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became candidate at term 2"} {"level":"info","ts":"2026-10-16T00:09:12.05996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 received MsgVoteResp from f14c7d724df9ef94 at term 2"} {"level":"info","ts":"2026-10-16T00:09:12.059976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became leader at term 2"} {"level":"info","ts":"2026-10-16T00:09:12.059994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f14c7d724df9ef94 elected leader f14c7d724df9ef94 at term 2"} {"level":"info","ts":"2026-10-16T00:09:12.061013Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:09:12.061902Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f14c7d724df9ef94","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.189:2379]}","request-path":"/0/members/f14c7d724df9ef94/attributes","cluster-id":"f7bde5cbb4d65447","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T00:09:12.061906Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:09:12.061943Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:09:12.06234Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T00:09:12.062368Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T00:09:12.062765Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f7bde5cbb4d65447","local-member-id":"f14c7d724df9ef94","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:09:12.062895Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:09:12.062937Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:09:12.065062Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T00:09:12.065595Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.189:2379"} {"level":"warn","ts":"2026-10-16T00:09:18.055138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.600393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:09:18.055315Z","caller":"traceutil/trace.go:171","msg":"trace[657129067] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"326.627572ms","start":"2026-10-16T00:09:17.72866Z","end":"2026-10-16T00:09:18.055287Z","steps":["trace[657129067] 'read index received' (duration: 83.265382ms)","trace[657129067] 'applied index is now lower than readState.Index' (duration: 243.36054ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:09:18.055408Z","caller":"traceutil/trace.go:171","msg":"trace[2027381901] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"347.223436ms","start":"2026-10-16T00:09:17.70817Z","end":"2026-10-16T00:09:18.055394Z","steps":["trace[2027381901] 'process raft request' (duration: 103.749543ms)","trace[2027381901] 'compare' (duration: 242.452319ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:09:18.055485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:17.708124Z","time spent":"347.325948ms","remote":"127.0.0.1:45342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-16T00:09:18.055717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.988301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:09:18.055809Z","caller":"traceutil/trace.go:171","msg":"trace[1990954187] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:285; }","duration":"327.175145ms","start":"2026-10-16T00:09:17.728608Z","end":"2026-10-16T00:09:18.055783Z","steps":["trace[1990954187] 'agreement among raft nodes before linearized reading' (duration: 326.96301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:18.05586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:17.728584Z","time spent":"327.262777ms","remote":"127.0.0.1:45342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" "} {"level":"info","ts":"2026-10-16T00:09:18.09706Z","caller":"traceutil/trace.go:171","msg":"trace[1845187055] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"245.723934ms","start":"2026-10-16T00:09:17.851309Z","end":"2026-10-16T00:09:18.097033Z","steps":["trace[1845187055] 'process raft request' (duration: 245.598712ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:18.369599Z","caller":"traceutil/trace.go:171","msg":"trace[884103317] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:291; }","duration":"314.215418ms","start":"2026-10-16T00:09:18.055357Z","end":"2026-10-16T00:09:18.369573Z","steps":["trace[884103317] 'read index received' (duration: 41.627616ms)","trace[884103317] 'applied index is now lower than readState.Index' (duration: 272.586822ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:09:18.369661Z","caller":"traceutil/trace.go:171","msg":"trace[1569318811] transaction","detail":"{read_only:false; number_of_response:0; response_revision:286; }","duration":"420.115ms","start":"2026-10-16T00:09:17.949518Z","end":"2026-10-16T00:09:18.369633Z","steps":["trace[1569318811] 'process raft request' (duration: 419.878235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:18.369791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.450496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:09:18.369797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:17.949497Z","time spent":"420.239922ms","remote":"127.0.0.1:45298","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-16T00:09:18.369778Z","caller":"traceutil/trace.go:171","msg":"trace[1503130848] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"302.392706ms","start":"2026-10-16T00:09:18.067343Z","end":"2026-10-16T00:09:18.369736Z","steps":["trace[1503130848] 'process raft request' (duration: 302.143921ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:18.369829Z","caller":"traceutil/trace.go:171","msg":"trace[1321217196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:287; }","duration":"416.529177ms","start":"2026-10-16T00:09:17.953288Z","end":"2026-10-16T00:09:18.369818Z","steps":["trace[1321217196] 'agreement among raft nodes before linearized reading' (duration: 416.426735ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:18.369839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.817255ms","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-10-16T00:09:18.369837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.004572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:09:18.369864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:17.953279Z","time spent":"416.576299ms","remote":"127.0.0.1:45418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-16T00:09:18.369903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:18.067326Z","time spent":"302.524439ms","remote":"127.0.0.1:45632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3200,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-16T00:09:18.369873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.962041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-10-16T00:09:18.369913Z","caller":"traceutil/trace.go:171","msg":"trace[39890924] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:287; }","duration":"519.132974ms","start":"2026-10-16T00:09:17.850763Z","end":"2026-10-16T00:09:18.369896Z","steps":["trace[39890924] 'agreement among raft nodes before linearized reading' (duration: 518.996781ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:18.369916Z","caller":"traceutil/trace.go:171","msg":"trace[107493893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:287; }","duration":"406.933947ms","start":"2026-10-16T00:09:17.962956Z","end":"2026-10-16T00:09:18.36989Z","steps":["trace[107493893] 'agreement among raft nodes before linearized reading' (duration: 406.707652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:18.369954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:17.850741Z","time spent":"519.205066ms","remote":"127.0.0.1:45418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-10-16T00:09:18.369962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:17.962935Z","time spent":"407.017819ms","remote":"127.0.0.1:45418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-16T00:09:18.369981Z","caller":"traceutil/trace.go:171","msg":"trace[449414182] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:287; }","duration":"519.114254ms","start":"2026-10-16T00:09:17.850821Z","end":"2026-10-16T00:09:18.369935Z","steps":["trace[449414182] 'agreement among raft nodes before linearized reading' (duration: 518.94934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:18.370216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:17.850797Z","time spent":"519.409001ms","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-10-16T00:09:18.841418Z","caller":"traceutil/trace.go:171","msg":"trace[1761565644] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"740.006088ms","start":"2026-10-16T00:09:18.101373Z","end":"2026-10-16T00:09:18.841379Z","steps":["trace[1761565644] 'process raft request' (duration: 729.401544ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:18.841429Z","caller":"traceutil/trace.go:171","msg":"trace[1113891061] transaction","detail":"{read_only:false; number_of_response:0; response_revision:288; }","duration":"661.601546ms","start":"2026-10-16T00:09:18.179805Z","end":"2026-10-16T00:09:18.841407Z","steps":["trace[1113891061] 'process raft request' (duration: 661.510594ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:18.841559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:18.101358Z","time spent":"740.13623ms","remote":"127.0.0.1:45164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-16T00:09:18.841566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:18.179789Z","time spent":"661.718929ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-16T00:09:18.841562Z","caller":"traceutil/trace.go:171","msg":"trace[35893673] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"661.208597ms","start":"2026-10-16T00:09:18.180318Z","end":"2026-10-16T00:09:18.841526Z","steps":["trace[35893673] 'process raft request' (duration: 661.080764ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:18.841628Z","caller":"traceutil/trace.go:171","msg":"trace[526980851] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"661.046434ms","start":"2026-10-16T00:09:18.180574Z","end":"2026-10-16T00:09:18.84162Z","steps":["trace[526980851] 'process raft request' (duration: 660.883921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:18.841688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:18.180563Z","time spent":"661.092055ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-16T00:09:18.841702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:18.180281Z","time spent":"661.356321ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-16T00:09:18.841786Z","caller":"traceutil/trace.go:171","msg":"trace[1604756469] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:294; }","duration":"472.086174ms","start":"2026-10-16T00:09:18.369687Z","end":"2026-10-16T00:09:18.841773Z","steps":["trace[1604756469] 'read index received' (duration: 461.162883ms)","trace[1604756469] 'applied index is now lower than readState.Index' (duration: 10.922271ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:09:18.841852Z","caller":"traceutil/trace.go:171","msg":"trace[976004465] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"661.170196ms","start":"2026-10-16T00:09:18.180671Z","end":"2026-10-16T00:09:18.841841Z","steps":["trace[976004465] 'process raft request' (duration: 660.811848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:18.841895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.423792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-10-16T00:09:18.841924Z","caller":"traceutil/trace.go:171","msg":"trace[535270507] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:293; }","duration":"782.492123ms","start":"2026-10-16T00:09:18.059422Z","end":"2026-10-16T00:09:18.841915Z","steps":["trace[535270507] 'agreement among raft nodes before linearized reading' (duration: 782.431882ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:18.841925Z","caller":"traceutil/trace.go:171","msg":"trace[1982579429] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"466.755901ms","start":"2026-10-16T00:09:18.375159Z","end":"2026-10-16T00:09:18.841915Z","steps":["trace[1982579429] 'process raft request' (duration: 466.353982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:18.841949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:18.0594Z","time spent":"782.542715ms","remote":"127.0.0.1:45218","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-10-16T00:09:18.841956Z","caller":"traceutil/trace.go:171","msg":"trace[366625190] transaction","detail":"{read_only:false; number_of_response:0; response_revision:288; }","duration":"661.972745ms","start":"2026-10-16T00:09:18.179972Z","end":"2026-10-16T00:09:18.841945Z","steps":["trace[366625190] 'process raft request' (duration: 661.394172ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:18.842012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:18.179964Z","time spent":"662.022546ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-16T00:09:18.842061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.232234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-10-16T00:09:18.842097Z","caller":"traceutil/trace.go:171","msg":"trace[710475381] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:293; }","duration":"471.299255ms","start":"2026-10-16T00:09:18.370787Z","end":"2026-10-16T00:09:18.842086Z","steps":["trace[710475381] 'agreement among raft nodes before linearized reading' (duration: 471.212563ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:18.842092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.197616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"warn","ts":"2026-10-16T00:09:18.842122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:18.37077Z","time spent":"471.345957ms","remote":"127.0.0.1:45298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4041,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-16T00:09:18.842149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:18.375138Z","time spent":"466.810593ms","remote":"127.0.0.1:45418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:09:18.84159Z","caller":"traceutil/trace.go:171","msg":"trace[1459911841] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"465.468411ms","start":"2026-10-16T00:09:18.376105Z","end":"2026-10-16T00:09:18.841574Z","steps":["trace[1459911841] 'process raft request' (duration: 465.443451ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:18.842157Z","caller":"traceutil/trace.go:171","msg":"trace[1836431040] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:293; }","duration":"465.278177ms","start":"2026-10-16T00:09:18.376858Z","end":"2026-10-16T00:09:18.842136Z","steps":["trace[1836431040] 'agreement among raft nodes before linearized reading' (duration: 465.169745ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:18.842221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.15374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-10-16T00:09:18.842251Z","caller":"traceutil/trace.go:171","msg":"trace[219798151] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:293; }","duration":"467.204342ms","start":"2026-10-16T00:09:18.375038Z","end":"2026-10-16T00:09:18.842243Z","steps":["trace[219798151] 'agreement among raft nodes before linearized reading' (duration: 467.12698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:18.842242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:18.376851Z","time spent":"465.381399ms","remote":"127.0.0.1:45148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":139,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-10-16T00:09:18.842253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:18.376094Z","time spent":"466.130667ms","remote":"127.0.0.1:45418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-16T00:09:18.842275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:18.375025Z","time spent":"467.244632ms","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-10-16T00:09:18.842312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.793555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-10-16T00:09:18.842341Z","caller":"traceutil/trace.go:171","msg":"trace[778940836] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:293; }","duration":"467.862347ms","start":"2026-10-16T00:09:18.374469Z","end":"2026-10-16T00:09:18.842331Z","steps":["trace[778940836] 'agreement among raft nodes before linearized reading' (duration: 467.808165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:18.842363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:18.374447Z","time spent":"467.911117ms","remote":"127.0.0.1:45298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4041,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-16T00:09:18.842421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.621918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-16T00:09:18.842448Z","caller":"traceutil/trace.go:171","msg":"trace[998464413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:293; }","duration":"193.710319ms","start":"2026-10-16T00:09:18.648728Z","end":"2026-10-16T00:09:18.842439Z","steps":["trace[998464413] 'agreement among raft nodes before linearized reading' (duration: 193.660898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:18.842707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:18.180651Z","time spent":"661.230358ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-16T00:09:19.271624Z","caller":"traceutil/trace.go:171","msg":"trace[893917946] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"106.26954ms","start":"2026-10-16T00:09:19.165276Z","end":"2026-10-16T00:09:19.271546Z","steps":["trace[893917946] 'process raft request' (duration: 49.399454ms)","trace[893917946] 'compare' (duration: 56.59338ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:10:04.991316Z","caller":"traceutil/trace.go:171","msg":"trace[1729563118] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"263.2345ms","start":"2026-10-16T00:10:04.728062Z","end":"2026-10-16T00:10:04.991297Z","steps":["trace[1729563118] 'process raft request' (duration: 263.058286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:04.991434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.479463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:10:04.991475Z","caller":"traceutil/trace.go:171","msg":"trace[1064978892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"135.569005ms","start":"2026-10-16T00:10:04.855893Z","end":"2026-10-16T00:10:04.991462Z","steps":["trace[1064978892] 'agreement among raft nodes before linearized reading' (duration: 135.470563ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:04.991292Z","caller":"traceutil/trace.go:171","msg":"trace[1533256447] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"135.298288ms","start":"2026-10-16T00:10:04.855948Z","end":"2026-10-16T00:10:04.991246Z","steps":["trace[1533256447] 'read index received' (duration: 135.139834ms)","trace[1533256447] 'applied index is now lower than readState.Index' (duration: 157.444µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:10:05.148087Z","caller":"traceutil/trace.go:171","msg":"trace[1060332264] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"144.053417ms","start":"2026-10-16T00:10:05.004007Z","end":"2026-10-16T00:10:05.148061Z","steps":["trace[1060332264] 'read index received' (duration: 57.394622ms)","trace[1060332264] 'applied index is now lower than readState.Index' (duration: 86.657815ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:10:05.148119Z","caller":"traceutil/trace.go:171","msg":"trace[1278948133] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"149.554802ms","start":"2026-10-16T00:10:04.998539Z","end":"2026-10-16T00:10:05.148094Z","steps":["trace[1278948133] 'process raft request' (duration: 62.850836ms)","trace[1278948133] 'compare' (duration: 86.546322ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:10:05.148257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.19306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:10:05.148296Z","caller":"traceutil/trace.go:171","msg":"trace[439426830] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:646; }","duration":"144.304873ms","start":"2026-10-16T00:10:05.00398Z","end":"2026-10-16T00:10:05.148285Z","steps":["trace[439426830] 'agreement among raft nodes before linearized reading' (duration: 144.17236ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:05.329917Z","caller":"traceutil/trace.go:171","msg":"trace[52316102] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"149.236104ms","start":"2026-10-16T00:10:05.180644Z","end":"2026-10-16T00:10:05.32988Z","steps":["trace[52316102] 'process raft request' (duration: 143.409292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:06.316737Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17263600576635346024,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-16T00:10:06.442119Z","caller":"traceutil/trace.go:171","msg":"trace[1865579331] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"625.76591ms","start":"2026-10-16T00:10:05.81633Z","end":"2026-10-16T00:10:06.442095Z","steps":["trace[1865579331] 'read index received' (duration: 625.513684ms)","trace[1865579331] 'applied index is now lower than readState.Index' (duration: 251.246µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:10:06.442214Z","caller":"traceutil/trace.go:171","msg":"trace[1677342714] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"779.21085ms","start":"2026-10-16T00:10:05.662958Z","end":"2026-10-16T00:10:06.442169Z","steps":["trace[1677342714] 'process raft request' (duration: 778.956474ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:06.442334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.991635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.189\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-16T00:10:06.442377Z","caller":"traceutil/trace.go:171","msg":"trace[606493190] range","detail":"{range_begin:/registry/masterleases/162.253.55.189; range_end:; response_count:1; response_revision:655; }","duration":"626.081767ms","start":"2026-10-16T00:10:05.816282Z","end":"2026-10-16T00:10:06.442363Z","steps":["trace[606493190] 'agreement among raft nodes before linearized reading' (duration: 625.965824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:06.442407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:10:05.816205Z","time spent":"626.19437ms","remote":"127.0.0.1:45112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.189\" "} {"level":"warn","ts":"2026-10-16T00:10:06.442414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:10:05.662933Z","time spent":"779.397863ms","remote":"127.0.0.1:45508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-16T00:10:06.442549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.385397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sc44l\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-16T00:10:06.442632Z","caller":"traceutil/trace.go:171","msg":"trace[1387990055] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sc44l; range_end:; response_count:1; response_revision:655; }","duration":"418.48695ms","start":"2026-10-16T00:10:06.024125Z","end":"2026-10-16T00:10:06.442611Z","steps":["trace[1387990055] 'agreement among raft nodes before linearized reading' (duration: 418.340126ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:06.442677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:10:06.024113Z","time spent":"418.554531ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-sc44l\" "} {"level":"warn","ts":"2026-10-16T00:10:06.442579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.748275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:10:06.442889Z","caller":"traceutil/trace.go:171","msg":"trace[1168103895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"587.087942ms","start":"2026-10-16T00:10:05.855778Z","end":"2026-10-16T00:10:06.442866Z","steps":["trace[1168103895] 'agreement among raft nodes before linearized reading' (duration: 586.650212ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:06.44294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:10:05.855768Z","time spent":"587.159664ms","remote":"127.0.0.1:45076","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-10-16T00:10:06.584906Z","caller":"traceutil/trace.go:171","msg":"trace[906763309] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"140.186249ms","start":"2026-10-16T00:10:06.444691Z","end":"2026-10-16T00:10:06.584877Z","steps":["trace[906763309] 'read index received' (duration: 139.447412ms)","trace[906763309] 'applied index is now lower than readState.Index' (duration: 737.357µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:10:06.586194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.088339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:10:06.586305Z","caller":"traceutil/trace.go:171","msg":"trace[148961203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"141.639162ms","start":"2026-10-16T00:10:06.444649Z","end":"2026-10-16T00:10:06.586288Z","steps":["trace[148961203] 'agreement among raft nodes before linearized reading' (duration: 140.412564ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:06.588425Z","caller":"traceutil/trace.go:171","msg":"trace[302747549] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"125.147388ms","start":"2026-10-16T00:10:06.463261Z","end":"2026-10-16T00:10:06.588409Z","steps":["trace[302747549] 'process raft request' (duration: 125.007635ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:06.588499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.42447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:10:06.588556Z","caller":"traceutil/trace.go:171","msg":"trace[1843545902] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:656; }","duration":"131.525583ms","start":"2026-10-16T00:10:06.457017Z","end":"2026-10-16T00:10:06.588542Z","steps":["trace[1843545902] 'agreement among raft nodes before linearized reading' (duration: 131.40954ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:08.277427Z","caller":"traceutil/trace.go:171","msg":"trace[204184596] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"100.701053ms","start":"2026-10-16T00:10:08.176699Z","end":"2026-10-16T00:10:08.2774Z","steps":["trace[204184596] 'process raft request' (duration: 100.57367ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:23.326379Z","caller":"traceutil/trace.go:171","msg":"trace[759816364] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:850; }","duration":"104.641783ms","start":"2026-10-16T00:10:23.221716Z","end":"2026-10-16T00:10:23.326357Z","steps":["trace[759816364] 'read index received' (duration: 104.462349ms)","trace[759816364] 'applied index is now lower than readState.Index' (duration: 178.374µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:10:23.326461Z","caller":"traceutil/trace.go:171","msg":"trace[855007292] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"143.508925ms","start":"2026-10-16T00:10:23.182913Z","end":"2026-10-16T00:10:23.326422Z","steps":["trace[855007292] 'process raft request' (duration: 143.284339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:23.326546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.802406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:10:23.326581Z","caller":"traceutil/trace.go:171","msg":"trace[1255174791] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:820; }","duration":"104.905169ms","start":"2026-10-16T00:10:23.221667Z","end":"2026-10-16T00:10:23.326572Z","steps":["trace[1255174791] 'agreement among raft nodes before linearized reading' (duration: 104.815887ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:02.416886Z","caller":"traceutil/trace.go:171","msg":"trace[1326784533] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"123.580214ms","start":"2026-10-16T00:11:02.293281Z","end":"2026-10-16T00:11:02.416861Z","steps":["trace[1326784533] 'process raft request' (duration: 123.447291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:04.038332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.362947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:11:04.038411Z","caller":"traceutil/trace.go:171","msg":"trace[1181721994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1107; }","duration":"182.484621ms","start":"2026-10-16T00:11:03.855908Z","end":"2026-10-16T00:11:04.038392Z","steps":["trace[1181721994] 'range keys from in-memory index tree' (duration: 182.275495ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:04.038489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.149469ms","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-10-16T00:11:04.03851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.935905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-16T00:11:04.038535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.41923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-16T00:11:04.038554Z","caller":"traceutil/trace.go:171","msg":"trace[229567941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1107; }","duration":"352.240142ms","start":"2026-10-16T00:11:03.686298Z","end":"2026-10-16T00:11:04.038538Z","steps":["trace[229567941] 'range keys from in-memory index tree' (duration: 351.961075ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:04.038577Z","caller":"traceutil/trace.go:171","msg":"trace[1108618916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1107; }","duration":"430.046837ms","start":"2026-10-16T00:11:03.608515Z","end":"2026-10-16T00:11:04.038562Z","steps":["trace[1108618916] 'range keys from in-memory index tree' (duration: 429.779451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:04.038587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:03.686285Z","time spent":"352.293563ms","remote":"127.0.0.1:45418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-16T00:11:04.038625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:03.608497Z","time spent":"430.116699ms","remote":"127.0.0.1:45418","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-10-16T00:11:04.038628Z","caller":"traceutil/trace.go:171","msg":"trace[1358675152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1107; }","duration":"413.499962ms","start":"2026-10-16T00:11:03.625066Z","end":"2026-10-16T00:11:04.038566Z","steps":["trace[1358675152] 'range keys from in-memory index tree' (duration: 413.264156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:04.03866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:03.625048Z","time spent":"413.604384ms","remote":"127.0.0.1:45418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-16T00:11:17.840001Z","caller":"traceutil/trace.go:171","msg":"trace[674498019] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"211.404396ms","start":"2026-10-16T00:11:17.628177Z","end":"2026-10-16T00:11:17.839581Z","steps":["trace[674498019] 'process raft request' (duration: 211.244482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:20.317027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.841357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-16T00:11:20.317059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.937402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-16T00:11:20.317072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.96987ms","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-10-16T00:11:20.317181Z","caller":"traceutil/trace.go:171","msg":"trace[1473797756] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1161; }","duration":"485.142727ms","start":"2026-10-16T00:11:19.832011Z","end":"2026-10-16T00:11:20.317154Z","steps":["trace[1473797756] 'count revisions from in-memory index tree' (duration: 484.82402ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:20.317219Z","caller":"traceutil/trace.go:171","msg":"trace[1130356201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1161; }","duration":"112.157514ms","start":"2026-10-16T00:11:20.20504Z","end":"2026-10-16T00:11:20.317198Z","steps":["trace[1130356201] 'range keys from in-memory index tree' (duration: 111.804286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:20.317251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:19.831978Z","time spent":"485.25451ms","remote":"127.0.0.1:45496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-10-16T00:11:20.31749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.490055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-10-16T00:11:20.317525Z","caller":"traceutil/trace.go:171","msg":"trace[1356430668] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1161; }","duration":"481.549966ms","start":"2026-10-16T00:11:19.835965Z","end":"2026-10-16T00:11:20.317515Z","steps":["trace[1356430668] 'range keys from in-memory index tree' (duration: 481.304531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:20.317516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.937878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:11:20.317113Z","caller":"traceutil/trace.go:171","msg":"trace[1666186433] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1161; }","duration":"371.948279ms","start":"2026-10-16T00:11:19.945142Z","end":"2026-10-16T00:11:20.317091Z","steps":["trace[1666186433] 'count revisions from in-memory index tree' (duration: 371.743644ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:20.317587Z","caller":"traceutil/trace.go:171","msg":"trace[1193210587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1161; }","duration":"461.02601ms","start":"2026-10-16T00:11:19.856545Z","end":"2026-10-16T00:11:20.317571Z","steps":["trace[1193210587] 'range keys from in-memory index tree' (duration: 460.894287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:20.317627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.854578ms","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-10-16T00:11:20.317557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:19.835949Z","time spent":"481.599177ms","remote":"127.0.0.1:45298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5355,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-10-16T00:11:20.317656Z","caller":"traceutil/trace.go:171","msg":"trace[1538278112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1161; }","duration":"472.90826ms","start":"2026-10-16T00:11:19.844741Z","end":"2026-10-16T00:11:20.317649Z","steps":["trace[1538278112] 'range keys from in-memory index tree' (duration: 472.758086ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:20.31763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:19.856537Z","time spent":"461.084311ms","remote":"127.0.0.1:45076","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-10-16T00:11:20.31768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:19.844718Z","time spent":"472.95607ms","remote":"127.0.0.1:45418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-16T00:11:20.317615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:19.945083Z","time spent":"372.511211ms","remote":"127.0.0.1:45164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":122,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-10-16T00:11:20.702449Z","caller":"traceutil/trace.go:171","msg":"trace[1885414475] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"496.928375ms","start":"2026-10-16T00:11:20.205495Z","end":"2026-10-16T00:11:20.702423Z","steps":["trace[1885414475] 'process raft request' (duration: 496.772011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:20.702599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:20.205478Z","time spent":"497.048357ms","remote":"127.0.0.1:45164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-16T00:11:20.702686Z","caller":"traceutil/trace.go:171","msg":"trace[1036674657] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1208; }","duration":"472.163242ms","start":"2026-10-16T00:11:20.23046Z","end":"2026-10-16T00:11:20.702623Z","steps":["trace[1036674657] 'read index received' (duration: 472.154682ms)","trace[1036674657] 'applied index is now lower than readState.Index' (duration: 7.09µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:11:20.702964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.48202ms","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-10-16T00:11:20.703084Z","caller":"traceutil/trace.go:171","msg":"trace[300022578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1162; }","duration":"472.627843ms","start":"2026-10-16T00:11:20.230438Z","end":"2026-10-16T00:11:20.703066Z","steps":["trace[300022578] 'agreement among raft nodes before linearized reading' (duration: 472.263675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:20.703137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:20.230429Z","time spent":"472.698645ms","remote":"127.0.0.1:45418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-16T00:11:20.712033Z","caller":"traceutil/trace.go:171","msg":"trace[1391231579] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"200.744694ms","start":"2026-10-16T00:11:20.511268Z","end":"2026-10-16T00:11:20.712013Z","steps":["trace[1391231579] 'process raft request' (duration: 200.671293ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:20.712212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.040591ms","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-10-16T00:11:20.712312Z","caller":"traceutil/trace.go:171","msg":"trace[610061530] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"390.51511ms","start":"2026-10-16T00:11:20.321786Z","end":"2026-10-16T00:11:20.712302Z","steps":["trace[610061530] 'process raft request' (duration: 389.992178ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:20.712329Z","caller":"traceutil/trace.go:171","msg":"trace[1186305409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1165; }","duration":"480.182315ms","start":"2026-10-16T00:11:20.232128Z","end":"2026-10-16T00:11:20.71231Z","steps":["trace[1186305409] 'agreement among raft nodes before linearized reading' (duration: 479.858818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:20.712423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:20.321762Z","time spent":"390.584072ms","remote":"127.0.0.1:45418","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-10-16T00:11:20.712436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:20.232119Z","time spent":"480.303848ms","remote":"127.0.0.1:45418","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-10-16T00:11:20.712522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.983554ms","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-10-16T00:11:20.712568Z","caller":"traceutil/trace.go:171","msg":"trace[2105771701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1165; }","duration":"147.078917ms","start":"2026-10-16T00:11:20.565477Z","end":"2026-10-16T00:11:20.712556Z","steps":["trace[2105771701] 'agreement among raft nodes before linearized reading' (duration: 146.928103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:20.712689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.201671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:11:20.712753Z","caller":"traceutil/trace.go:171","msg":"trace[1951303922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1165; }","duration":"393.335975ms","start":"2026-10-16T00:11:20.319402Z","end":"2026-10-16T00:11:20.712738Z","steps":["trace[1951303922] 'agreement among raft nodes before linearized reading' (duration: 393.250703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:20.712803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:20.319381Z","time spent":"393.413926ms","remote":"127.0.0.1:45084","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-10-16T00:11:20.712229Z","caller":"traceutil/trace.go:171","msg":"trace[1758862943] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"389.754163ms","start":"2026-10-16T00:11:20.322456Z","end":"2026-10-16T00:11:20.71221Z","steps":["trace[1758862943] 'process raft request' (duration: 389.442366ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:20.71293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:20.32243Z","time spent":"390.436149ms","remote":"127.0.0.1:45418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:11:20.839747Z","caller":"traceutil/trace.go:171","msg":"trace[699759643] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"122.744355ms","start":"2026-10-16T00:11:20.716963Z","end":"2026-10-16T00:11:20.839707Z","steps":["trace[699759643] 'process raft request' (duration: 122.470759ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:20.839928Z","caller":"traceutil/trace.go:171","msg":"trace[2105079080] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"121.305492ms","start":"2026-10-16T00:11:20.7186Z","end":"2026-10-16T00:11:20.839906Z","steps":["trace[2105079080] 'process raft request' (duration: 121.016656ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:21.339724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.30262ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-16T00:11:21.339846Z","caller":"traceutil/trace.go:171","msg":"trace[340176439] linearizableReadLoop","detail":"{readStateIndex:1217; appliedIndex:1216; }","duration":"203.853865ms","start":"2026-10-16T00:11:21.135974Z","end":"2026-10-16T00:11:21.339828Z","steps":["trace[340176439] 'read index received' (duration: 16.355011ms)","trace[340176439] 'applied index is now lower than readState.Index' (duration: 187.497324ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:11:21.339924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.946538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:11:21.339958Z","caller":"traceutil/trace.go:171","msg":"trace[1749411415] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1170; }","duration":"204.028339ms","start":"2026-10-16T00:11:21.135917Z","end":"2026-10-16T00:11:21.339945Z","steps":["trace[1749411415] 'agreement among raft nodes before linearized reading' (duration: 203.956598ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:34.538561Z","caller":"traceutil/trace.go:171","msg":"trace[1476845785] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"361.091442ms","start":"2026-10-16T00:11:34.177436Z","end":"2026-10-16T00:11:34.538527Z","steps":["trace[1476845785] 'process raft request' (duration: 360.902588ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:34.538773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:34.177409Z","time spent":"361.248666ms","remote":"127.0.0.1:45418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:11:34.539831Z","caller":"traceutil/trace.go:171","msg":"trace[139287100] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1320; }","duration":"267.074279ms","start":"2026-10-16T00:11:34.272715Z","end":"2026-10-16T00:11:34.53979Z","steps":["trace[139287100] 'read index received' (duration: 267.046348ms)","trace[139287100] 'applied index is now lower than readState.Index' (duration: 25.461µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:11:34.540072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.352536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-10-16T00:11:34.54014Z","caller":"traceutil/trace.go:171","msg":"trace[1748534447] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1271; }","duration":"267.426998ms","start":"2026-10-16T00:11:34.272695Z","end":"2026-10-16T00:11:34.540122Z","steps":["trace[1748534447] 'agreement among raft nodes before linearized reading' (duration: 267.223713ms)"],"step_count":1}