{"level":"warn","ts":"2026-01-29T18:46:12.426127Z","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-29T18:46:12.426227Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78: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-29T18:46:12.426328Z","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-29T18:46:12.426341Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-01-29T18:46:12.426359Z","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-29T18:46:12.427016Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-01-29T18:46:12.427116Z","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://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78: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-29T18:46:12.43153Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.203345ms"} {"level":"info","ts":"2026-01-29T18:46:12.475959Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-01-29T18:46:12.476023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-01-29T18:46:12.476043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-01-29T18:46:12.476057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-29T18:46:12.476064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-01-29T18:46:12.476094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-01-29T18:46:12.483072Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-29T18:46:12.48406Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-29T18:46:12.487145Z","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-29T18:46:12.497192Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-29T18:46:12.497666Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-29T18:46:12.497812Z","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-29T18:46:12.497849Z","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-29T18:46:12.497856Z","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-29T18:46:12.49828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-01-29T18:46:12.498407Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-01-29T18:46:12.5143Z","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-29T18:46:12.514443Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-01-29T18:46:12.514597Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-01-29T18:46:12.515764Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-29T18:46:12.51579Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-29T18:46:12.776526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-01-29T18:46:12.776573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-29T18:46:12.776597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-01-29T18:46:12.776608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-01-29T18:46:12.776715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-01-29T18:46:12.776724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-01-29T18:46:12.776731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-01-29T18:46:12.780299Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:46:12.784376Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-01-29T18:46:12.784401Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T18:46:12.784803Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T18:46:12.785012Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-29T18:46:12.785136Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-29T18:46:12.785321Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:46:12.785611Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:46:12.785689Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:46:12.806127Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"info","ts":"2026-01-29T18:46:12.806631Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-29T18:46:15.030311Z","caller":"traceutil/trace.go:171","msg":"trace[225502269] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"147.722801ms","start":"2026-01-29T18:46:14.88257Z","end":"2026-01-29T18:46:15.030293Z","steps":["trace[225502269] 'process raft request' (duration: 143.154606ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:46:15.038171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.647446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T18:46:15.038261Z","caller":"traceutil/trace.go:171","msg":"trace[1948308485] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"155.753619ms","start":"2026-01-29T18:46:14.882495Z","end":"2026-01-29T18:46:15.038249Z","steps":["trace[1948308485] 'agreement among raft nodes before linearized reading' (duration: 155.649687ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:46:15.986253Z","caller":"traceutil/trace.go:171","msg":"trace[1436863264] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"139.159717ms","start":"2026-01-29T18:46:15.847076Z","end":"2026-01-29T18:46:15.986235Z","steps":["trace[1436863264] 'process raft request' (duration: 132.30368ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:46:15.991669Z","caller":"traceutil/trace.go:171","msg":"trace[1555291777] linearizableReadLoop","detail":"{readStateIndex:90; appliedIndex:88; }","duration":"124.229731ms","start":"2026-01-29T18:46:15.867424Z","end":"2026-01-29T18:46:15.991654Z","steps":["trace[1555291777] 'read index received' (duration: 111.894215ms)","trace[1555291777] 'applied index is now lower than readState.Index' (duration: 12.334676ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:46:15.991771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.335774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T18:46:15.991798Z","caller":"traceutil/trace.go:171","msg":"trace[54793482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:84; }","duration":"124.395255ms","start":"2026-01-29T18:46:15.867396Z","end":"2026-01-29T18:46:15.991791Z","steps":["trace[54793482] 'agreement among raft nodes before linearized reading' (duration: 124.326694ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:46:15.99168Z","caller":"traceutil/trace.go:171","msg":"trace[458061970] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"143.990959ms","start":"2026-01-29T18:46:15.847669Z","end":"2026-01-29T18:46:15.99166Z","steps":["trace[458061970] 'process raft request' (duration: 143.902407ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:46:22.204336Z","caller":"traceutil/trace.go:171","msg":"trace[1459817484] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"109.899841ms","start":"2026-01-29T18:46:22.094413Z","end":"2026-01-29T18:46:22.204313Z","steps":["trace[1459817484] 'process raft request' (duration: 109.686626ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:46:28.714019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078429969s","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-01-29T18:46:28.714105Z","caller":"traceutil/trace.go:171","msg":"trace[735274096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:403; }","duration":"1.078571633s","start":"2026-01-29T18:46:27.635505Z","end":"2026-01-29T18:46:28.714077Z","steps":["trace[735274096] 'range keys from in-memory index tree' (duration: 1.078307646s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:46:28.714144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:27.635486Z","time spent":"1.078647994s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-29T18:46:28.7147Z","caller":"traceutil/trace.go:171","msg":"trace[333329903] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"238.243404ms","start":"2026-01-29T18:46:28.476443Z","end":"2026-01-29T18:46:28.714686Z","steps":["trace[333329903] 'process raft request' (duration: 146.659531ms)","trace[333329903] 'compare' (duration: 91.48003ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:46:29.308087Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602262162472835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:46:29.80874Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602262162472835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:46:30.309507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602262162472835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:46:30.673965Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.973547679s","expected-duration":"1s"} {"level":"info","ts":"2026-01-29T18:46:30.726268Z","caller":"traceutil/trace.go:171","msg":"trace[945393093] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"2.194627646s","start":"2026-01-29T18:46:28.531608Z","end":"2026-01-29T18:46:30.726235Z","steps":["trace[945393093] 'process raft request' (duration: 2.142983061s)","trace[945393093] 'compare' (duration: 50.452343ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:46:30.72697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:28.531589Z","time spent":"2.194761991s","remote":"127.0.0.1:47854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:46:31.386978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000411341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-29T18:46:31.387058Z","caller":"traceutil/trace.go:171","msg":"trace[383563081] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000542304s","start":"2026-01-29T18:46:29.386499Z","end":"2026-01-29T18:46:31.387041Z","steps":["trace[383563081] 'agreement among raft nodes before linearized reading' (duration: 2.000426301s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:46:31.387136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:29.386483Z","time spent":"2.000642787s","remote":"127.0.0.1:47686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T18:46:31.554163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.85ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:46:31.554331Z","caller":"traceutil/trace.go:171","msg":"trace[1298770022] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:416; }","duration":"2.74739951s","start":"2026-01-29T18:46:28.806883Z","end":"2026-01-29T18:46:31.554283Z","steps":["trace[1298770022] 'read index received' (duration: 1.86771766s)","trace[1298770022] 'applied index is now lower than readState.Index' (duration: 879.68058ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:46:31.554606Z","caller":"traceutil/trace.go:171","msg":"trace[910881593] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"2.836141785s","start":"2026-01-29T18:46:28.718451Z","end":"2026-01-29T18:46:31.554592Z","steps":["trace[910881593] 'process raft request' (duration: 2.276571095s)","trace[910881593] 'compare' (duration: 558.725926ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:46:31.55467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:28.718433Z","time spent":"2.836202737s","remote":"127.0.0.1:47930","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-01-29T18:46:31.554842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.747955565s","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-29T18:46:31.55487Z","caller":"traceutil/trace.go:171","msg":"trace[1551744696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:406; }","duration":"2.748010347s","start":"2026-01-29T18:46:28.806851Z","end":"2026-01-29T18:46:31.554861Z","steps":["trace[1551744696] 'agreement among raft nodes before linearized reading' (duration: 2.747956525s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:46:31.554898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:28.806835Z","time spent":"2.748055867s","remote":"127.0.0.1:47930","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-01-29T18:46:31.555031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.220220963s","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-29T18:46:31.555057Z","caller":"traceutil/trace.go:171","msg":"trace[2019165833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:406; }","duration":"2.220269873s","start":"2026-01-29T18:46:29.334779Z","end":"2026-01-29T18:46:31.555049Z","steps":["trace[2019165833] 'agreement among raft nodes before linearized reading' (duration: 2.220223562s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:46:31.555083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:29.334766Z","time spent":"2.220308925s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T18:46:31.555262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.946894ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:46:31.555377Z","caller":"traceutil/trace.go:171","msg":"trace[898783062] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:406; }","duration":"106.061617ms","start":"2026-01-29T18:46:31.449303Z","end":"2026-01-29T18:46:31.555365Z","steps":["trace[898783062] 'agreement among raft nodes before linearized reading' (duration: 105.937823ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:46:31.555567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.427714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:46:31.555594Z","caller":"traceutil/trace.go:171","msg":"trace[1304763176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"168.512356ms","start":"2026-01-29T18:46:31.387074Z","end":"2026-01-29T18:46:31.555586Z","steps":["trace[1304763176] 'agreement among raft nodes before linearized reading' (duration: 168.466325ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:46:31.555916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184456994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-01-29T18:46:31.555954Z","caller":"traceutil/trace.go:171","msg":"trace[696376266] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:406; }","duration":"1.184551267s","start":"2026-01-29T18:46:30.371392Z","end":"2026-01-29T18:46:31.555943Z","steps":["trace[696376266] 'agreement among raft nodes before linearized reading' (duration: 1.184445144s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:46:31.555986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:30.371379Z","time spent":"1.184597268s","remote":"127.0.0.1:47862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6917,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-01-29T18:46:31.556148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.953384382s","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-01-29T18:46:31.556667Z","caller":"traceutil/trace.go:171","msg":"trace[1259435857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:406; }","duration":"1.953897015s","start":"2026-01-29T18:46:29.602731Z","end":"2026-01-29T18:46:31.556628Z","steps":["trace[1259435857] 'agreement among raft nodes before linearized reading' (duration: 1.953384751s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:46:31.556757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:29.602718Z","time spent":"1.954025879s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-29T18:46:31.734557Z","caller":"traceutil/trace.go:171","msg":"trace[719797870] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"161.546397ms","start":"2026-01-29T18:46:31.572991Z","end":"2026-01-29T18:46:31.734537Z","steps":["trace[719797870] 'read index received' (duration: 87.484541ms)","trace[719797870] 'applied index is now lower than readState.Index' (duration: 74.061136ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:46:31.734662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.652269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:46:31.734684Z","caller":"traceutil/trace.go:171","msg":"trace[285258246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"161.719091ms","start":"2026-01-29T18:46:31.572958Z","end":"2026-01-29T18:46:31.734677Z","steps":["trace[285258246] 'agreement among raft nodes before linearized reading' (duration: 161.644309ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:46:31.734825Z","caller":"traceutil/trace.go:171","msg":"trace[1379337887] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"168.476876ms","start":"2026-01-29T18:46:31.566327Z","end":"2026-01-29T18:46:31.734804Z","steps":["trace[1379337887] 'process raft request' (duration: 94.281086ms)","trace[1379337887] 'compare' (duration: 73.82059ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:46:31.736141Z","caller":"traceutil/trace.go:171","msg":"trace[2095872860] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"162.888583ms","start":"2026-01-29T18:46:31.573246Z","end":"2026-01-29T18:46:31.736135Z","steps":["trace[2095872860] 'process raft request' (duration: 162.865092ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:46:31.73636Z","caller":"traceutil/trace.go:171","msg":"trace[1062146090] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"163.286154ms","start":"2026-01-29T18:46:31.573069Z","end":"2026-01-29T18:46:31.736355Z","steps":["trace[1062146090] 'process raft request' (duration: 162.926724ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:46:31.736414Z","caller":"traceutil/trace.go:171","msg":"trace[1252442360] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"163.288494ms","start":"2026-01-29T18:46:31.573122Z","end":"2026-01-29T18:46:31.736411Z","steps":["trace[1252442360] 'process raft request' (duration: 162.933415ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:46:31.736511Z","caller":"traceutil/trace.go:171","msg":"trace[1924506254] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"163.340374ms","start":"2026-01-29T18:46:31.573165Z","end":"2026-01-29T18:46:31.736506Z","steps":["trace[1924506254] 'process raft request' (duration: 162.924744ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:46:33.94855Z","caller":"traceutil/trace.go:171","msg":"trace[756809128] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"181.399667ms","start":"2026-01-29T18:46:33.767134Z","end":"2026-01-29T18:46:33.948533Z","steps":["trace[756809128] 'process raft request' (duration: 179.491745ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:46:34.014863Z","caller":"traceutil/trace.go:171","msg":"trace[1957045822] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"247.622909ms","start":"2026-01-29T18:46:33.767214Z","end":"2026-01-29T18:46:34.014837Z","steps":["trace[1957045822] 'process raft request' (duration: 247.520367ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:46:34.454963Z","caller":"traceutil/trace.go:171","msg":"trace[1142658481] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"123.907732ms","start":"2026-01-29T18:46:34.331039Z","end":"2026-01-29T18:46:34.454947Z","steps":["trace[1142658481] 'process raft request' (duration: 123.868231ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:46:34.45518Z","caller":"traceutil/trace.go:171","msg":"trace[1695629929] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"125.002922ms","start":"2026-01-29T18:46:34.33017Z","end":"2026-01-29T18:46:34.455173Z","steps":["trace[1695629929] 'process raft request' (duration: 121.028034ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:46:34.672096Z","caller":"traceutil/trace.go:171","msg":"trace[995258707] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"186.868435ms","start":"2026-01-29T18:46:34.485204Z","end":"2026-01-29T18:46:34.672073Z","steps":["trace[995258707] 'process raft request' (duration: 89.800453ms)","trace[995258707] 'compare' (duration: 96.910067ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:46:34.672197Z","caller":"traceutil/trace.go:171","msg":"trace[1385701871] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"184.268795ms","start":"2026-01-29T18:46:34.487882Z","end":"2026-01-29T18:46:34.672151Z","steps":["trace[1385701871] 'process raft request' (duration: 184.202954ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:46:34.672344Z","caller":"traceutil/trace.go:171","msg":"trace[1602620567] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:441; }","duration":"186.756263ms","start":"2026-01-29T18:46:34.485581Z","end":"2026-01-29T18:46:34.672337Z","steps":["trace[1602620567] 'read index received' (duration: 89.437374ms)","trace[1602620567] 'applied index is now lower than readState.Index' (duration: 97.317759ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:46:34.672463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.867616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-g8vnq\" ","response":"range_response_count:1 size:3994"} {"level":"info","ts":"2026-01-29T18:46:34.672488Z","caller":"traceutil/trace.go:171","msg":"trace[713094179] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-g8vnq; range_end:; response_count:1; response_revision:430; }","duration":"186.918687ms","start":"2026-01-29T18:46:34.485562Z","end":"2026-01-29T18:46:34.672481Z","steps":["trace[713094179] 'agreement among raft nodes before linearized reading' (duration: 186.825414ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:46:34.672666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.370081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-01-29T18:46:34.672687Z","caller":"traceutil/trace.go:171","msg":"trace[1972800200] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:430; }","duration":"186.402773ms","start":"2026-01-29T18:46:34.486278Z","end":"2026-01-29T18:46:34.672681Z","steps":["trace[1972800200] 'agreement among raft nodes before linearized reading' (duration: 186.357642ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:46:50.408704Z","caller":"traceutil/trace.go:171","msg":"trace[1330489407] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"324.588033ms","start":"2026-01-29T18:46:50.084099Z","end":"2026-01-29T18:46:50.408687Z","steps":["trace[1330489407] 'process raft request' (duration: 287.131774ms)","trace[1330489407] 'compare' (duration: 37.09685ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:46:50.408793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:50.084082Z","time spent":"324.664985ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T18:46:54.347268Z","caller":"traceutil/trace.go:171","msg":"trace[305546997] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"167.597841ms","start":"2026-01-29T18:46:54.179648Z","end":"2026-01-29T18:46:54.347246Z","steps":["trace[305546997] 'process raft request' (duration: 167.488048ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:46:54.727428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.351573ms","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-29T18:46:54.727519Z","caller":"traceutil/trace.go:171","msg":"trace[1509886190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"304.473907ms","start":"2026-01-29T18:46:54.423024Z","end":"2026-01-29T18:46:54.727498Z","steps":["trace[1509886190] 'range keys from in-memory index tree' (duration: 304.276801ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:46:54.727583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:54.423012Z","time spent":"304.559968ms","remote":"127.0.0.1:47930","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-29T18:46:55.232745Z","caller":"traceutil/trace.go:171","msg":"trace[1004326872] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"492.37464ms","start":"2026-01-29T18:46:54.740344Z","end":"2026-01-29T18:46:55.232719Z","steps":["trace[1004326872] 'process raft request' (duration: 492.231476ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:46:55.232906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:54.74032Z","time spent":"492.490243ms","remote":"127.0.0.1:47930","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-29T18:46:57.939074Z","caller":"traceutil/trace.go:171","msg":"trace[1213651757] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"288.653606ms","start":"2026-01-29T18:46:57.650412Z","end":"2026-01-29T18:46:57.939065Z","steps":["trace[1213651757] 'process raft request' (duration: 263.382768ms)","trace[1213651757] 'compare' (duration: 24.944299ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:46:57.938995Z","caller":"traceutil/trace.go:171","msg":"trace[600202461] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"131.396365ms","start":"2026-01-29T18:46:57.807583Z","end":"2026-01-29T18:46:57.938979Z","steps":["trace[600202461] 'read index received' (duration: 106.225891ms)","trace[600202461] 'applied index is now lower than readState.Index' (duration: 25.169324ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:46:57.939576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.025773ms","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-01-29T18:46:57.939599Z","caller":"traceutil/trace.go:171","msg":"trace[1330394413] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:554; }","duration":"132.092764ms","start":"2026-01-29T18:46:57.8075Z","end":"2026-01-29T18:46:57.939592Z","steps":["trace[1330394413] 'agreement among raft nodes before linearized reading' (duration: 131.811727ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:47:08.854304Z","caller":"traceutil/trace.go:171","msg":"trace[1425158149] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"132.848833ms","start":"2026-01-29T18:47:08.721441Z","end":"2026-01-29T18:47:08.85429Z","steps":["trace[1425158149] 'process raft request' (duration: 132.802952ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:47:08.854448Z","caller":"traceutil/trace.go:171","msg":"trace[1712861201] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"134.400353ms","start":"2026-01-29T18:47:08.720043Z","end":"2026-01-29T18:47:08.854443Z","steps":["trace[1712861201] 'process raft request' (duration: 130.869262ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:47:08.875061Z","caller":"traceutil/trace.go:171","msg":"trace[772147893] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"147.052341ms","start":"2026-01-29T18:47:08.727992Z","end":"2026-01-29T18:47:08.875045Z","steps":["trace[772147893] 'process raft request' (duration: 147.01ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:47:08.875379Z","caller":"traceutil/trace.go:171","msg":"trace[984345395] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"152.516243ms","start":"2026-01-29T18:47:08.722856Z","end":"2026-01-29T18:47:08.875373Z","steps":["trace[984345395] 'process raft request' (duration: 152.059131ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:47:09.085569Z","caller":"traceutil/trace.go:171","msg":"trace[1540704216] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:722; }","duration":"185.337394ms","start":"2026-01-29T18:47:08.900213Z","end":"2026-01-29T18:47:09.08555Z","steps":["trace[1540704216] 'read index received' (duration: 124.956829ms)","trace[1540704216] 'applied index is now lower than readState.Index' (duration: 60.379615ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:47:09.085716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.486637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3769"} {"level":"info","ts":"2026-01-29T18:47:09.08574Z","caller":"traceutil/trace.go:171","msg":"trace[2147162224] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:701; }","duration":"185.530338ms","start":"2026-01-29T18:47:08.9002Z","end":"2026-01-29T18:47:09.085731Z","steps":["trace[2147162224] 'agreement among raft nodes before linearized reading' (duration: 185.417605ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:47:09.085724Z","caller":"traceutil/trace.go:171","msg":"trace[425204421] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"186.300038ms","start":"2026-01-29T18:47:08.899403Z","end":"2026-01-29T18:47:09.085703Z","steps":["trace[425204421] 'process raft request' (duration: 125.737869ms)","trace[425204421] 'compare' (duration: 60.1781ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:47:14.594698Z","caller":"traceutil/trace.go:171","msg":"trace[1358652020] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"151.29882ms","start":"2026-01-29T18:47:14.443287Z","end":"2026-01-29T18:47:14.594586Z","steps":["trace[1358652020] 'process raft request' (duration: 151.179377ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:47:16.177864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075048601s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-29T18:47:16.177833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.060343ms","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-29T18:47:16.177911Z","caller":"traceutil/trace.go:171","msg":"trace[2076177477] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:719; }","duration":"1.075125903s","start":"2026-01-29T18:47:15.10277Z","end":"2026-01-29T18:47:16.177896Z","steps":["trace[2076177477] 'count revisions from in-memory index tree' (duration: 1.074957378s)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:47:16.177918Z","caller":"traceutil/trace.go:171","msg":"trace[1870882705] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:719; }","duration":"362.164016ms","start":"2026-01-29T18:47:15.815747Z","end":"2026-01-29T18:47:16.177911Z","steps":["trace[1870882705] 'range keys from in-memory index tree' (duration: 362.049112ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:47:16.177941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:15.102751Z","time spent":"1.075181914s","remote":"127.0.0.1:47890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-01-29T18:47:16.178252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.050264ms","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-29T18:47:16.178275Z","caller":"traceutil/trace.go:171","msg":"trace[1819108793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:719; }","duration":"151.106936ms","start":"2026-01-29T18:47:16.027162Z","end":"2026-01-29T18:47:16.178269Z","steps":["trace[1819108793] 'range keys from in-memory index tree' (duration: 150.943782ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:47:16.178408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.77177ms","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-29T18:47:16.178428Z","caller":"traceutil/trace.go:171","msg":"trace[195811598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:719; }","duration":"132.827672ms","start":"2026-01-29T18:47:16.045594Z","end":"2026-01-29T18:47:16.178422Z","steps":["trace[195811598] 'range keys from in-memory index tree' (duration: 132.650458ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:47:16.178254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.716726ms","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-29T18:47:16.17852Z","caller":"traceutil/trace.go:171","msg":"trace[1789953211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:719; }","duration":"819.011734ms","start":"2026-01-29T18:47:15.3595Z","end":"2026-01-29T18:47:16.178512Z","steps":["trace[1789953211] 'range keys from in-memory index tree' (duration: 818.639575ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:47:16.178548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:15.359468Z","time spent":"819.071856ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T18:47:17.494814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.054106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T18:47:17.494875Z","caller":"traceutil/trace.go:171","msg":"trace[1832845374] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:726; }","duration":"403.130438ms","start":"2026-01-29T18:47:17.091732Z","end":"2026-01-29T18:47:17.494863Z","steps":["trace[1832845374] 'count revisions from in-memory index tree' (duration: 402.996014ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:47:17.494905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:17.091685Z","time spent":"403.210179ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-01-29T18:47:24.344476Z","caller":"traceutil/trace.go:171","msg":"trace[1112882838] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"125.267727ms","start":"2026-01-29T18:47:24.219182Z","end":"2026-01-29T18:47:24.344449Z","steps":["trace[1112882838] 'read index received' (duration: 125.05829ms)","trace[1112882838] 'applied index is now lower than readState.Index' (duration: 208.325µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:47:24.344738Z","caller":"traceutil/trace.go:171","msg":"trace[602100213] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"129.445075ms","start":"2026-01-29T18:47:24.215281Z","end":"2026-01-29T18:47:24.344726Z","steps":["trace[602100213] 'process raft request' (duration: 129.058545ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:47:24.345001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.804789ms","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-29T18:47:24.345034Z","caller":"traceutil/trace.go:171","msg":"trace[801173288] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"125.871732ms","start":"2026-01-29T18:47:24.219152Z","end":"2026-01-29T18:47:24.345024Z","steps":["trace[801173288] 'agreement among raft nodes before linearized reading' (duration: 125.749729ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:47:24.345294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.703735ms","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-01-29T18:47:24.345331Z","caller":"traceutil/trace.go:171","msg":"trace[1037884391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:750; }","duration":"104.762396ms","start":"2026-01-29T18:47:24.24056Z","end":"2026-01-29T18:47:24.345322Z","steps":["trace[1037884391] 'agreement among raft nodes before linearized reading' (duration: 104.647713ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:47:24.345639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.653722ms","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-29T18:47:24.345676Z","caller":"traceutil/trace.go:171","msg":"trace[1102700051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:750; }","duration":"104.693173ms","start":"2026-01-29T18:47:24.240974Z","end":"2026-01-29T18:47:24.345667Z","steps":["trace[1102700051] 'agreement among raft nodes before linearized reading' (duration: 104.594461ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:47:34.687173Z","caller":"traceutil/trace.go:171","msg":"trace[248164759] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"231.825907ms","start":"2026-01-29T18:47:34.455331Z","end":"2026-01-29T18:47:34.687157Z","steps":["trace[248164759] 'process raft request' (duration: 231.719535ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:47:34.692344Z","caller":"traceutil/trace.go:171","msg":"trace[294829076] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"236.909699ms","start":"2026-01-29T18:47:34.455426Z","end":"2026-01-29T18:47:34.692336Z","steps":["trace[294829076] 'process raft request' (duration: 236.849997ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:47:35.06908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.199936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:47:35.069157Z","caller":"traceutil/trace.go:171","msg":"trace[1240968515] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"295.721663ms","start":"2026-01-29T18:47:34.773419Z","end":"2026-01-29T18:47:35.06914Z","steps":["trace[1240968515] 'process raft request' (duration: 70.390964ms)","trace[1240968515] 'compare' (duration: 224.861308ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:47:35.416717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.061568ms","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-01-29T18:47:35.416784Z","caller":"traceutil/trace.go:171","msg":"trace[161376127] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:782; }","duration":"236.183091ms","start":"2026-01-29T18:47:35.180587Z","end":"2026-01-29T18:47:35.41677Z","steps":["trace[161376127] 'count revisions from in-memory index tree' (duration: 236.007696ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:47:36.841077Z","caller":"traceutil/trace.go:171","msg":"trace[330278907] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"150.388146ms","start":"2026-01-29T18:47:36.69067Z","end":"2026-01-29T18:47:36.841058Z","steps":["trace[330278907] 'read index received' (duration: 150.291943ms)","trace[330278907] 'applied index is now lower than readState.Index' (duration: 94.973µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:47:36.841247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.563762ms","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-29T18:47:36.841263Z","caller":"traceutil/trace.go:171","msg":"trace[577587388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"150.631613ms","start":"2026-01-29T18:47:36.690627Z","end":"2026-01-29T18:47:36.841258Z","steps":["trace[577587388] 'agreement among raft nodes before linearized reading' (duration: 150.52596ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:47:36.841462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.266701ms","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-29T18:47:36.841477Z","caller":"traceutil/trace.go:171","msg":"trace[1627326846] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"146.312562ms","start":"2026-01-29T18:47:36.69516Z","end":"2026-01-29T18:47:36.841472Z","steps":["trace[1627326846] 'agreement among raft nodes before linearized reading' (duration: 146.272201ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:47:47.814298Z","caller":"traceutil/trace.go:171","msg":"trace[1638963753] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"307.978491ms","start":"2026-01-29T18:47:47.506297Z","end":"2026-01-29T18:47:47.814276Z","steps":["trace[1638963753] 'process raft request' (duration: 307.801357ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:47:47.814474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:47.506272Z","time spent":"308.125085ms","remote":"127.0.0.1:47930","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-29T18:47:49.242894Z","caller":"traceutil/trace.go:171","msg":"trace[412218502] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"275.52563ms","start":"2026-01-29T18:47:48.967352Z","end":"2026-01-29T18:47:49.242878Z","steps":["trace[412218502] 'read index received' (duration: 275.399837ms)","trace[412218502] 'applied index is now lower than readState.Index' (duration: 124.963µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:47:49.243088Z","caller":"traceutil/trace.go:171","msg":"trace[380467140] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"276.924865ms","start":"2026-01-29T18:47:48.966158Z","end":"2026-01-29T18:47:49.243082Z","steps":["trace[380467140] 'process raft request' (duration: 276.635578ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:47:49.24325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.884449ms","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-29T18:47:49.243267Z","caller":"traceutil/trace.go:171","msg":"trace[143825358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:872; }","duration":"275.930351ms","start":"2026-01-29T18:47:48.967332Z","end":"2026-01-29T18:47:49.243262Z","steps":["trace[143825358] 'agreement among raft nodes before linearized reading' (duration: 275.851869ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:47:49.460546Z","caller":"traceutil/trace.go:171","msg":"trace[1586270480] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"214.427068ms","start":"2026-01-29T18:47:49.2461Z","end":"2026-01-29T18:47:49.460527Z","steps":["trace[1586270480] 'process raft request' (duration: 178.436275ms)","trace[1586270480] 'compare' (duration: 35.738846ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:47:49.475372Z","caller":"traceutil/trace.go:171","msg":"trace[1053324675] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:905; }","duration":"159.704928ms","start":"2026-01-29T18:47:49.315652Z","end":"2026-01-29T18:47:49.475357Z","steps":["trace[1053324675] 'read index received' (duration: 108.824809ms)","trace[1053324675] 'applied index is now lower than readState.Index' (duration: 50.879189ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:47:49.475475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.810152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T18:47:49.475492Z","caller":"traceutil/trace.go:171","msg":"trace[423002734] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:874; }","duration":"159.875463ms","start":"2026-01-29T18:47:49.315612Z","end":"2026-01-29T18:47:49.475487Z","steps":["trace[423002734] 'agreement among raft nodes before linearized reading' (duration: 159.802221ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:47:49.47567Z","caller":"traceutil/trace.go:171","msg":"trace[757739926] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"228.985995ms","start":"2026-01-29T18:47:49.246662Z","end":"2026-01-29T18:47:49.475648Z","steps":["trace[757739926] 'process raft request' (duration: 228.611585ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:47:50.57928Z","caller":"traceutil/trace.go:171","msg":"trace[1429504657] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"128.345617ms","start":"2026-01-29T18:47:50.450917Z","end":"2026-01-29T18:47:50.579263Z","steps":["trace[1429504657] 'process raft request' (duration: 128.050898ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:47:59.432235Z","caller":"traceutil/trace.go:171","msg":"trace[208298945] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"147.477952ms","start":"2026-01-29T18:47:59.284742Z","end":"2026-01-29T18:47:59.43222Z","steps":["trace[208298945] 'process raft request' (duration: 147.38291ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:48:00.218873Z","caller":"traceutil/trace.go:171","msg":"trace[223817497] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"147.683188ms","start":"2026-01-29T18:48:00.071173Z","end":"2026-01-29T18:48:00.218857Z","steps":["trace[223817497] 'process raft request' (duration: 147.590115ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:48:02.858374Z","caller":"traceutil/trace.go:171","msg":"trace[109807579] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"173.99167ms","start":"2026-01-29T18:48:02.684366Z","end":"2026-01-29T18:48:02.858357Z","steps":["trace[109807579] 'read index received' (duration: 173.836086ms)","trace[109807579] 'applied index is now lower than readState.Index' (duration: 154.834µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:48:02.858552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.165564ms","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-01-29T18:48:02.858588Z","caller":"traceutil/trace.go:171","msg":"trace[1068327752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:909; }","duration":"174.239815ms","start":"2026-01-29T18:48:02.684341Z","end":"2026-01-29T18:48:02.858581Z","steps":["trace[1068327752] 'agreement among raft nodes before linearized reading' (duration: 174.089732ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:48:02.858797Z","caller":"traceutil/trace.go:171","msg":"trace[1378564514] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"189.748008ms","start":"2026-01-29T18:48:02.669042Z","end":"2026-01-29T18:48:02.85879Z","steps":["trace[1378564514] 'process raft request' (duration: 189.210013ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:48:03.293628Z","caller":"traceutil/trace.go:171","msg":"trace[1287156862] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"424.017909ms","start":"2026-01-29T18:48:02.869574Z","end":"2026-01-29T18:48:03.293592Z","steps":["trace[1287156862] 'process raft request' (duration: 423.916445ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:48:03.293725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:02.869556Z","time spent":"424.118262ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T18:48:03.602645Z","caller":"traceutil/trace.go:171","msg":"trace[820506879] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"156.924097ms","start":"2026-01-29T18:48:03.4457Z","end":"2026-01-29T18:48:03.602624Z","steps":["trace[820506879] 'process raft request' (duration: 155.667814ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:48:15.952029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.747248ms","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-29T18:48:15.956392Z","caller":"traceutil/trace.go:171","msg":"trace[1818876399] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:974; }","duration":"140.123616ms","start":"2026-01-29T18:48:15.81625Z","end":"2026-01-29T18:48:15.956374Z","steps":["trace[1818876399] 'range keys from in-memory index tree' (duration: 135.731268ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:48:22.953435Z","caller":"traceutil/trace.go:171","msg":"trace[604323753] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"200.655504ms","start":"2026-01-29T18:48:22.752753Z","end":"2026-01-29T18:48:22.953408Z","steps":["trace[604323753] 'process raft request' (duration: 200.185355ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:48:41.003558Z","caller":"traceutil/trace.go:171","msg":"trace[102223251] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"230.659162ms","start":"2026-01-29T18:48:40.77288Z","end":"2026-01-29T18:48:41.003539Z","steps":["trace[102223251] 'process raft request' (duration: 183.366418ms)","trace[102223251] 'compare' (duration: 47.189122ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:48:54.548452Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602262162474523,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:48:56.554195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000359218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-29T18:48:57.77851Z","caller":"traceutil/trace.go:171","msg":"trace[935058879] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"3.224713416s","start":"2026-01-29T18:48:54.55378Z","end":"2026-01-29T18:48:57.778493Z","steps":["trace[935058879] 'agreement among raft nodes before linearized reading' (duration: 2.000385458s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:48:58.226174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602262162474523,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:48:58.726278Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602262162474523,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:49:01.775259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:54.553764Z","time spent":"7.221447324s","remote":"127.0.0.1:47686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T18:49:01.778863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602262162474523,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:49:01.778938Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} 2026/01/29 18:49:01 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-29T18:49:01.77947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.652046288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-29T18:49:01.779515Z","caller":"traceutil/trace.go:171","msg":"trace[869034722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"6.65213713s","start":"2026-01-29T18:48:55.12736Z","end":"2026-01-29T18:49:01.779497Z","steps":["trace[869034722] 'agreement among raft nodes before linearized reading' (duration: 6.652076229s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.77956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:55.127344Z","time spent":"6.652208562s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T18:48:58.95387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999965523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-29T18:49:01.780601Z","caller":"traceutil/trace.go:171","msg":"trace[2073379003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"7.826740667s","start":"2026-01-29T18:48:53.953833Z","end":"2026-01-29T18:49:01.780574Z","steps":["trace[2073379003] 'agreement among raft nodes before linearized reading' (duration: 5.000011284s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.786073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:53.953822Z","time spent":"7.832179819s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-29T18:49:01.787813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.062586155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"warn","ts":"2026-01-29T18:49:01.780889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:53.953339Z","time spent":"7.827547745s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-01-29T18:49:01.78112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.731129406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"warn","ts":"2026-01-29T18:49:01.788438Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"7.834941999s","expected-duration":"1s"} {"level":"info","ts":"2026-01-29T18:49:01.793491Z","caller":"traceutil/trace.go:171","msg":"trace[1040749330] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"4.068257413s","start":"2026-01-29T18:48:57.725203Z","end":"2026-01-29T18:49:01.79346Z","steps":["trace[1040749330] 'agreement among raft nodes before linearized reading' (duration: 4.062591815s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.793717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:57.7252Z","time spent":"4.068492899s","remote":"127.0.0.1:47686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T18:49:01.794135Z","caller":"traceutil/trace.go:171","msg":"trace[393070685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.744166158s","start":"2026-01-29T18:48:56.04996Z","end":"2026-01-29T18:49:01.794126Z","steps":["trace[393070685] 'agreement among raft nodes before linearized reading' (duration: 5.731137796s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.794206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:56.049954Z","time spent":"5.74424256s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-29T18:49:01.813588Z","caller":"traceutil/trace.go:171","msg":"trace[1157860661] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"3.024620917s","start":"2026-01-29T18:48:58.78895Z","end":"2026-01-29T18:49:01.81357Z","steps":["trace[1157860661] 'process raft request' (duration: 3.023497407s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.813704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:58.788932Z","time spent":"3.024708708s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:49:01.82844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.310449007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-01-29T18:49:01.828507Z","caller":"traceutil/trace.go:171","msg":"trace[834556633] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1158; }","duration":"3.31055018s","start":"2026-01-29T18:48:58.517935Z","end":"2026-01-29T18:49:01.828485Z","steps":["trace[834556633] 'agreement among raft nodes before linearized reading' (duration: 3.310394456s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.828536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:58.517921Z","time spent":"3.310608681s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-01-29T18:49:01.828714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.546617674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-01-29T18:49:01.828736Z","caller":"traceutil/trace.go:171","msg":"trace[1599099989] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1158; }","duration":"3.546678376s","start":"2026-01-29T18:48:58.282051Z","end":"2026-01-29T18:49:01.828729Z","steps":["trace[1599099989] 'agreement among raft nodes before linearized reading' (duration: 3.546606244s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.828753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:58.282036Z","time spent":"3.546712656s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-01-29T18:49:01.828897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.003343192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:49:01.828917Z","caller":"traceutil/trace.go:171","msg":"trace[1911037962] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1158; }","duration":"4.003365802s","start":"2026-01-29T18:48:57.825545Z","end":"2026-01-29T18:49:01.828911Z","steps":["trace[1911037962] 'agreement among raft nodes before linearized reading' (duration: 4.003330862s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.828943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:57.82551Z","time spent":"4.003428254s","remote":"127.0.0.1:47838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-01-29T18:49:01.829087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.103887549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:49:01.829107Z","caller":"traceutil/trace.go:171","msg":"trace[2081397159] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:0; response_revision:1158; }","duration":"4.10393301s","start":"2026-01-29T18:48:57.725168Z","end":"2026-01-29T18:49:01.829101Z","steps":["trace[2081397159] 'agreement among raft nodes before linearized reading' (duration: 4.103899829s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.829151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:57.725157Z","time spent":"4.103987991s","remote":"127.0.0.1:47716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"warn","ts":"2026-01-29T18:49:01.829281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.392467693s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T18:49:01.829302Z","caller":"traceutil/trace.go:171","msg":"trace[42874153] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1158; }","duration":"5.392529535s","start":"2026-01-29T18:48:56.436766Z","end":"2026-01-29T18:49:01.829296Z","steps":["trace[42874153] 'agreement among raft nodes before linearized reading' (duration: 5.392477934s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.82932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:56.436748Z","time spent":"5.392567235s","remote":"127.0.0.1:48064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-01-29T18:49:01.829452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.638978091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T18:49:01.829471Z","caller":"traceutil/trace.go:171","msg":"trace[1549139979] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1158; }","duration":"5.639020741s","start":"2026-01-29T18:48:56.190444Z","end":"2026-01-29T18:49:01.829465Z","steps":["trace[1549139979] 'agreement among raft nodes before linearized reading' (duration: 5.63896606s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.829488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:56.19043Z","time spent":"5.639052582s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":12,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-01-29T18:49:01.829647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.77961795s","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:533"} {"level":"info","ts":"2026-01-29T18:49:01.829668Z","caller":"traceutil/trace.go:171","msg":"trace[196471357] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1158; }","duration":"5.77964622s","start":"2026-01-29T18:48:56.050015Z","end":"2026-01-29T18:49:01.829661Z","steps":["trace[196471357] 'agreement among raft nodes before linearized reading' (duration: 5.779575368s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.829687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:56.050013Z","time spent":"5.779668271s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-01-29T18:49:01.829817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.779802414s","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-01-29T18:49:01.829838Z","caller":"traceutil/trace.go:171","msg":"trace[1511218397] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1158; }","duration":"5.779831764s","start":"2026-01-29T18:48:56.05Z","end":"2026-01-29T18:49:01.829832Z","steps":["trace[1511218397] 'agreement among raft nodes before linearized reading' (duration: 5.779774203s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.829855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:56.049997Z","time spent":"5.779853625s","remote":"127.0.0.1:47930","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-01-29T18:49:01.829961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.780948446s","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-29T18:49:01.82998Z","caller":"traceutil/trace.go:171","msg":"trace[725580256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1158; }","duration":"5.780982468s","start":"2026-01-29T18:48:56.048992Z","end":"2026-01-29T18:49:01.829974Z","steps":["trace[725580256] 'agreement among raft nodes before linearized reading' (duration: 5.780929806s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.829996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:56.048986Z","time spent":"5.781005298s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T18:49:01.8301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.782253454s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-01-29T18:49:01.830121Z","caller":"traceutil/trace.go:171","msg":"trace[533076780] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1158; }","duration":"5.782280274s","start":"2026-01-29T18:48:56.047834Z","end":"2026-01-29T18:49:01.830114Z","steps":["trace[533076780] 'agreement among raft nodes before linearized reading' (duration: 5.782229353s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.830136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:56.047831Z","time spent":"5.782300535s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-01-29T18:49:01.830224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.782392168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T18:49:01.830246Z","caller":"traceutil/trace.go:171","msg":"trace[1954136735] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1158; }","duration":"5.782423339s","start":"2026-01-29T18:48:56.047815Z","end":"2026-01-29T18:49:01.830239Z","steps":["trace[1954136735] 'agreement among raft nodes before linearized reading' (duration: 5.782384737s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.830266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:56.047812Z","time spent":"5.78244704s","remote":"127.0.0.1:59522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-01-29T18:49:01.830384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.782571943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T18:49:01.830405Z","caller":"traceutil/trace.go:171","msg":"trace[2061344847] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1158; }","duration":"5.782614683s","start":"2026-01-29T18:48:56.047784Z","end":"2026-01-29T18:49:01.830399Z","steps":["trace[2061344847] 'agreement among raft nodes before linearized reading' (duration: 5.782572682s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.830423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:56.047774Z","time spent":"5.782643304s","remote":"127.0.0.1:47734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-01-29T18:49:01.830535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.090241244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T18:49:01.830556Z","caller":"traceutil/trace.go:171","msg":"trace[1220067331] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1158; }","duration":"7.090290454s","start":"2026-01-29T18:48:54.74026Z","end":"2026-01-29T18:49:01.830551Z","steps":["trace[1220067331] 'agreement among raft nodes before linearized reading' (duration: 7.090251303s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:01.830574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:54.740245Z","time spent":"7.090323185s","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-01-29T18:49:08.08292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.808855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:49:08.083015Z","caller":"traceutil/trace.go:171","msg":"trace[280074200] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"163.669126ms","start":"2026-01-29T18:49:07.919334Z","end":"2026-01-29T18:49:08.083004Z","steps":["trace[280074200] 'read index received' (duration: 3.642425ms)","trace[280074200] 'applied index is now lower than readState.Index' (duration: 160.025891ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:49:08.083332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.182062ms","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":"warn","ts":"2026-01-29T18:49:08.083319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.955533ms","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-29T18:49:08.083358Z","caller":"traceutil/trace.go:171","msg":"trace[1828448175] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1188; }","duration":"155.227813ms","start":"2026-01-29T18:49:07.928121Z","end":"2026-01-29T18:49:08.083349Z","steps":["trace[1828448175] 'agreement among raft nodes before linearized reading' (duration: 155.11599ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:49:08.083414Z","caller":"traceutil/trace.go:171","msg":"trace[295833782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1188; }","duration":"164.090166ms","start":"2026-01-29T18:49:07.919292Z","end":"2026-01-29T18:49:08.083382Z","steps":["trace[295833782] 'agreement among raft nodes before linearized reading' (duration: 163.85284ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:49:08.083571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.51977ms","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-01-29T18:49:08.083593Z","caller":"traceutil/trace.go:171","msg":"trace[803940668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1188; }","duration":"150.558021ms","start":"2026-01-29T18:49:07.933029Z","end":"2026-01-29T18:49:08.083587Z","steps":["trace[803940668] 'agreement among raft nodes before linearized reading' (duration: 150.477229ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:49:08.083636Z","caller":"traceutil/trace.go:171","msg":"trace[91477739] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"164.331743ms","start":"2026-01-29T18:49:07.919292Z","end":"2026-01-29T18:49:08.083623Z","steps":["trace[91477739] 'compare' (duration: 159.699931ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:49:08.247087Z","caller":"traceutil/trace.go:171","msg":"trace[1101564124] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"156.730974ms","start":"2026-01-29T18:49:08.090332Z","end":"2026-01-29T18:49:08.247063Z","steps":["trace[1101564124] 'process raft request' (duration: 156.665852ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:49:08.247165Z","caller":"traceutil/trace.go:171","msg":"trace[2079326508] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"157.842552ms","start":"2026-01-29T18:49:08.0893Z","end":"2026-01-29T18:49:08.247143Z","steps":["trace[2079326508] 'process raft request' (duration: 157.626036ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:49:08.247296Z","caller":"traceutil/trace.go:171","msg":"trace[2133946641] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"158.855069ms","start":"2026-01-29T18:49:08.088432Z","end":"2026-01-29T18:49:08.247288Z","steps":["trace[2133946641] 'process raft request' (duration: 95.133786ms)","trace[2133946641] 'compare' (duration: 63.164728ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:49:18.121004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.853911ms","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-01-29T18:49:18.121633Z","caller":"traceutil/trace.go:171","msg":"trace[1878650959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1227; }","duration":"172.539018ms","start":"2026-01-29T18:49:17.949072Z","end":"2026-01-29T18:49:18.121611Z","steps":["trace[1878650959] 'range keys from in-memory index tree' (duration: 171.644145ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:50:42.33179Z","caller":"traceutil/trace.go:171","msg":"trace[1181631515] transaction","detail":"{read_only:false; response_revision:1570; number_of_response:1; }","duration":"124.816626ms","start":"2026-01-29T18:50:42.206951Z","end":"2026-01-29T18:50:42.331768Z","steps":["trace[1181631515] 'process raft request' (duration: 115.357445ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:50:44.255435Z","caller":"traceutil/trace.go:171","msg":"trace[1025480787] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"120.326571ms","start":"2026-01-29T18:50:44.135071Z","end":"2026-01-29T18:50:44.255398Z","steps":["trace[1025480787] 'process raft request' (duration: 64.725336ms)","trace[1025480787] 'compare' (duration: 55.507983ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:51:41.51269Z","caller":"traceutil/trace.go:171","msg":"trace[531967313] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"152.0333ms","start":"2026-01-29T18:51:41.360626Z","end":"2026-01-29T18:51:41.512659Z","steps":["trace[531967313] 'process raft request' (duration: 60.492262ms)","trace[531967313] 'compare' (duration: 90.574483ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:51:41.512252Z","caller":"traceutil/trace.go:171","msg":"trace[1044268003] linearizableReadLoop","detail":"{readStateIndex:1915; appliedIndex:1914; }","duration":"148.686944ms","start":"2026-01-29T18:51:41.36355Z","end":"2026-01-29T18:51:41.512237Z","steps":["trace[1044268003] 'read index received' (duration: 57.558167ms)","trace[1044268003] 'applied index is now lower than readState.Index' (duration: 91.127777ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:51:41.513563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.943546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-01-29T18:51:41.513668Z","caller":"traceutil/trace.go:171","msg":"trace[190234005] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1835; }","duration":"150.11323ms","start":"2026-01-29T18:51:41.363546Z","end":"2026-01-29T18:51:41.513659Z","steps":["trace[190234005] 'agreement among raft nodes before linearized reading' (duration: 149.857434ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:51:45.041208Z","caller":"traceutil/trace.go:171","msg":"trace[938004339] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"214.616283ms","start":"2026-01-29T18:51:44.826566Z","end":"2026-01-29T18:51:45.041183Z","steps":["trace[938004339] 'process raft request' (duration: 214.303135ms)"],"step_count":1}