{"level":"warn","ts":"2026-02-05T00:46:02.043907Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T00:46:02.044378Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.49: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.49:2380","--initial-cluster=instance=https://162.253.55.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.49: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-02-05T00:46:02.044512Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T00:46:02.044545Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-05T00:46:02.044584Z","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-02-05T00:46:02.045379Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"]} {"level":"info","ts":"2026-02-05T00:46:02.045538Z","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.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.49: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-02-05T00:46:02.051312Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.154425ms"} {"level":"info","ts":"2026-02-05T00:46:02.082312Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-02-05T00:46:02.082424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T00:46:02.082458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-02-05T00:46:02.082495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a9ba61e55fef5be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T00:46:02.082518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-02-05T00:46:02.082591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-02-05T00:46:02.099738Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T00:46:02.104424Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T00:46:02.110323Z","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-02-05T00:46:02.11555Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a9ba61e55fef5be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T00:46:02.117157Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a9ba61e55fef5be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T00:46:02.117471Z","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-02-05T00:46:02.123211Z","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-02-05T00:46:02.123232Z","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-02-05T00:46:02.123905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-02-05T00:46:02.123964Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","added-peer-id":"6a9ba61e55fef5be","added-peer-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-05T00:46:02.142061Z","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-02-05T00:46:02.14223Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a9ba61e55fef5be","initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T00:46:02.14225Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T00:46:02.142333Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-05T00:46:02.142341Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-05T00:46:02.283116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T00:46:02.28316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T00:46:02.283204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-02-05T00:46:02.283221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-02-05T00:46:02.283229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-05T00:46:02.283238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-02-05T00:46:02.283248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-05T00:46:02.284748Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a9ba61e55fef5be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.49:2379]}","request-path":"/0/members/6a9ba61e55fef5be/attributes","cluster-id":"9612db6e19ac6ce7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T00:46:02.284878Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T00:46:02.284944Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T00:46:02.285213Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T00:46:02.285409Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T00:46:02.285491Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T00:46:02.287899Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"info","ts":"2026-02-05T00:46:02.289361Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T00:46:02.292282Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T00:46:02.292378Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T00:46:02.2924Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T00:46:25.349131Z","caller":"traceutil/trace.go:171","msg":"trace[1863485221] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"126.632727ms","start":"2026-02-05T00:46:25.222476Z","end":"2026-02-05T00:46:25.349109Z","steps":["trace[1863485221] 'process raft request' (duration: 126.511875ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:46:32.562253Z","caller":"traceutil/trace.go:171","msg":"trace[17766833] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"157.413834ms","start":"2026-02-05T00:46:32.404824Z","end":"2026-02-05T00:46:32.562238Z","steps":["trace[17766833] 'process raft request' (duration: 157.281181ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:46:53.2101Z","caller":"traceutil/trace.go:171","msg":"trace[1378671491] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"418.794053ms","start":"2026-02-05T00:46:52.791282Z","end":"2026-02-05T00:46:53.210076Z","steps":["trace[1378671491] 'process raft request' (duration: 418.69052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:53.210497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:52.791262Z","time spent":"418.903175ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:46:53.527831Z","caller":"traceutil/trace.go:171","msg":"trace[1083884288] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:683; }","duration":"519.793944ms","start":"2026-02-05T00:46:53.008022Z","end":"2026-02-05T00:46:53.527816Z","steps":["trace[1083884288] 'read index received' (duration: 201.933008ms)","trace[1083884288] 'applied index is now lower than readState.Index' (duration: 317.860436ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:46:53.527913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.882855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:46:53.527939Z","caller":"traceutil/trace.go:171","msg":"trace[586203271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"519.946867ms","start":"2026-02-05T00:46:53.007987Z","end":"2026-02-05T00:46:53.527934Z","steps":["trace[586203271] 'agreement among raft nodes before linearized reading' (duration: 519.888776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:53.527955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:53.007959Z","time spent":"519.992488ms","remote":"127.0.0.1:42946","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-02-05T00:46:53.528112Z","caller":"traceutil/trace.go:171","msg":"trace[307154701] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"735.550355ms","start":"2026-02-05T00:46:52.792554Z","end":"2026-02-05T00:46:53.528104Z","steps":["trace[307154701] 'process raft request' (duration: 691.383318ms)","trace[307154701] 'compare' (duration: 43.821278ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:46:53.528161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:52.792541Z","time spent":"735.589005ms","remote":"127.0.0.1:42116","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-02-05T00:47:18.487506Z","caller":"traceutil/trace.go:171","msg":"trace[1320405204] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"118.583134ms","start":"2026-02-05T00:47:18.368898Z","end":"2026-02-05T00:47:18.487481Z","steps":["trace[1320405204] 'process raft request' (duration: 118.313958ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:47:29.132931Z","caller":"traceutil/trace.go:171","msg":"trace[1471080460] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"116.129481ms","start":"2026-02-05T00:47:29.016787Z","end":"2026-02-05T00:47:29.132916Z","steps":["trace[1471080460] 'process raft request' (duration: 116.021069ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:47:30.057068Z","caller":"traceutil/trace.go:171","msg":"trace[1475162435] linearizableReadLoop","detail":"{readStateIndex:855; appliedIndex:854; }","duration":"355.435627ms","start":"2026-02-05T00:47:29.701613Z","end":"2026-02-05T00:47:30.057049Z","steps":["trace[1475162435] 'read index received' (duration: 355.292324ms)","trace[1475162435] 'applied index is now lower than readState.Index' (duration: 142.663µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:47:30.057266Z","caller":"traceutil/trace.go:171","msg":"trace[537482058] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"368.947852ms","start":"2026-02-05T00:47:29.68831Z","end":"2026-02-05T00:47:30.057258Z","steps":["trace[537482058] 'process raft request' (duration: 368.645135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:47:30.057343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:47:29.6883Z","time spent":"368.985022ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:47:30.057517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.902457ms","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-02-05T00:47:30.05754Z","caller":"traceutil/trace.go:171","msg":"trace[1356755646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:821; }","duration":"355.950259ms","start":"2026-02-05T00:47:29.701583Z","end":"2026-02-05T00:47:30.057533Z","steps":["trace[1356755646] 'agreement among raft nodes before linearized reading' (duration: 355.870397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:47:30.057559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:47:29.701569Z","time spent":"355.985859ms","remote":"127.0.0.1:42116","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-02-05T00:47:31.227326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.809247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T00:47:31.227492Z","caller":"traceutil/trace.go:171","msg":"trace[27677422] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"1.158828506s","start":"2026-02-05T00:47:30.06865Z","end":"2026-02-05T00:47:31.227479Z","steps":["trace[27677422] 'process raft request' (duration: 375.721712ms)","trace[27677422] 'compare' (duration: 782.557792ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:47:31.227535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:47:30.068641Z","time spent":"1.158873106s","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:47:31.227696Z","caller":"traceutil/trace.go:171","msg":"trace[939235781] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:855; }","duration":"630.324512ms","start":"2026-02-05T00:47:30.597349Z","end":"2026-02-05T00:47:31.227673Z","steps":["trace[939235781] 'read index received' (duration: 30.011µs)","trace[939235781] 'applied index is now lower than readState.Index' (duration: 630.290781ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:47:31.372891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.488614ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-05T00:47:31.372969Z","caller":"traceutil/trace.go:171","msg":"trace[324718657] linearizableReadLoop","detail":"{readStateIndex:857; appliedIndex:856; }","duration":"145.214908ms","start":"2026-02-05T00:47:31.227736Z","end":"2026-02-05T00:47:31.372951Z","steps":["trace[324718657] 'read index received' (duration: 48.811µs)","trace[324718657] 'applied index is now lower than readState.Index' (duration: 145.165247ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:47:31.373244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.888447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-05T00:47:31.373282Z","caller":"traceutil/trace.go:171","msg":"trace[1382202506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:822; }","duration":"775.956488ms","start":"2026-02-05T00:47:30.597319Z","end":"2026-02-05T00:47:31.373275Z","steps":["trace[1382202506] 'agreement among raft nodes before linearized reading' (duration: 630.453044ms)","trace[1382202506] 'range keys from in-memory index tree' (duration: 145.399822ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:47:31.373301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:47:30.597305Z","time spent":"775.990629ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T00:47:31.373508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.63665ms","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-02-05T00:47:31.373526Z","caller":"traceutil/trace.go:171","msg":"trace[282992516] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:822; }","duration":"237.65488ms","start":"2026-02-05T00:47:31.135867Z","end":"2026-02-05T00:47:31.373522Z","steps":["trace[282992516] 'agreement among raft nodes before linearized reading' (duration: 237.606669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:47:31.373622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.875742ms","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":"info","ts":"2026-02-05T00:47:31.373634Z","caller":"traceutil/trace.go:171","msg":"trace[191093815] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:822; }","duration":"661.912943ms","start":"2026-02-05T00:47:30.711717Z","end":"2026-02-05T00:47:31.37363Z","steps":["trace[191093815] 'agreement among raft nodes before linearized reading' (duration: 661.864952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:47:31.373651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:47:30.711704Z","time spent":"661.942523ms","remote":"127.0.0.1:42190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-02-05T00:47:39.55616Z","caller":"traceutil/trace.go:171","msg":"trace[2001143572] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:879; }","duration":"299.130279ms","start":"2026-02-05T00:47:39.257013Z","end":"2026-02-05T00:47:39.556143Z","steps":["trace[2001143572] 'read index received' (duration: 298.999406ms)","trace[2001143572] 'applied index is now lower than readState.Index' (duration: 130.363µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:47:39.556399Z","caller":"traceutil/trace.go:171","msg":"trace[101139943] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"370.676404ms","start":"2026-02-05T00:47:39.185717Z","end":"2026-02-05T00:47:39.556393Z","steps":["trace[101139943] 'process raft request' (duration: 370.339797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:47:39.556459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:47:39.185702Z","time spent":"370.713445ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:47:39.556607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.592609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T00:47:39.556624Z","caller":"traceutil/trace.go:171","msg":"trace[1248942398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:844; }","duration":"299.64397ms","start":"2026-02-05T00:47:39.256975Z","end":"2026-02-05T00:47:39.556619Z","steps":["trace[1248942398] 'agreement among raft nodes before linearized reading' (duration: 299.576709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:47:39.556715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.919564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T00:47:39.556727Z","caller":"traceutil/trace.go:171","msg":"trace[1451449277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:844; }","duration":"155.970445ms","start":"2026-02-05T00:47:39.400753Z","end":"2026-02-05T00:47:39.556723Z","steps":["trace[1451449277] 'agreement among raft nodes before linearized reading' (duration: 155.935025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:47:39.556894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.689676ms","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-02-05T00:47:39.556907Z","caller":"traceutil/trace.go:171","msg":"trace[779913097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:844; }","duration":"124.712786ms","start":"2026-02-05T00:47:39.43219Z","end":"2026-02-05T00:47:39.556903Z","steps":["trace[779913097] 'agreement among raft nodes before linearized reading' (duration: 124.677526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:47:39.556986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.779628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-05T00:47:39.557007Z","caller":"traceutil/trace.go:171","msg":"trace[276488103] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:844; }","duration":"129.81977ms","start":"2026-02-05T00:47:39.427184Z","end":"2026-02-05T00:47:39.557003Z","steps":["trace[276488103] 'agreement among raft nodes before linearized reading' (duration: 129.777719ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:47:43.916905Z","caller":"traceutil/trace.go:171","msg":"trace[2117888730] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"293.23746ms","start":"2026-02-05T00:47:43.623652Z","end":"2026-02-05T00:47:43.91689Z","steps":["trace[2117888730] 'process raft request' (duration: 293.155418ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:47:44.410582Z","caller":"traceutil/trace.go:171","msg":"trace[1868001471] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:893; }","duration":"404.611602ms","start":"2026-02-05T00:47:44.005957Z","end":"2026-02-05T00:47:44.410568Z","steps":["trace[1868001471] 'read index received' (duration: 358.069777ms)","trace[1868001471] 'applied index is now lower than readState.Index' (duration: 46.541145ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:47:44.410715Z","caller":"traceutil/trace.go:171","msg":"trace[323063423] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"786.521154ms","start":"2026-02-05T00:47:43.624188Z","end":"2026-02-05T00:47:44.410709Z","steps":["trace[323063423] 'process raft request' (duration: 739.922178ms)","trace[323063423] 'compare' (duration: 46.153316ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:47:44.410776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:47:43.624174Z","time spent":"786.558095ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:47:44.410875Z","caller":"traceutil/trace.go:171","msg":"trace[363895008] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"785.294437ms","start":"2026-02-05T00:47:43.625577Z","end":"2026-02-05T00:47:44.410871Z","steps":["trace[363895008] 'process raft request' (duration: 784.965609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:47:44.410899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:47:43.625562Z","time spent":"785.322857ms","remote":"127.0.0.1:42116","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-02-05T00:47:44.41097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.009141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:47:44.410995Z","caller":"traceutil/trace.go:171","msg":"trace[795674569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:858; }","duration":"405.062791ms","start":"2026-02-05T00:47:44.005928Z","end":"2026-02-05T00:47:44.410991Z","steps":["trace[795674569] 'agreement among raft nodes before linearized reading' (duration: 405.02163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:47:44.411008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:47:44.005914Z","time spent":"405.091131ms","remote":"127.0.0.1:42946","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-02-05T00:47:44.411116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.714905ms","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-02-05T00:47:44.411129Z","caller":"traceutil/trace.go:171","msg":"trace[1356203436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:858; }","duration":"266.726735ms","start":"2026-02-05T00:47:44.144398Z","end":"2026-02-05T00:47:44.411125Z","steps":["trace[1356203436] 'agreement among raft nodes before linearized reading' (duration: 266.671604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:47:44.662562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.491657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T00:47:44.662623Z","caller":"traceutil/trace.go:171","msg":"trace[1650703805] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"247.119073ms","start":"2026-02-05T00:47:44.415493Z","end":"2026-02-05T00:47:44.662612Z","steps":["trace[1650703805] 'process raft request' (duration: 134.521613ms)","trace[1650703805] 'compare' (duration: 112.376655ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:47:50.102438Z","caller":"traceutil/trace.go:171","msg":"trace[812457752] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"171.233752ms","start":"2026-02-05T00:47:49.931189Z","end":"2026-02-05T00:47:50.102422Z","steps":["trace[812457752] 'process raft request' (duration: 171.132119ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:47:50.699035Z","caller":"traceutil/trace.go:171","msg":"trace[867902873] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"266.340706ms","start":"2026-02-05T00:47:50.432677Z","end":"2026-02-05T00:47:50.699017Z","steps":["trace[867902873] 'process raft request' (duration: 193.541253ms)","trace[867902873] 'compare' (duration: 72.334123ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:47:51.230511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762494495353295,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T00:47:51.501564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.239482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T00:47:51.50163Z","caller":"traceutil/trace.go:171","msg":"trace[654642269] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"796.617386ms","start":"2026-02-05T00:47:50.705003Z","end":"2026-02-05T00:47:51.50162Z","steps":["trace[654642269] 'process raft request' (duration: 580.269221ms)","trace[654642269] 'compare' (duration: 216.176881ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:47:51.501662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:47:50.704988Z","time spent":"796.659937ms","remote":"127.0.0.1:42116","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-02-05T00:47:51.552011Z","caller":"traceutil/trace.go:171","msg":"trace[1276411713] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:934; }","duration":"821.912643ms","start":"2026-02-05T00:47:50.730081Z","end":"2026-02-05T00:47:51.551993Z","steps":["trace[1276411713] 'read index received' (duration: 555.199439ms)","trace[1276411713] 'applied index is now lower than readState.Index' (duration: 266.712424ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:47:51.552133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.045826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T00:47:51.552155Z","caller":"traceutil/trace.go:171","msg":"trace[555510129] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:897; }","duration":"822.119638ms","start":"2026-02-05T00:47:50.730025Z","end":"2026-02-05T00:47:51.552144Z","steps":["trace[555510129] 'agreement among raft nodes before linearized reading' (duration: 822.063466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:47:51.552176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:47:50.730005Z","time spent":"822.164528ms","remote":"127.0.0.1:42322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-05T00:47:51.552261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.082838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:47:51.552327Z","caller":"traceutil/trace.go:171","msg":"trace[565468301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"546.17662ms","start":"2026-02-05T00:47:51.00613Z","end":"2026-02-05T00:47:51.552307Z","steps":["trace[565468301] 'agreement among raft nodes before linearized reading' (duration: 546.072558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:47:51.552397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:47:51.006115Z","time spent":"546.268762ms","remote":"127.0.0.1:42946","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-02-05T00:48:54.01179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.54901ms","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-02-05T00:48:54.015265Z","caller":"traceutil/trace.go:171","msg":"trace[1031180611] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1182; }","duration":"140.031478ms","start":"2026-02-05T00:48:53.875212Z","end":"2026-02-05T00:48:54.015244Z","steps":["trace[1031180611] 'range keys from in-memory index tree' (duration: 136.532179ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:48:54.121049Z","caller":"traceutil/trace.go:171","msg":"trace[1342426268] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"101.918007ms","start":"2026-02-05T00:48:54.019109Z","end":"2026-02-05T00:48:54.121027Z","steps":["trace[1342426268] 'process raft request' (duration: 101.522238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:49:52.096205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.772201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-05T00:49:52.096256Z","caller":"traceutil/trace.go:171","msg":"trace[1492676853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1416; }","duration":"506.824672ms","start":"2026-02-05T00:49:51.589416Z","end":"2026-02-05T00:49:52.096241Z","steps":["trace[1492676853] 'range keys from in-memory index tree' (duration: 506.642429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:49:52.096276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:49:51.589354Z","time spent":"506.916835ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T00:49:52.096398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.012295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:49:52.096418Z","caller":"traceutil/trace.go:171","msg":"trace[1215825255] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1416; }","duration":"423.046386ms","start":"2026-02-05T00:49:51.673361Z","end":"2026-02-05T00:49:52.096408Z","steps":["trace[1215825255] 'count revisions from in-memory index tree' (duration: 422.979404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:49:52.09643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:49:51.673351Z","time spent":"423.075256ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-05T00:49:52.096574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.928932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-05T00:49:52.096588Z","caller":"traceutil/trace.go:171","msg":"trace[2818424] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1416; }","duration":"391.989834ms","start":"2026-02-05T00:49:51.704592Z","end":"2026-02-05T00:49:52.096582Z","steps":["trace[2818424] 'range keys from in-memory index tree' (duration: 391.84187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:49:52.096599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:49:51.704572Z","time spent":"392.025014ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-05T00:49:52.096753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.02439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-05T00:49:52.096768Z","caller":"traceutil/trace.go:171","msg":"trace[1071176933] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1416; }","duration":"171.072642ms","start":"2026-02-05T00:49:51.925691Z","end":"2026-02-05T00:49:52.096764Z","steps":["trace[1071176933] 'range keys from in-memory index tree' (duration: 170.908348ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:49:52.252769Z","caller":"traceutil/trace.go:171","msg":"trace[956170863] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"151.307671ms","start":"2026-02-05T00:49:52.101437Z","end":"2026-02-05T00:49:52.252745Z","steps":["trace[956170863] 'process raft request' (duration: 151.109837ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:49:52.678303Z","caller":"traceutil/trace.go:171","msg":"trace[504755423] linearizableReadLoop","detail":"{readStateIndex:1483; appliedIndex:1480; }","duration":"373.351689ms","start":"2026-02-05T00:49:52.304929Z","end":"2026-02-05T00:49:52.678281Z","steps":["trace[504755423] 'read index received' (duration: 373.088832ms)","trace[504755423] 'applied index is now lower than readState.Index' (duration: 262.067µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:49:52.678512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.564794ms","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-02-05T00:49:52.678545Z","caller":"traceutil/trace.go:171","msg":"trace[418470692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1420; }","duration":"373.637235ms","start":"2026-02-05T00:49:52.304897Z","end":"2026-02-05T00:49:52.678534Z","steps":["trace[418470692] 'agreement among raft nodes before linearized reading' (duration: 373.484111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:49:52.678577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:49:52.304884Z","time spent":"373.684006ms","remote":"127.0.0.1:42116","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-02-05T00:49:52.67889Z","caller":"traceutil/trace.go:171","msg":"trace[1272022322] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"574.743645ms","start":"2026-02-05T00:49:52.104133Z","end":"2026-02-05T00:49:52.678877Z","steps":["trace[1272022322] 'process raft request' (duration: 573.952737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:49:52.678967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:49:52.104128Z","time spent":"574.789466ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:49:52.679094Z","caller":"traceutil/trace.go:171","msg":"trace[203988699] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"574.429979ms","start":"2026-02-05T00:49:52.104653Z","end":"2026-02-05T00:49:52.679083Z","steps":["trace[203988699] 'process raft request' (duration: 573.559189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:49:52.67914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:49:52.104648Z","time spent":"574.466629ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:49:52.679252Z","caller":"traceutil/trace.go:171","msg":"trace[1985232120] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"573.701202ms","start":"2026-02-05T00:49:52.105543Z","end":"2026-02-05T00:49:52.679244Z","steps":["trace[1985232120] 'process raft request' (duration: 572.706039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:49:52.679313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:49:52.105538Z","time spent":"573.748513ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:49:52.877822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.085659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T00:49:52.877924Z","caller":"traceutil/trace.go:171","msg":"trace[803115707] linearizableReadLoop","detail":"{readStateIndex:1485; appliedIndex:1483; }","duration":"142.373062ms","start":"2026-02-05T00:49:52.735542Z","end":"2026-02-05T00:49:52.877915Z","steps":["trace[803115707] 'read index received' (duration: 21.16465ms)","trace[803115707] 'applied index is now lower than readState.Index' (duration: 121.207852ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:49:52.878023Z","caller":"traceutil/trace.go:171","msg":"trace[791508940] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"193.032401ms","start":"2026-02-05T00:49:52.684984Z","end":"2026-02-05T00:49:52.878017Z","steps":["trace[791508940] 'process raft request' (duration: 71.709687ms)","trace[791508940] 'compare' (duration: 120.950716ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:49:52.878103Z","caller":"traceutil/trace.go:171","msg":"trace[752296973] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"157.008638ms","start":"2026-02-05T00:49:52.72109Z","end":"2026-02-05T00:49:52.878099Z","steps":["trace[752296973] 'process raft request' (duration: 156.796923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:49:52.878192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.650519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T00:49:52.878205Z","caller":"traceutil/trace.go:171","msg":"trace[1193093520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1422; }","duration":"142.689279ms","start":"2026-02-05T00:49:52.735511Z","end":"2026-02-05T00:49:52.878201Z","steps":["trace[1193093520] 'agreement among raft nodes before linearized reading' (duration: 142.639388ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:49:53.133548Z","caller":"traceutil/trace.go:171","msg":"trace[1871830886] linearizableReadLoop","detail":"{readStateIndex:1487; appliedIndex:1486; }","duration":"125.799803ms","start":"2026-02-05T00:49:53.007738Z","end":"2026-02-05T00:49:53.133537Z","steps":["trace[1871830886] 'read index received' (duration: 89.248528ms)","trace[1871830886] 'applied index is now lower than readState.Index' (duration: 36.550905ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:49:53.133624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.876825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:49:53.133639Z","caller":"traceutil/trace.go:171","msg":"trace[1227475846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1424; }","duration":"125.923766ms","start":"2026-02-05T00:49:53.00771Z","end":"2026-02-05T00:49:53.133634Z","steps":["trace[1227475846] 'agreement among raft nodes before linearized reading' (duration: 125.878505ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:49:53.133725Z","caller":"traceutil/trace.go:171","msg":"trace[2056290077] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"249.396637ms","start":"2026-02-05T00:49:52.884323Z","end":"2026-02-05T00:49:53.133719Z","steps":["trace[2056290077] 'process raft request' (duration: 212.691219ms)","trace[2056290077] 'compare' (duration: 36.34575ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:49:56.85175Z","caller":"traceutil/trace.go:171","msg":"trace[264388364] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"124.710808ms","start":"2026-02-05T00:49:56.727024Z","end":"2026-02-05T00:49:56.851735Z","steps":["trace[264388364] 'process raft request' (duration: 124.630987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:49:57.549052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.595132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T00:49:57.549135Z","caller":"traceutil/trace.go:171","msg":"trace[394865056] linearizableReadLoop","detail":"{readStateIndex:1502; appliedIndex:1501; }","duration":"535.556472ms","start":"2026-02-05T00:49:57.013558Z","end":"2026-02-05T00:49:57.549114Z","steps":["trace[394865056] 'read index received' (duration: 236.785986ms)","trace[394865056] 'applied index is now lower than readState.Index' (duration: 298.767876ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:49:57.549298Z","caller":"traceutil/trace.go:171","msg":"trace[912914372] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"535.783697ms","start":"2026-02-05T00:49:57.013503Z","end":"2026-02-05T00:49:57.549287Z","steps":["trace[912914372] 'process raft request' (duration: 236.891268ms)","trace[912914372] 'compare' (duration: 298.432939ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:49:57.549345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:49:57.013491Z","time spent":"535.832299ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:49:57.549804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.255718ms","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-02-05T00:49:57.549841Z","caller":"traceutil/trace.go:171","msg":"trace[850952931] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1438; }","duration":"396.30953ms","start":"2026-02-05T00:49:57.153516Z","end":"2026-02-05T00:49:57.549825Z","steps":["trace[850952931] 'agreement among raft nodes before linearized reading' (duration: 396.222288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:49:57.549861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:49:57.153491Z","time spent":"396.36654ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T00:49:57.587877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.91013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:49:57.587934Z","caller":"traceutil/trace.go:171","msg":"trace[1368078545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1438; }","duration":"574.398758ms","start":"2026-02-05T00:49:57.013522Z","end":"2026-02-05T00:49:57.587921Z","steps":["trace[1368078545] 'agreement among raft nodes before linearized reading' (duration: 535.9268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:49:57.587975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:49:57.013508Z","time spent":"574.455059ms","remote":"127.0.0.1:42948","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-02-05T00:49:57.900351Z","caller":"traceutil/trace.go:171","msg":"trace[952041300] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"173.976507ms","start":"2026-02-05T00:49:57.726332Z","end":"2026-02-05T00:49:57.900309Z","steps":["trace[952041300] 'process raft request' (duration: 141.579455ms)","trace[952041300] 'compare' (duration: 32.326451ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:49:59.691202Z","caller":"traceutil/trace.go:171","msg":"trace[541552887] linearizableReadLoop","detail":"{readStateIndex:1511; appliedIndex:1510; }","duration":"138.393864ms","start":"2026-02-05T00:49:59.552797Z","end":"2026-02-05T00:49:59.691191Z","steps":["trace[541552887] 'read index received' (duration: 138.295961ms)","trace[541552887] 'applied index is now lower than readState.Index' (duration: 97.353µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:49:59.69133Z","caller":"traceutil/trace.go:171","msg":"trace[907382839] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"220.459443ms","start":"2026-02-05T00:49:59.470864Z","end":"2026-02-05T00:49:59.691323Z","steps":["trace[907382839] 'process raft request' (duration: 220.240368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:49:59.69146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.65448ms","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-02-05T00:49:59.693321Z","caller":"traceutil/trace.go:171","msg":"trace[585727997] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1447; }","duration":"140.575943ms","start":"2026-02-05T00:49:59.552738Z","end":"2026-02-05T00:49:59.693314Z","steps":["trace[585727997] 'agreement among raft nodes before linearized reading' (duration: 138.66144ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:49:59.809383Z","caller":"traceutil/trace.go:171","msg":"trace[1978321555] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"112.61978ms","start":"2026-02-05T00:49:59.696743Z","end":"2026-02-05T00:49:59.809362Z","steps":["trace[1978321555] 'process raft request' (duration: 112.547858ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:49:59.809662Z","caller":"traceutil/trace.go:171","msg":"trace[445198316] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"111.180937ms","start":"2026-02-05T00:49:59.698454Z","end":"2026-02-05T00:49:59.809635Z","steps":["trace[445198316] 'process raft request' (duration: 110.872711ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:49:59.809794Z","caller":"traceutil/trace.go:171","msg":"trace[1732939946] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"113.771035ms","start":"2026-02-05T00:49:59.696016Z","end":"2026-02-05T00:49:59.809787Z","steps":["trace[1732939946] 'process raft request' (duration: 55.567248ms)","trace[1732939946] 'compare' (duration: 57.599784ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:50:02.131067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.776994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:50:02.131244Z","caller":"traceutil/trace.go:171","msg":"trace[231039097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1457; }","duration":"122.00391ms","start":"2026-02-05T00:50:02.009216Z","end":"2026-02-05T00:50:02.13122Z","steps":["trace[231039097] 'range keys from in-memory index tree' (duration: 121.552169ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:50:03.873242Z","caller":"traceutil/trace.go:171","msg":"trace[151045086] transaction","detail":"{read_only:false; response_revision:1464; number_of_response:1; }","duration":"101.43507ms","start":"2026-02-05T00:50:03.771784Z","end":"2026-02-05T00:50:03.873219Z","steps":["trace[151045086] 'process raft request' (duration: 101.266986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:50:04.954974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.831652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T00:50:04.955068Z","caller":"traceutil/trace.go:171","msg":"trace[1984271427] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"135.141071ms","start":"2026-02-05T00:50:04.819909Z","end":"2026-02-05T00:50:04.95505Z","steps":["trace[1984271427] 'process raft request' (duration: 14.162506ms)","trace[1984271427] 'compare' (duration: 120.74977ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:50:05.143929Z","caller":"traceutil/trace.go:171","msg":"trace[1781803260] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"166.642993ms","start":"2026-02-05T00:50:04.977265Z","end":"2026-02-05T00:50:05.143908Z","steps":["trace[1781803260] 'process raft request' (duration: 114.318377ms)","trace[1781803260] 'compare' (duration: 52.241694ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:50:06.231995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.108642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T00:50:06.232066Z","caller":"traceutil/trace.go:171","msg":"trace[748037263] linearizableReadLoop","detail":"{readStateIndex:1541; appliedIndex:1540; }","duration":"192.622072ms","start":"2026-02-05T00:50:06.039432Z","end":"2026-02-05T00:50:06.232054Z","steps":["trace[748037263] 'read index received' (duration: 30.339066ms)","trace[748037263] 'applied index is now lower than readState.Index' (duration: 162.282326ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:50:06.232134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.700283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:50:06.232147Z","caller":"traceutil/trace.go:171","msg":"trace[834790826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1475; }","duration":"192.725474ms","start":"2026-02-05T00:50:06.039417Z","end":"2026-02-05T00:50:06.232142Z","steps":["trace[834790826] 'agreement among raft nodes before linearized reading' (duration: 192.665242ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:50:06.232378Z","caller":"traceutil/trace.go:171","msg":"trace[915305746] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"193.061032ms","start":"2026-02-05T00:50:06.03931Z","end":"2026-02-05T00:50:06.232371Z","steps":["trace[915305746] 'process raft request' (duration: 30.5248ms)","trace[915305746] 'compare' (duration: 162.00446ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:50:07.263035Z","caller":"traceutil/trace.go:171","msg":"trace[1609289223] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"110.995113ms","start":"2026-02-05T00:50:07.15202Z","end":"2026-02-05T00:50:07.263015Z","steps":["trace[1609289223] 'process raft request' (duration: 110.671786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:50:08.232022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.106728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T00:50:08.232133Z","caller":"traceutil/trace.go:171","msg":"trace[1229325803] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"187.421716ms","start":"2026-02-05T00:50:08.044682Z","end":"2026-02-05T00:50:08.232104Z","steps":["trace[1229325803] 'process raft request' (duration: 62.144004ms)","trace[1229325803] 'compare' (duration: 124.997476ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:50:20.644995Z","caller":"traceutil/trace.go:171","msg":"trace[2052398658] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"218.909476ms","start":"2026-02-05T00:50:20.42607Z","end":"2026-02-05T00:50:20.644979Z","steps":["trace[2052398658] 'process raft request' (duration: 215.620984ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:50:22.854871Z","caller":"traceutil/trace.go:171","msg":"trace[302767592] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"202.688176ms","start":"2026-02-05T00:50:22.652162Z","end":"2026-02-05T00:50:22.85485Z","steps":["trace[302767592] 'process raft request' (duration: 202.561714ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:50:25.073718Z","caller":"traceutil/trace.go:171","msg":"trace[1595690718] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"142.129087ms","start":"2026-02-05T00:50:24.931562Z","end":"2026-02-05T00:50:25.073691Z","steps":["trace[1595690718] 'process raft request' (duration: 92.209475ms)","trace[1595690718] 'compare' (duration: 49.624216ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:50:28.211094Z","caller":"traceutil/trace.go:171","msg":"trace[835538463] transaction","detail":"{read_only:false; response_revision:1561; number_of_response:1; }","duration":"123.954163ms","start":"2026-02-05T00:50:28.087118Z","end":"2026-02-05T00:50:28.211072Z","steps":["trace[835538463] 'process raft request' (duration: 122.213254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:50:28.65258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.489639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T00:50:28.652654Z","caller":"traceutil/trace.go:171","msg":"trace[1802822899] linearizableReadLoop","detail":"{readStateIndex:1632; appliedIndex:1631; }","duration":"321.552554ms","start":"2026-02-05T00:50:28.331083Z","end":"2026-02-05T00:50:28.652636Z","steps":["trace[1802822899] 'read index received' (duration: 49.071µs)","trace[1802822899] 'applied index is now lower than readState.Index' (duration: 321.502383ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:50:28.652714Z","caller":"traceutil/trace.go:171","msg":"trace[2040801] transaction","detail":"{read_only:false; response_revision:1562; number_of_response:1; }","duration":"432.927875ms","start":"2026-02-05T00:50:28.219778Z","end":"2026-02-05T00:50:28.652706Z","steps":["trace[2040801] 'process raft request' (duration: 73.250053ms)","trace[2040801] 'compare' (duration: 359.358545ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:50:28.652745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:50:28.219754Z","time spent":"432.973836ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:50:28.652868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.79894ms","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-02-05T00:50:28.652881Z","caller":"traceutil/trace.go:171","msg":"trace[1824190594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1562; }","duration":"321.860601ms","start":"2026-02-05T00:50:28.331015Z","end":"2026-02-05T00:50:28.652876Z","steps":["trace[1824190594] 'agreement among raft nodes before linearized reading' (duration: 321.78997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:50:28.652897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:50:28.330985Z","time spent":"321.909652ms","remote":"127.0.0.1:42116","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-02-05T00:50:28.653016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.853042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T00:50:28.653028Z","caller":"traceutil/trace.go:171","msg":"trace[1768815241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1562; }","duration":"107.901243ms","start":"2026-02-05T00:50:28.545123Z","end":"2026-02-05T00:50:28.653024Z","steps":["trace[1768815241] 'agreement among raft nodes before linearized reading' (duration: 107.856322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:50:30.269381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.961178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T00:50:30.269433Z","caller":"traceutil/trace.go:171","msg":"trace[255034955] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1571; }","duration":"105.070651ms","start":"2026-02-05T00:50:30.164349Z","end":"2026-02-05T00:50:30.26942Z","steps":["trace[255034955] 'count revisions from in-memory index tree' (duration: 104.912537ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:50:30.900988Z","caller":"traceutil/trace.go:171","msg":"trace[1330896604] transaction","detail":"{read_only:false; response_revision:1573; number_of_response:1; }","duration":"155.70407ms","start":"2026-02-05T00:50:30.745254Z","end":"2026-02-05T00:50:30.900958Z","steps":["trace[1330896604] 'process raft request' (duration: 119.403111ms)","trace[1330896604] 'compare' (duration: 36.011802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:50:33.933555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.186866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T00:50:33.933665Z","caller":"traceutil/trace.go:171","msg":"trace[565607499] linearizableReadLoop","detail":"{readStateIndex:1655; appliedIndex:1654; }","duration":"115.908592ms","start":"2026-02-05T00:50:33.817738Z","end":"2026-02-05T00:50:33.933647Z","steps":["trace[565607499] 'read index received' (duration: 9.495091ms)","trace[565607499] 'applied index is now lower than readState.Index' (duration: 106.412161ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:50:33.933816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.095936ms","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-02-05T00:50:33.933843Z","caller":"traceutil/trace.go:171","msg":"trace[1339053179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1584; }","duration":"116.160828ms","start":"2026-02-05T00:50:33.817673Z","end":"2026-02-05T00:50:33.933834Z","steps":["trace[1339053179] 'agreement among raft nodes before linearized reading' (duration: 116.025995ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:50:33.934108Z","caller":"traceutil/trace.go:171","msg":"trace[1598909937] transaction","detail":"{read_only:false; response_revision:1584; number_of_response:1; }","duration":"267.743136ms","start":"2026-02-05T00:50:33.666351Z","end":"2026-02-05T00:50:33.934094Z","steps":["trace[1598909937] 'process raft request' (duration: 160.931485ms)","trace[1598909937] 'compare' (duration: 106.091745ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:50:43.274905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.52708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T00:50:43.275295Z","caller":"traceutil/trace.go:171","msg":"trace[36681692] transaction","detail":"{read_only:false; response_revision:1621; number_of_response:1; }","duration":"198.062203ms","start":"2026-02-05T00:50:43.077209Z","end":"2026-02-05T00:50:43.275272Z","steps":["trace[36681692] 'process raft request' (duration: 84.046352ms)","trace[36681692] 'compare' (duration: 112.59836ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:50:43.275816Z","caller":"traceutil/trace.go:171","msg":"trace[810636661] linearizableReadLoop","detail":"{readStateIndex:1694; appliedIndex:1693; }","duration":"108.527939ms","start":"2026-02-05T00:50:43.166509Z","end":"2026-02-05T00:50:43.275037Z","steps":["trace[810636661] 'read index received' (duration: 36.731µs)","trace[810636661] 'applied index is now lower than readState.Index' (duration: 108.489108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:50:43.276366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.812527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T00:50:43.276416Z","caller":"traceutil/trace.go:171","msg":"trace[1529387978] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1621; }","duration":"109.93867ms","start":"2026-02-05T00:50:43.166463Z","end":"2026-02-05T00:50:43.276401Z","steps":["trace[1529387978] 'agreement among raft nodes before linearized reading' (duration: 109.642753ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:51:17.188738Z","caller":"traceutil/trace.go:171","msg":"trace[1794465623] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"119.258865ms","start":"2026-02-05T00:51:17.069452Z","end":"2026-02-05T00:51:17.188711Z","steps":["trace[1794465623] 'process raft request' (duration: 119.130532ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:51:20.130808Z","caller":"traceutil/trace.go:171","msg":"trace[265051678] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"109.757161ms","start":"2026-02-05T00:51:20.02103Z","end":"2026-02-05T00:51:20.130787Z","steps":["trace[265051678] 'process raft request' (duration: 109.438884ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:51:20.247381Z","caller":"traceutil/trace.go:171","msg":"trace[1729682143] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"110.12082ms","start":"2026-02-05T00:51:20.137233Z","end":"2026-02-05T00:51:20.247354Z","steps":["trace[1729682143] 'process raft request' (duration: 80.679531ms)","trace[1729682143] 'compare' (duration: 29.308686ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:51:48.043082Z","caller":"traceutil/trace.go:171","msg":"trace[1607652857] linearizableReadLoop","detail":"{readStateIndex:2013; appliedIndex:2012; }","duration":"308.921509ms","start":"2026-02-05T00:51:47.734136Z","end":"2026-02-05T00:51:48.043058Z","steps":["trace[1607652857] 'read index received' (duration: 308.690084ms)","trace[1607652857] 'applied index is now lower than readState.Index' (duration: 230.065µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:51:48.043782Z","caller":"traceutil/trace.go:171","msg":"trace[1539979515] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"338.296815ms","start":"2026-02-05T00:51:47.705468Z","end":"2026-02-05T00:51:48.043765Z","steps":["trace[1539979515] 'process raft request' (duration: 337.432706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:51:48.044065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:51:47.705456Z","time spent":"338.372046ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:51:48.044459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.46478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/kube-public/\" range_end:\"/registry/networkpolicies/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-05T00:51:48.045262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.121779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"info","ts":"2026-02-05T00:51:48.045311Z","caller":"traceutil/trace.go:171","msg":"trace[441561429] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:1925; }","duration":"311.1971ms","start":"2026-02-05T00:51:47.734099Z","end":"2026-02-05T00:51:48.045296Z","steps":["trace[441561429] 'agreement among raft nodes before linearized reading' (duration: 311.119538ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:51:48.044519Z","caller":"traceutil/trace.go:171","msg":"trace[217015286] range","detail":"{range_begin:/registry/networkpolicies/kube-public/; range_end:/registry/networkpolicies/kube-public0; response_count:0; response_revision:1925; }","duration":"248.576162ms","start":"2026-02-05T00:51:47.795926Z","end":"2026-02-05T00:51:48.044502Z","steps":["trace[217015286] 'agreement among raft nodes before linearized reading' (duration: 248.47681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:51:48.047255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.494583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"info","ts":"2026-02-05T00:51:48.047301Z","caller":"traceutil/trace.go:171","msg":"trace[1195601282] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:1925; }","duration":"245.557085ms","start":"2026-02-05T00:51:47.801732Z","end":"2026-02-05T00:51:48.047289Z","steps":["trace[1195601282] 'agreement among raft nodes before linearized reading' (duration: 245.474923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:51:48.047556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:51:47.73408Z","time spent":"313.462331ms","remote":"127.0.0.1:43052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3040,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"info","ts":"2026-02-05T00:52:01.150265Z","caller":"traceutil/trace.go:171","msg":"trace[1356154639] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"152.862272ms","start":"2026-02-05T00:52:00.997372Z","end":"2026-02-05T00:52:01.150234Z","steps":["trace[1356154639] 'process raft request' (duration: 152.129086ms)"],"step_count":1}