{"level":"warn","ts":"2026-01-30T09:19:07.58035Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T09:19:07.580591Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.140: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.140:2380","--initial-cluster=instance=https://162.253.55.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.140: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-01-30T09:19:07.581029Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T09:19:07.58104Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-01-30T09:19:07.581059Z","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-01-30T09:19:07.583599Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"]} {"level":"info","ts":"2026-01-30T09:19:07.583674Z","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":2,"max-cpu-available":2,"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.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.140: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-01-30T09:19:07.59151Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.654602ms"} {"level":"info","ts":"2026-01-30T09:19:07.600416Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf809824ab95ac81","cluster-id":"6f89f5fbef18dc61"} {"level":"info","ts":"2026-01-30T09:19:07.600503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=()"} {"level":"info","ts":"2026-01-30T09:19:07.600521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 0"} {"level":"info","ts":"2026-01-30T09:19:07.60053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf809824ab95ac81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-30T09:19:07.600536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 1"} {"level":"info","ts":"2026-01-30T09:19:07.600563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"warn","ts":"2026-01-30T09:19:07.608904Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-30T09:19:07.631972Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-30T09:19:07.639721Z","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-01-30T09:19:07.644022Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf809824ab95ac81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-30T09:19:07.645733Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf809824ab95ac81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-30T09:19:07.646258Z","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-01-30T09:19:07.646585Z","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-01-30T09:19:07.646681Z","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-01-30T09:19:07.647414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"info","ts":"2026-01-30T09:19:07.647612Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","added-peer-id":"cf809824ab95ac81","added-peer-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-01-30T09:19:07.648797Z","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-01-30T09:19:07.650207Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-01-30T09:19:07.650875Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-01-30T09:19:07.659581Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf809824ab95ac81","initial-advertise-peer-urls":["https://162.253.55.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-30T09:19:07.659649Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-30T09:19:08.603299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 is starting a new election at term 1"} {"level":"info","ts":"2026-01-30T09:19:08.60339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-30T09:19:08.603425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgPreVoteResp from cf809824ab95ac81 at term 1"} {"level":"info","ts":"2026-01-30T09:19:08.603447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became candidate at term 2"} {"level":"info","ts":"2026-01-30T09:19:08.603484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgVoteResp from cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-01-30T09:19:08.603506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became leader at term 2"} {"level":"info","ts":"2026-01-30T09:19:08.603523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf809824ab95ac81 elected leader cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-01-30T09:19:08.608507Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T09:19:08.614606Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T09:19:08.614677Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T09:19:08.614705Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T09:19:08.614752Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf809824ab95ac81","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.140:2379]}","request-path":"/0/members/cf809824ab95ac81/attributes","cluster-id":"6f89f5fbef18dc61","publish-timeout":"7s"} {"level":"info","ts":"2026-01-30T09:19:08.614778Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T09:19:08.61513Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T09:19:08.61643Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-30T09:19:08.61771Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.140:2379"} {"level":"info","ts":"2026-01-30T09:19:08.621945Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-30T09:19:08.621974Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-30T09:19:18.96955Z","caller":"traceutil/trace.go:171","msg":"trace[199326454] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:316; }","duration":"298.97918ms","start":"2026-01-30T09:19:18.670473Z","end":"2026-01-30T09:19:18.969452Z","steps":["trace[199326454] 'read index received' (duration: 298.820209ms)","trace[199326454] 'applied index is now lower than readState.Index' (duration: 157.871µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:19:18.969687Z","caller":"traceutil/trace.go:171","msg":"trace[1128148912] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"344.316768ms","start":"2026-01-30T09:19:18.62536Z","end":"2026-01-30T09:19:18.969676Z","steps":["trace[1128148912] 'process raft request' (duration: 343.980105ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:18.969952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.994197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4597"} {"level":"info","ts":"2026-01-30T09:19:18.97005Z","caller":"traceutil/trace.go:171","msg":"trace[991794302] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:307; }","duration":"294.109618ms","start":"2026-01-30T09:19:18.675921Z","end":"2026-01-30T09:19:18.970031Z","steps":["trace[991794302] 'agreement among raft nodes before linearized reading' (duration: 293.946826ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:18.970186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:18.625334Z","time spent":"344.377769ms","remote":"127.0.0.1:58306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T09:19:18.970269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.788897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-01-30T09:19:18.97031Z","caller":"traceutil/trace.go:171","msg":"trace[1066029703] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:307; }","duration":"299.838947ms","start":"2026-01-30T09:19:18.670456Z","end":"2026-01-30T09:19:18.970295Z","steps":["trace[1066029703] 'agreement among raft nodes before linearized reading' (duration: 299.778107ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:18.970535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.998268ms","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-01-30T09:19:18.970561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.31019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-01-30T09:19:18.970594Z","caller":"traceutil/trace.go:171","msg":"trace[899091345] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:307; }","duration":"294.351141ms","start":"2026-01-30T09:19:18.676234Z","end":"2026-01-30T09:19:18.970585Z","steps":["trace[899091345] 'agreement among raft nodes before linearized reading' (duration: 294.29283ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:18.970743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.561402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-01-30T09:19:18.970772Z","caller":"traceutil/trace.go:171","msg":"trace[1796380771] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:307; }","duration":"294.615202ms","start":"2026-01-30T09:19:18.676148Z","end":"2026-01-30T09:19:18.970764Z","steps":["trace[1796380771] 'agreement among raft nodes before linearized reading' (duration: 294.565582ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:18.970894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.927635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5270"} {"level":"info","ts":"2026-01-30T09:19:18.970918Z","caller":"traceutil/trace.go:171","msg":"trace[251630569] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:307; }","duration":"294.956765ms","start":"2026-01-30T09:19:18.675954Z","end":"2026-01-30T09:19:18.970911Z","steps":["trace[251630569] 'agreement among raft nodes before linearized reading' (duration: 294.913855ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:18.971033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.077746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-01-30T09:19:18.971057Z","caller":"traceutil/trace.go:171","msg":"trace[1238308271] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:307; }","duration":"295.108387ms","start":"2026-01-30T09:19:18.675942Z","end":"2026-01-30T09:19:18.97105Z","steps":["trace[1238308271] 'agreement among raft nodes before linearized reading' (duration: 295.067816ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:19:18.970573Z","caller":"traceutil/trace.go:171","msg":"trace[331055262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:307; }","duration":"259.055408ms","start":"2026-01-30T09:19:18.711506Z","end":"2026-01-30T09:19:18.970562Z","steps":["trace[331055262] 'agreement among raft nodes before linearized reading' (duration: 258.981598ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:18.97211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.763313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-01-30T09:19:18.972156Z","caller":"traceutil/trace.go:171","msg":"trace[151145693] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:307; }","duration":"100.825984ms","start":"2026-01-30T09:19:18.871311Z","end":"2026-01-30T09:19:18.972137Z","steps":["trace[151145693] 'agreement among raft nodes before linearized reading' (duration: 100.758033ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:18.972301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.143654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-01-30T09:19:18.972335Z","caller":"traceutil/trace.go:171","msg":"trace[833100810] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:307; }","duration":"151.202425ms","start":"2026-01-30T09:19:18.821125Z","end":"2026-01-30T09:19:18.972327Z","steps":["trace[833100810] 'agreement among raft nodes before linearized reading' (duration: 151.149354ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:18.972454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.065632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-01-30T09:19:18.972486Z","caller":"traceutil/trace.go:171","msg":"trace[1116798645] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:307; }","duration":"201.133802ms","start":"2026-01-30T09:19:18.771344Z","end":"2026-01-30T09:19:18.972478Z","steps":["trace[1116798645] 'agreement among raft nodes before linearized reading' (duration: 201.086181ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:18.972598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.854697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-01-30T09:19:18.972631Z","caller":"traceutil/trace.go:171","msg":"trace[1233835479] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:307; }","duration":"251.898527ms","start":"2026-01-30T09:19:18.720724Z","end":"2026-01-30T09:19:18.972623Z","steps":["trace[1233835479] 'agreement among raft nodes before linearized reading' (duration: 251.850737ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:18.97275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.102127ms","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-01-30T09:19:18.972774Z","caller":"traceutil/trace.go:171","msg":"trace[594746950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:307; }","duration":"260.145997ms","start":"2026-01-30T09:19:18.712621Z","end":"2026-01-30T09:19:18.972767Z","steps":["trace[594746950] 'agreement among raft nodes before linearized reading' (duration: 260.104287ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:19:29.186857Z","caller":"traceutil/trace.go:171","msg":"trace[1277417449] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"120.675391ms","start":"2026-01-30T09:19:29.066158Z","end":"2026-01-30T09:19:29.186834Z","steps":["trace[1277417449] 'read index received' (duration: 120.519789ms)","trace[1277417449] 'applied index is now lower than readState.Index' (duration: 154.612µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:19:29.186975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.806501ms","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-01-30T09:19:29.187007Z","caller":"traceutil/trace.go:171","msg":"trace[2145119530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:414; }","duration":"120.863912ms","start":"2026-01-30T09:19:29.066133Z","end":"2026-01-30T09:19:29.186997Z","steps":["trace[2145119530] 'agreement among raft nodes before linearized reading' (duration: 120.794631ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:19:29.187029Z","caller":"traceutil/trace.go:171","msg":"trace[169500300] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"121.87529ms","start":"2026-01-30T09:19:29.065143Z","end":"2026-01-30T09:19:29.187018Z","steps":["trace[169500300] 'process raft request' (duration: 121.565398ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:19:29.299524Z","caller":"traceutil/trace.go:171","msg":"trace[1756355246] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"106.902131ms","start":"2026-01-30T09:19:29.192601Z","end":"2026-01-30T09:19:29.299503Z","steps":["trace[1756355246] 'process raft request' (duration: 106.78217ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:37.8299Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388031317955589,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-30T09:19:37.869545Z","caller":"traceutil/trace.go:171","msg":"trace[1348635993] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"539.958963ms","start":"2026-01-30T09:19:37.329564Z","end":"2026-01-30T09:19:37.869523Z","steps":["trace[1348635993] 'read index received' (duration: 539.742242ms)","trace[1348635993] 'applied index is now lower than readState.Index' (duration: 215.371µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:19:37.869707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.118275ms","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-01-30T09:19:37.869742Z","caller":"traceutil/trace.go:171","msg":"trace[1000296473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"540.200225ms","start":"2026-01-30T09:19:37.32953Z","end":"2026-01-30T09:19:37.86973Z","steps":["trace[1000296473] 'agreement among raft nodes before linearized reading' (duration: 540.103354ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:37.869776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:37.329514Z","time spent":"540.254235ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-30T09:19:37.870074Z","caller":"traceutil/trace.go:171","msg":"trace[327669924] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"653.189048ms","start":"2026-01-30T09:19:37.216875Z","end":"2026-01-30T09:19:37.870064Z","steps":["trace[327669924] 'process raft request' (duration: 652.508292ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:37.870152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:37.216854Z","time spent":"653.243918ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T09:19:39.52737Z","caller":"traceutil/trace.go:171","msg":"trace[984965959] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"284.339892ms","start":"2026-01-30T09:19:39.24301Z","end":"2026-01-30T09:19:39.52735Z","steps":["trace[984965959] 'process raft request' (duration: 284.221051ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:19:40.09971Z","caller":"traceutil/trace.go:171","msg":"trace[362362701] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"562.288752ms","start":"2026-01-30T09:19:39.537407Z","end":"2026-01-30T09:19:40.099695Z","steps":["trace[362362701] 'process raft request' (duration: 561.305294ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:40.09979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:39.53739Z","time spent":"562.364192ms","remote":"127.0.0.1:58170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12733,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T09:19:40.099972Z","caller":"traceutil/trace.go:171","msg":"trace[1381051512] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:488; }","duration":"224.869349ms","start":"2026-01-30T09:19:39.875093Z","end":"2026-01-30T09:19:40.099963Z","steps":["trace[1381051512] 'read index received' (duration: 223.71344ms)","trace[1381051512] 'applied index is now lower than readState.Index' (duration: 1.155299ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:19:40.100195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.931934ms","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-01-30T09:19:40.100217Z","caller":"traceutil/trace.go:171","msg":"trace[1035089602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"177.986864ms","start":"2026-01-30T09:19:39.922225Z","end":"2026-01-30T09:19:40.100211Z","steps":["trace[1035089602] 'agreement among raft nodes before linearized reading' (duration: 177.893663ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:19:40.100303Z","caller":"traceutil/trace.go:171","msg":"trace[1352192316] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"562.655664ms","start":"2026-01-30T09:19:39.537641Z","end":"2026-01-30T09:19:40.100297Z","steps":["trace[1352192316] 'process raft request' (duration: 562.255551ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:40.100332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:39.537627Z","time spent":"562.688774ms","remote":"127.0.0.1:58172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1745,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T09:19:40.100406Z","caller":"traceutil/trace.go:171","msg":"trace[288728845] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"562.714525ms","start":"2026-01-30T09:19:39.537685Z","end":"2026-01-30T09:19:40.1004Z","steps":["trace[288728845] 'process raft request' (duration: 562.243602ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:40.10043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:39.537683Z","time spent":"562.735755ms","remote":"127.0.0.1:58254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-30T09:19:40.100529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.435043ms","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-01-30T09:19:40.100545Z","caller":"traceutil/trace.go:171","msg":"trace[1054907591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"225.484713ms","start":"2026-01-30T09:19:39.875055Z","end":"2026-01-30T09:19:40.10054Z","steps":["trace[1054907591] 'agreement among raft nodes before linearized reading' (duration: 225.435903ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:19:44.397328Z","caller":"traceutil/trace.go:171","msg":"trace[1089454913] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"266.395078ms","start":"2026-01-30T09:19:44.130924Z","end":"2026-01-30T09:19:44.397319Z","steps":["trace[1089454913] 'process raft request' (duration: 266.041731ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:19:44.397124Z","caller":"traceutil/trace.go:171","msg":"trace[1189047657] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"265.181579ms","start":"2026-01-30T09:19:44.131927Z","end":"2026-01-30T09:19:44.397108Z","steps":["trace[1189047657] 'read index received' (duration: 265.148308ms)","trace[1189047657] 'applied index is now lower than readState.Index' (duration: 32.551µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:19:44.397593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.651892ms","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-01-30T09:19:44.397802Z","caller":"traceutil/trace.go:171","msg":"trace[2123812458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:507; }","duration":"265.846051ms","start":"2026-01-30T09:19:44.131923Z","end":"2026-01-30T09:19:44.397769Z","steps":["trace[2123812458] 'agreement among raft nodes before linearized reading' (duration: 265.578718ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:19:44.695019Z","caller":"traceutil/trace.go:171","msg":"trace[434561553] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"292.659217ms","start":"2026-01-30T09:19:44.402337Z","end":"2026-01-30T09:19:44.694997Z","steps":["trace[434561553] 'process raft request' (duration: 292.614205ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:19:44.695219Z","caller":"traceutil/trace.go:171","msg":"trace[1225191288] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"354.297391ms","start":"2026-01-30T09:19:44.340894Z","end":"2026-01-30T09:19:44.695192Z","steps":["trace[1225191288] 'process raft request' (duration: 271.876005ms)","trace[1225191288] 'compare' (duration: 82.058318ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:19:44.69534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:44.340885Z","time spent":"354.406226ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T09:19:44.695407Z","caller":"traceutil/trace.go:171","msg":"trace[1372555574] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"297.667651ms","start":"2026-01-30T09:19:44.397734Z","end":"2026-01-30T09:19:44.695402Z","steps":["trace[1372555574] 'read index received' (duration: 215.047396ms)","trace[1372555574] 'applied index is now lower than readState.Index' (duration: 82.619755ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:19:44.695472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.816179ms","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-01-30T09:19:44.695486Z","caller":"traceutil/trace.go:171","msg":"trace[946985882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"562.84365ms","start":"2026-01-30T09:19:44.132638Z","end":"2026-01-30T09:19:44.695481Z","steps":["trace[946985882] 'agreement among raft nodes before linearized reading' (duration: 562.779467ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:44.695498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:44.132632Z","time spent":"562.863702ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-30T09:19:44.695552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.023323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-58f27\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-01-30T09:19:44.695562Z","caller":"traceutil/trace.go:171","msg":"trace[383749659] range","detail":"{range_begin:/registry/pods/kube-system/cilium-58f27; range_end:; response_count:1; response_revision:509; }","duration":"357.057255ms","start":"2026-01-30T09:19:44.338502Z","end":"2026-01-30T09:19:44.695559Z","steps":["trace[383749659] 'agreement among raft nodes before linearized reading' (duration: 357.022383ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:44.695573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:44.338475Z","time spent":"357.095497ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-58f27\" "} {"level":"warn","ts":"2026-01-30T09:19:44.695673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.190671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-30T09:19:44.695704Z","caller":"traceutil/trace.go:171","msg":"trace[1698037473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:509; }","duration":"451.254935ms","start":"2026-01-30T09:19:44.244444Z","end":"2026-01-30T09:19:44.695699Z","steps":["trace[1698037473] 'agreement among raft nodes before linearized reading' (duration: 451.204732ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:44.695715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:44.244426Z","time spent":"451.286106ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-30T09:19:45.557304Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388031317955675,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T09:19:45.765591Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.000657862s","expected-duration":"1s"} {"level":"info","ts":"2026-01-30T09:19:45.76591Z","caller":"traceutil/trace.go:171","msg":"trace[156910558] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:527; }","duration":"709.31069ms","start":"2026-01-30T09:19:45.05657Z","end":"2026-01-30T09:19:45.765881Z","steps":["trace[156910558] 'read index received' (duration: 709.10868ms)","trace[156910558] 'applied index is now lower than readState.Index' (duration: 201.42µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:19:45.765978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.395183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T09:19:45.766001Z","caller":"traceutil/trace.go:171","msg":"trace[1602079477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"709.457406ms","start":"2026-01-30T09:19:45.056537Z","end":"2026-01-30T09:19:45.765994Z","steps":["trace[1602079477] 'agreement among raft nodes before linearized reading' (duration: 709.399383ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:45.766027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:45.056521Z","time spent":"709.501228ms","remote":"127.0.0.1:58066","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-01-30T09:19:45.766208Z","caller":"traceutil/trace.go:171","msg":"trace[1496852405] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.060195732s","start":"2026-01-30T09:19:44.706005Z","end":"2026-01-30T09:19:45.7662Z","steps":["trace[1496852405] 'process raft request' (duration: 1.059722679s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:45.766266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:44.705981Z","time spent":"1.060249484s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T09:19:45.76641Z","caller":"traceutil/trace.go:171","msg":"trace[2018819277] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.057105962s","start":"2026-01-30T09:19:44.709297Z","end":"2026-01-30T09:19:45.766403Z","steps":["trace[2018819277] 'process raft request' (duration: 1.056518283s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:45.766448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:44.709289Z","time spent":"1.057141813s","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T09:19:47.040447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.825919ms","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-01-30T09:19:47.04052Z","caller":"traceutil/trace.go:171","msg":"trace[953725375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"638.940284ms","start":"2026-01-30T09:19:46.40156Z","end":"2026-01-30T09:19:47.0405Z","steps":["trace[953725375] 'range keys from in-memory index tree' (duration: 638.602727ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:47.040561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:46.401539Z","time spent":"639.013338ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-30T09:19:47.040622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.403596ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T09:19:47.040702Z","caller":"traceutil/trace.go:171","msg":"trace[343164489] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:512; }","duration":"320.505431ms","start":"2026-01-30T09:19:46.720183Z","end":"2026-01-30T09:19:47.040689Z","steps":["trace[343164489] 'range keys from in-memory index tree' (duration: 320.391385ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:47.21241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388031317955681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T09:19:47.712518Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388031317955681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T09:19:48.212886Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388031317955681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T09:19:48.681302Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.018372031s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-30T09:19:48.921768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.190281ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-30T09:19:48.923519Z","caller":"traceutil/trace.go:171","msg":"trace[492586456] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:529; }","duration":"2.212613596s","start":"2026-01-30T09:19:46.710877Z","end":"2026-01-30T09:19:48.923491Z","steps":["trace[492586456] 'read index received' (duration: 1.986498727s)","trace[492586456] 'applied index is now lower than readState.Index' (duration: 226.113009ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:19:48.923624Z","caller":"traceutil/trace.go:171","msg":"trace[248533567] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.879251826s","start":"2026-01-30T09:19:47.044362Z","end":"2026-01-30T09:19:48.923614Z","steps":["trace[248533567] 'process raft request' (duration: 1.877464953s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:48.923722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:47.044348Z","time spent":"1.879306188s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T09:19:48.932232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.877133301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-30T09:19:48.932278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.221398034s","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-01-30T09:19:48.93229Z","caller":"traceutil/trace.go:171","msg":"trace[1466575470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"1.877215765s","start":"2026-01-30T09:19:47.055056Z","end":"2026-01-30T09:19:48.932272Z","steps":["trace[1466575470] 'agreement among raft nodes before linearized reading' (duration: 1.86890458s)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:19:48.932308Z","caller":"traceutil/trace.go:171","msg":"trace[1365046421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"2.221454326s","start":"2026-01-30T09:19:46.710846Z","end":"2026-01-30T09:19:48.9323Z","steps":["trace[1365046421] 'agreement among raft nodes before linearized reading' (duration: 2.213042206s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:48.932323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:47.055048Z","time spent":"1.877268387s","remote":"127.0.0.1:58066","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-01-30T09:19:48.932331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:46.710833Z","time spent":"2.221493098s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-30T09:19:48.932548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.189027934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-30T09:19:48.932568Z","caller":"traceutil/trace.go:171","msg":"trace[1344101750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"2.189061476s","start":"2026-01-30T09:19:46.7435Z","end":"2026-01-30T09:19:48.932562Z","steps":["trace[1344101750] 'agreement among raft nodes before linearized reading' (duration: 2.180408964s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:48.932569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.160810073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-01-30T09:19:48.932585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:46.743491Z","time spent":"2.189090007s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-30T09:19:48.93259Z","caller":"traceutil/trace.go:171","msg":"trace[1201996728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"1.160855375s","start":"2026-01-30T09:19:47.771728Z","end":"2026-01-30T09:19:48.932583Z","steps":["trace[1201996728] 'agreement among raft nodes before linearized reading' (duration: 1.152225434s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:48.93261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:47.771714Z","time spent":"1.160892407s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-30T09:19:51.119613Z","caller":"traceutil/trace.go:171","msg":"trace[567968898] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"456.972345ms","start":"2026-01-30T09:19:50.662584Z","end":"2026-01-30T09:19:51.119556Z","steps":["trace[567968898] 'process raft request' (duration: 456.768426ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:51.119805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:19:50.662565Z","time spent":"457.138543ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T09:19:51.12045Z","caller":"traceutil/trace.go:171","msg":"trace[1297048597] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:543; }","duration":"193.760792ms","start":"2026-01-30T09:19:50.926675Z","end":"2026-01-30T09:19:51.120436Z","steps":["trace[1297048597] 'read index received' (duration: 192.559418ms)","trace[1297048597] 'applied index is now lower than readState.Index' (duration: 1.200263ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:19:51.120695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.383791ms","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-01-30T09:19:51.121086Z","caller":"traceutil/trace.go:171","msg":"trace[2051867512] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:525; }","duration":"168.791149ms","start":"2026-01-30T09:19:50.952283Z","end":"2026-01-30T09:19:51.121074Z","steps":["trace[2051867512] 'agreement among raft nodes before linearized reading' (duration: 168.287317ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:51.121147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.108678ms","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-01-30T09:19:51.121178Z","caller":"traceutil/trace.go:171","msg":"trace[191603507] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"194.536526ms","start":"2026-01-30T09:19:50.926633Z","end":"2026-01-30T09:19:51.12117Z","steps":["trace[191603507] 'agreement among raft nodes before linearized reading' (duration: 193.907668ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:51.12281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.044419ms","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-01-30T09:19:51.123204Z","caller":"traceutil/trace.go:171","msg":"trace[1263158717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"167.607544ms","start":"2026-01-30T09:19:50.955583Z","end":"2026-01-30T09:19:51.123191Z","steps":["trace[1263158717] 'agreement among raft nodes before linearized reading' (duration: 165.971071ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:51.1256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.943628ms","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-01-30T09:19:51.125621Z","caller":"traceutil/trace.go:171","msg":"trace[153173450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:525; }","duration":"169.97399ms","start":"2026-01-30T09:19:50.955642Z","end":"2026-01-30T09:19:51.125616Z","steps":["trace[153173450] 'agreement among raft nodes before linearized reading' (duration: 169.009017ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:19:53.760449Z","caller":"traceutil/trace.go:171","msg":"trace[2073811525] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"221.489133ms","start":"2026-01-30T09:19:53.538942Z","end":"2026-01-30T09:19:53.760431Z","steps":["trace[2073811525] 'process raft request' (duration: 219.327228ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:19:53.764261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.915901ms","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-01-30T09:19:53.764285Z","caller":"traceutil/trace.go:171","msg":"trace[1749452991] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:585; }","duration":"189.994294ms","start":"2026-01-30T09:19:53.574285Z","end":"2026-01-30T09:19:53.76428Z","steps":["trace[1749452991] 'agreement among raft nodes before linearized reading' (duration: 189.871829ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:19:53.764119Z","caller":"traceutil/trace.go:171","msg":"trace[483939118] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"189.755543ms","start":"2026-01-30T09:19:53.574343Z","end":"2026-01-30T09:19:53.764098Z","steps":["trace[483939118] 'read index received' (duration: 184.063254ms)","trace[483939118] 'applied index is now lower than readState.Index' (duration: 1.845801ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:20:15.581912Z","caller":"traceutil/trace.go:171","msg":"trace[1319726087] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"105.900355ms","start":"2026-01-30T09:20:15.475996Z","end":"2026-01-30T09:20:15.581896Z","steps":["trace[1319726087] 'process raft request' (duration: 105.792601ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:20:22.221988Z","caller":"traceutil/trace.go:171","msg":"trace[1738319784] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"124.530563ms","start":"2026-01-30T09:20:22.097435Z","end":"2026-01-30T09:20:22.221966Z","steps":["trace[1738319784] 'process raft request' (duration: 124.392358ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:20:35.882843Z","caller":"traceutil/trace.go:171","msg":"trace[1553626886] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"131.362919ms","start":"2026-01-30T09:20:35.751457Z","end":"2026-01-30T09:20:35.88282Z","steps":["trace[1553626886] 'process raft request' (duration: 120.219247ms)","trace[1553626886] 'compare' (duration: 10.998328ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:20:35.88305Z","caller":"traceutil/trace.go:171","msg":"trace[1419267184] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:826; }","duration":"102.391998ms","start":"2026-01-30T09:20:35.780649Z","end":"2026-01-30T09:20:35.883041Z","steps":["trace[1419267184] 'read index received' (duration: 91.095281ms)","trace[1419267184] 'applied index is now lower than readState.Index' (duration: 11.295756ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:20:35.883201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.534401ms","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-01-30T09:20:35.883205Z","caller":"traceutil/trace.go:171","msg":"trace[514976632] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"131.080391ms","start":"2026-01-30T09:20:35.752098Z","end":"2026-01-30T09:20:35.883178Z","steps":["trace[514976632] 'process raft request' (duration: 130.68945ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:20:35.883232Z","caller":"traceutil/trace.go:171","msg":"trace[1968601374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:800; }","duration":"102.602464ms","start":"2026-01-30T09:20:35.78062Z","end":"2026-01-30T09:20:35.883223Z","steps":["trace[1968601374] 'agreement among raft nodes before linearized reading' (duration: 102.46653ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:20:48.347631Z","caller":"traceutil/trace.go:171","msg":"trace[1381715124] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"156.943697ms","start":"2026-01-30T09:20:48.190665Z","end":"2026-01-30T09:20:48.347609Z","steps":["trace[1381715124] 'process raft request' (duration: 156.816734ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:20:48.348035Z","caller":"traceutil/trace.go:171","msg":"trace[2095080894] linearizableReadLoop","detail":"{readStateIndex:865; appliedIndex:865; }","duration":"107.132598ms","start":"2026-01-30T09:20:48.240892Z","end":"2026-01-30T09:20:48.348025Z","steps":["trace[2095080894] 'read index received' (duration: 107.128728ms)","trace[2095080894] 'applied index is now lower than readState.Index' (duration: 3.26µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:20:48.348807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.899777ms","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-01-30T09:20:48.348842Z","caller":"traceutil/trace.go:171","msg":"trace[399999216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:834; }","duration":"107.945328ms","start":"2026-01-30T09:20:48.240887Z","end":"2026-01-30T09:20:48.348832Z","steps":["trace[399999216] 'agreement among raft nodes before linearized reading' (duration: 107.849785ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:21:06.735036Z","caller":"traceutil/trace.go:171","msg":"trace[715816797] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:951; }","duration":"222.787044ms","start":"2026-01-30T09:21:06.512229Z","end":"2026-01-30T09:21:06.735016Z","steps":["trace[715816797] 'read index received' (duration: 222.640511ms)","trace[715816797] 'applied index is now lower than readState.Index' (duration: 145.833µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:21:06.735535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.514011ms","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-01-30T09:21:06.735719Z","caller":"traceutil/trace.go:171","msg":"trace[1716594181] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:917; }","duration":"217.720555ms","start":"2026-01-30T09:21:06.517985Z","end":"2026-01-30T09:21:06.735705Z","steps":["trace[1716594181] 'agreement among raft nodes before linearized reading' (duration: 217.432529ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:21:06.736051Z","caller":"traceutil/trace.go:171","msg":"trace[2075788540] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"301.747723ms","start":"2026-01-30T09:21:06.434287Z","end":"2026-01-30T09:21:06.736035Z","steps":["trace[2075788540] 'process raft request' (duration: 300.627049ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:21:06.736163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:21:06.434278Z","time spent":"301.825344ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T09:21:06.73641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.170964ms","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-01-30T09:21:06.736578Z","caller":"traceutil/trace.go:171","msg":"trace[849045433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:917; }","duration":"224.310027ms","start":"2026-01-30T09:21:06.512225Z","end":"2026-01-30T09:21:06.736535Z","steps":["trace[849045433] 'agreement among raft nodes before linearized reading' (duration: 223.843067ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:21:14.333912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.396392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-30T09:21:14.333974Z","caller":"traceutil/trace.go:171","msg":"trace[227857641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:944; }","duration":"115.512554ms","start":"2026-01-30T09:21:14.218448Z","end":"2026-01-30T09:21:14.333961Z","steps":["trace[227857641] 'range keys from in-memory index tree' (duration: 115.225788ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:21:15.372189Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388031317956719,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-30T09:21:15.416435Z","caller":"traceutil/trace.go:171","msg":"trace[1475550528] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"550.623776ms","start":"2026-01-30T09:21:14.865787Z","end":"2026-01-30T09:21:15.41641Z","steps":["trace[1475550528] 'process raft request' (duration: 550.346991ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:21:15.41662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:21:14.865766Z","time spent":"550.739819ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T09:21:15.428946Z","caller":"traceutil/trace.go:171","msg":"trace[1960959415] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:983; }","duration":"561.615367ms","start":"2026-01-30T09:21:14.867312Z","end":"2026-01-30T09:21:15.428927Z","steps":["trace[1960959415] 'read index received' (duration: 548.772909ms)","trace[1960959415] 'applied index is now lower than readState.Index' (duration: 12.841768ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:21:15.429033Z","caller":"traceutil/trace.go:171","msg":"trace[593836296] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"231.168858ms","start":"2026-01-30T09:21:15.197858Z","end":"2026-01-30T09:21:15.429027Z","steps":["trace[593836296] 'process raft request' (duration: 225.220279ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:21:15.429197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.871463ms","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-01-30T09:21:15.429214Z","caller":"traceutil/trace.go:171","msg":"trace[1349748789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:948; }","duration":"561.928494ms","start":"2026-01-30T09:21:14.86728Z","end":"2026-01-30T09:21:15.429209Z","steps":["trace[1349748789] 'agreement among raft nodes before linearized reading' (duration: 561.841242ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:21:15.429229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:21:14.867245Z","time spent":"561.979825ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-30T09:21:15.429385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.428747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T09:21:15.429397Z","caller":"traceutil/trace.go:171","msg":"trace[533889552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"372.479547ms","start":"2026-01-30T09:21:15.056914Z","end":"2026-01-30T09:21:15.429393Z","steps":["trace[533889552] 'agreement among raft nodes before linearized reading' (duration: 372.457286ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:21:15.429419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:21:15.056894Z","time spent":"372.521878ms","remote":"127.0.0.1:58066","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-01-30T09:21:49.663967Z","caller":"traceutil/trace.go:171","msg":"trace[42883649] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1090; }","duration":"109.885737ms","start":"2026-01-30T09:21:49.55379Z","end":"2026-01-30T09:21:49.663676Z","steps":["trace[42883649] 'read index received' (duration: 109.701884ms)","trace[42883649] 'applied index is now lower than readState.Index' (duration: 183.193µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:21:49.663911Z","caller":"traceutil/trace.go:171","msg":"trace[161556048] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"113.189961ms","start":"2026-01-30T09:21:49.550703Z","end":"2026-01-30T09:21:49.663893Z","steps":["trace[161556048] 'process raft request' (duration: 112.855795ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:21:49.664455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.614279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-58f27\" ","response":"range_response_count:1 size:18062"} {"level":"info","ts":"2026-01-30T09:21:49.664496Z","caller":"traceutil/trace.go:171","msg":"trace[1893588452] range","detail":"{range_begin:/registry/pods/kube-system/cilium-58f27; range_end:; response_count:1; response_revision:1047; }","duration":"110.725ms","start":"2026-01-30T09:21:49.553761Z","end":"2026-01-30T09:21:49.664486Z","steps":["trace[1893588452] 'agreement among raft nodes before linearized reading' (duration: 110.421585ms)"],"step_count":1}