{"level":"warn","ts":"2026-02-12T16:55:17.847237Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-12T16:55:17.848651Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.69: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.69:2380","--initial-cluster=instance=https://199.204.45.69:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.69:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.69:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-12T16:55:17.859665Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-12T16:55:17.859881Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-02-12T16:55:17.860128Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-12T16:55:17.867508Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"]} {"level":"info","ts":"2026-02-12T16:55:17.867872Z","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.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.69:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-12T16:55:17.878319Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.523942ms"} {"level":"info","ts":"2026-02-12T16:55:17.904842Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fe334179879fd0aa","cluster-id":"d79a8cccc23ced73"} {"level":"info","ts":"2026-02-12T16:55:17.922564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T16:55:17.922727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 0"} {"level":"info","ts":"2026-02-12T16:55:17.922754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fe334179879fd0aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T16:55:17.922767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 1"} {"level":"info","ts":"2026-02-12T16:55:17.922903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"warn","ts":"2026-02-12T16:55:17.943671Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T16:55:17.948416Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T16:55:17.952444Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-12T16:55:17.957744Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fe334179879fd0aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T16:55:17.958451Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fe334179879fd0aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T16:55:17.958976Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-12T16:55:17.959075Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-12T16:55:17.960973Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-12T16:55:17.961539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"info","ts":"2026-02-12T16:55:17.961724Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","added-peer-id":"fe334179879fd0aa","added-peer-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-02-12T16:55:17.97014Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-12T16:55:17.970393Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-02-12T16:55:17.970416Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-02-12T16:55:17.971891Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fe334179879fd0aa","initial-advertise-peer-urls":["https://199.204.45.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T16:55:17.971963Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T16:55:18.323645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T16:55:18.323784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T16:55:18.323862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgPreVoteResp from fe334179879fd0aa at term 1"} {"level":"info","ts":"2026-02-12T16:55:18.323901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became candidate at term 2"} {"level":"info","ts":"2026-02-12T16:55:18.323926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgVoteResp from fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-02-12T16:55:18.323949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became leader at term 2"} {"level":"info","ts":"2026-02-12T16:55:18.323971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fe334179879fd0aa elected leader fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-02-12T16:55:18.327147Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fe334179879fd0aa","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.69:2379]}","request-path":"/0/members/fe334179879fd0aa/attributes","cluster-id":"d79a8cccc23ced73","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T16:55:18.327206Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T16:55:18.327809Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T16:55:18.327929Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T16:55:18.333996Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.69:2379"} {"level":"info","ts":"2026-02-12T16:55:18.334123Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T16:55:18.335371Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T16:55:18.336851Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T16:55:18.340774Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T16:55:18.341847Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T16:55:18.341902Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-12T16:55:39.761954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.94425ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-12T16:55:39.762376Z","caller":"traceutil/trace.go:171","msg":"trace[309624754] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"577.758109ms","start":"2026-02-12T16:55:39.18447Z","end":"2026-02-12T16:55:39.762228Z","steps":["trace[309624754] 'read index received' (duration: 195.661912ms)","trace[309624754] 'applied index is now lower than readState.Index' (duration: 382.082417ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:55:39.762994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.731659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4061"} {"level":"info","ts":"2026-02-12T16:55:39.763044Z","caller":"traceutil/trace.go:171","msg":"trace[629434737] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:431; }","duration":"408.117128ms","start":"2026-02-12T16:55:39.354906Z","end":"2026-02-12T16:55:39.763023Z","steps":["trace[629434737] 'agreement among raft nodes before linearized reading' (duration: 407.77701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:55:39.762999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.410715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-12T16:55:39.763128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:55:39.35488Z","time spent":"408.227011ms","remote":"127.0.0.1:47378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4085,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-12T16:55:39.763164Z","caller":"traceutil/trace.go:171","msg":"trace[358334785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:431; }","duration":"578.713723ms","start":"2026-02-12T16:55:39.184406Z","end":"2026-02-12T16:55:39.76312Z","steps":["trace[358334785] 'agreement among raft nodes before linearized reading' (duration: 577.970535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:55:39.76328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:55:39.184381Z","time spent":"578.885876ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-12T16:56:00.997994Z","caller":"traceutil/trace.go:171","msg":"trace[45571382] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"266.014863ms","start":"2026-02-12T16:56:00.731888Z","end":"2026-02-12T16:56:00.997903Z","steps":["trace[45571382] 'process raft request' (duration: 265.830939ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:56:04.015831Z","caller":"traceutil/trace.go:171","msg":"trace[21983079] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"335.770554ms","start":"2026-02-12T16:56:03.679946Z","end":"2026-02-12T16:56:04.015716Z","steps":["trace[21983079] 'process raft request' (duration: 329.709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:04.020647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:03.679921Z","time spent":"336.205003ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:56:04.437429Z","caller":"traceutil/trace.go:171","msg":"trace[1641348202] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"273.112742ms","start":"2026-02-12T16:56:04.164277Z","end":"2026-02-12T16:56:04.43739Z","steps":["trace[1641348202] 'process raft request' (duration: 272.918518ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:56:04.437791Z","caller":"traceutil/trace.go:171","msg":"trace[1109331728] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"302.755864ms","start":"2026-02-12T16:56:04.135025Z","end":"2026-02-12T16:56:04.437781Z","steps":["trace[1109331728] 'process raft request' (duration: 215.031361ms)","trace[1109331728] 'compare' (duration: 86.79509ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:56:04.437909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:04.134987Z","time spent":"302.827515ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:56:06.945042Z","caller":"traceutil/trace.go:171","msg":"trace[882959888] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"278.892419ms","start":"2026-02-12T16:56:06.666081Z","end":"2026-02-12T16:56:06.944974Z","steps":["trace[882959888] 'read index received' (duration: 278.550311ms)","trace[882959888] 'applied index is now lower than readState.Index' (duration: 340.278µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:56:06.945307Z","caller":"traceutil/trace.go:171","msg":"trace[1723691462] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"458.878682ms","start":"2026-02-12T16:56:06.486383Z","end":"2026-02-12T16:56:06.945262Z","steps":["trace[1723691462] 'process raft request' (duration: 458.188965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:06.945917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:06.48637Z","time spent":"459.006446ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:56:06.946299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.171709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T16:56:06.946409Z","caller":"traceutil/trace.go:171","msg":"trace[468470290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"280.373574ms","start":"2026-02-12T16:56:06.665995Z","end":"2026-02-12T16:56:06.946369Z","steps":["trace[468470290] 'agreement among raft nodes before linearized reading' (duration: 280.150709ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:56:07.051992Z","caller":"traceutil/trace.go:171","msg":"trace[1337152082] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"106.736882ms","start":"2026-02-12T16:56:06.945208Z","end":"2026-02-12T16:56:07.051945Z","steps":["trace[1337152082] 'read index received' (duration: 106.582028ms)","trace[1337152082] 'applied index is now lower than readState.Index' (duration: 153.364µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:56:07.052043Z","caller":"traceutil/trace.go:171","msg":"trace[844287239] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"236.096269ms","start":"2026-02-12T16:56:06.815901Z","end":"2026-02-12T16:56:07.051997Z","steps":["trace[844287239] 'process raft request' (duration: 235.811222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:07.052576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.593402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:56:07.05261Z","caller":"traceutil/trace.go:171","msg":"trace[1157914743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"322.707645ms","start":"2026-02-12T16:56:06.729895Z","end":"2026-02-12T16:56:07.052602Z","steps":["trace[1157914743] 'agreement among raft nodes before linearized reading' (duration: 322.628674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:07.052632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:06.729863Z","time spent":"322.760276ms","remote":"127.0.0.1:47214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:56:07.05352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.476969ms","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-02-12T16:56:07.053637Z","caller":"traceutil/trace.go:171","msg":"trace[355523851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"378.862951ms","start":"2026-02-12T16:56:06.674762Z","end":"2026-02-12T16:56:07.053624Z","steps":["trace[355523851] 'agreement among raft nodes before linearized reading' (duration: 377.326555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:07.053708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:06.674738Z","time spent":"378.958054ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T16:56:09.562224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036002185274501084,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T16:56:09.614286Z","caller":"traceutil/trace.go:171","msg":"trace[986685310] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"647.155431ms","start":"2026-02-12T16:56:08.967031Z","end":"2026-02-12T16:56:09.614186Z","steps":["trace[986685310] 'process raft request' (duration: 646.842213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:09.614787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:08.966993Z","time spent":"647.450128ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:56:10.046553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.637578ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-12T16:56:10.046651Z","caller":"traceutil/trace.go:171","msg":"trace[387160274] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"984.976273ms","start":"2026-02-12T16:56:09.061655Z","end":"2026-02-12T16:56:10.046631Z","steps":["trace[387160274] 'read index received' (duration: 553.452097ms)","trace[387160274] 'applied index is now lower than readState.Index' (duration: 431.522816ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:56:10.046855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.185558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-12T16:56:10.046856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.711445ms","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-02-12T16:56:10.046886Z","caller":"traceutil/trace.go:171","msg":"trace[1175094852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:565; }","duration":"985.27818ms","start":"2026-02-12T16:56:09.061591Z","end":"2026-02-12T16:56:10.046869Z","steps":["trace[1175094852] 'agreement among raft nodes before linearized reading' (duration: 985.133196ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:56:10.046891Z","caller":"traceutil/trace.go:171","msg":"trace[1318669651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:565; }","duration":"978.778496ms","start":"2026-02-12T16:56:09.068099Z","end":"2026-02-12T16:56:10.046878Z","steps":["trace[1318669651] 'agreement among raft nodes before linearized reading' (duration: 978.627342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:10.046915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:09.068081Z","time spent":"978.828027ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T16:56:10.046915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:09.061562Z","time spent":"985.347371ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T16:56:10.04705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.738737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:56:10.047068Z","caller":"traceutil/trace.go:171","msg":"trace[1604662873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"317.805368ms","start":"2026-02-12T16:56:09.729257Z","end":"2026-02-12T16:56:10.047062Z","steps":["trace[1604662873] 'agreement among raft nodes before linearized reading' (duration: 317.744627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:10.047091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:09.729232Z","time spent":"317.85443ms","remote":"127.0.0.1:47214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:56:10.047325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.766636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-46pfn\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-12T16:56:10.047346Z","caller":"traceutil/trace.go:171","msg":"trace[691982058] range","detail":"{range_begin:/registry/pods/kube-system/cilium-46pfn; range_end:; response_count:1; response_revision:565; }","duration":"639.865709ms","start":"2026-02-12T16:56:09.407473Z","end":"2026-02-12T16:56:10.047339Z","steps":["trace[691982058] 'agreement among raft nodes before linearized reading' (duration: 639.741306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:10.047363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:09.40745Z","time spent":"639.908839ms","remote":"127.0.0.1:47384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16676,"request content":"key:\"/registry/pods/kube-system/cilium-46pfn\" "} {"level":"warn","ts":"2026-02-12T16:56:10.047371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.199749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-12T16:56:10.047394Z","caller":"traceutil/trace.go:171","msg":"trace[380857067] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:565; }","duration":"966.253911ms","start":"2026-02-12T16:56:09.081133Z","end":"2026-02-12T16:56:10.047387Z","steps":["trace[380857067] 'agreement among raft nodes before linearized reading' (duration: 966.168849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:10.047412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:09.081121Z","time spent":"966.286751ms","remote":"127.0.0.1:47464","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":"warn","ts":"2026-02-12T16:56:15.016944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.55068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-12T16:56:15.017227Z","caller":"traceutil/trace.go:171","msg":"trace[1530511433] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:639; }","duration":"272.924989ms","start":"2026-02-12T16:56:14.744222Z","end":"2026-02-12T16:56:15.017147Z","steps":["trace[1530511433] 'range keys from in-memory index tree' (duration: 272.333975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:15.01766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.102032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:56:15.017681Z","caller":"traceutil/trace.go:171","msg":"trace[1075050035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"281.160823ms","start":"2026-02-12T16:56:14.736514Z","end":"2026-02-12T16:56:15.017675Z","steps":["trace[1075050035] 'range keys from in-memory index tree' (duration: 281.051281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:24.933471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036002185274501368,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T16:56:24.995916Z","caller":"traceutil/trace.go:171","msg":"trace[1126699318] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"616.632899ms","start":"2026-02-12T16:56:24.379223Z","end":"2026-02-12T16:56:24.995856Z","steps":["trace[1126699318] 'process raft request' (duration: 616.21454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:24.996286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:24.378919Z","time spent":"617.10752ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:56:25.011977Z","caller":"traceutil/trace.go:171","msg":"trace[612219063] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:726; }","duration":"579.557383ms","start":"2026-02-12T16:56:24.432399Z","end":"2026-02-12T16:56:25.011956Z","steps":["trace[612219063] 'read index received' (duration: 558.074935ms)","trace[612219063] 'applied index is now lower than readState.Index' (duration: 21.481938ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:56:25.012077Z","caller":"traceutil/trace.go:171","msg":"trace[1029388961] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"579.710007ms","start":"2026-02-12T16:56:24.432361Z","end":"2026-02-12T16:56:25.012071Z","steps":["trace[1029388961] 'process raft request' (duration: 579.40908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:25.012163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:24.432216Z","time spent":"579.875241ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:56:25.012287Z","caller":"traceutil/trace.go:171","msg":"trace[692510617] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"279.106235ms","start":"2026-02-12T16:56:24.733168Z","end":"2026-02-12T16:56:25.012274Z","steps":["trace[692510617] 'process raft request' (duration: 278.723706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:25.012502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.101327ms","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-02-12T16:56:25.012546Z","caller":"traceutil/trace.go:171","msg":"trace[686621536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:704; }","duration":"580.208719ms","start":"2026-02-12T16:56:24.432323Z","end":"2026-02-12T16:56:25.012532Z","steps":["trace[686621536] 'agreement among raft nodes before linearized reading' (duration: 580.006144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:25.012575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:24.432216Z","time spent":"580.347603ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T16:56:25.012594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.847443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:56:25.012656Z","caller":"traceutil/trace.go:171","msg":"trace[2116096631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:704; }","duration":"280.035756ms","start":"2026-02-12T16:56:24.732601Z","end":"2026-02-12T16:56:25.012636Z","steps":["trace[2116096631] 'agreement among raft nodes before linearized reading' (duration: 279.918244ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:56:32.539194Z","caller":"traceutil/trace.go:171","msg":"trace[1291717378] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"631.01902ms","start":"2026-02-12T16:56:31.908103Z","end":"2026-02-12T16:56:32.539122Z","steps":["trace[1291717378] 'process raft request' (duration: 630.876246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:32.540074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:31.908079Z","time spent":"631.44169ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:56:34.089547Z","caller":"traceutil/trace.go:171","msg":"trace[546835392] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"116.720247ms","start":"2026-02-12T16:56:33.972802Z","end":"2026-02-12T16:56:34.089523Z","steps":["trace[546835392] 'process raft request' (duration: 100.192707ms)","trace[546835392] 'compare' (duration: 16.133301ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:56:35.227391Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036002185274501501,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T16:56:35.728572Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036002185274501501,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T16:56:35.835389Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.28618401s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T16:56:35.835965Z","caller":"traceutil/trace.go:171","msg":"trace[2097199625] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"1.286839935s","start":"2026-02-12T16:56:34.549102Z","end":"2026-02-12T16:56:35.835942Z","steps":["trace[2097199625] 'process raft request' (duration: 1.286675061s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:35.836127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:34.549088Z","time spent":"1.286943937s","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:56:35.870354Z","caller":"traceutil/trace.go:171","msg":"trace[802510510] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"497.075824ms","start":"2026-02-12T16:56:35.373251Z","end":"2026-02-12T16:56:35.870327Z","steps":["trace[802510510] 'process raft request' (duration: 487.178531ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:56:35.870349Z","caller":"traceutil/trace.go:171","msg":"trace[1721670224] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"1.143257252s","start":"2026-02-12T16:56:34.726783Z","end":"2026-02-12T16:56:35.87004Z","steps":["trace[1721670224] 'read index received' (duration: 1.109894764s)","trace[1721670224] 'applied index is now lower than readState.Index' (duration: 33.361658ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:56:35.870558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143766574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-12T16:56:35.870564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:35.373227Z","time spent":"497.227618ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:56:35.87059Z","caller":"traceutil/trace.go:171","msg":"trace[211434937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"1.143835406s","start":"2026-02-12T16:56:34.726741Z","end":"2026-02-12T16:56:35.870577Z","steps":["trace[211434937] 'agreement among raft nodes before linearized reading' (duration: 1.143772754s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:35.87062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:34.726721Z","time spent":"1.143892928s","remote":"127.0.0.1:47214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:56:35.870852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.548728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-12T16:56:35.870874Z","caller":"traceutil/trace.go:171","msg":"trace[1675231823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:769; }","duration":"809.570189ms","start":"2026-02-12T16:56:35.061297Z","end":"2026-02-12T16:56:35.870868Z","steps":["trace[1675231823] 'agreement among raft nodes before linearized reading' (duration: 809.477077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:35.870921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:35.06125Z","time spent":"809.659701ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T16:56:35.871008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.039955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-12T16:56:35.871041Z","caller":"traceutil/trace.go:171","msg":"trace[522733775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:769; }","duration":"704.105337ms","start":"2026-02-12T16:56:35.166928Z","end":"2026-02-12T16:56:35.871033Z","steps":["trace[522733775] 'agreement among raft nodes before linearized reading' (duration: 703.964573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:35.871066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:35.16691Z","time spent":"704.149178ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T16:56:35.871067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.083494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T16:56:35.87111Z","caller":"traceutil/trace.go:171","msg":"trace[1937772531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"742.108204ms","start":"2026-02-12T16:56:35.128977Z","end":"2026-02-12T16:56:35.871085Z","steps":["trace[1937772531] 'agreement among raft nodes before linearized reading' (duration: 742.036102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:35.87113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:35.128936Z","time spent":"742.189466ms","remote":"127.0.0.1:47464","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-02-12T16:56:35.871258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.862238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-12T16:56:35.871276Z","caller":"traceutil/trace.go:171","msg":"trace[1919049158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:769; }","duration":"807.92058ms","start":"2026-02-12T16:56:35.06335Z","end":"2026-02-12T16:56:35.871271Z","steps":["trace[1919049158] 'agreement among raft nodes before linearized reading' (duration: 807.860878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:35.8713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:35.06334Z","time spent":"807.95585ms","remote":"127.0.0.1:47464","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":"warn","ts":"2026-02-12T16:56:52.472389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036002185274501637,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T16:56:52.595156Z","caller":"traceutil/trace.go:171","msg":"trace[842281415] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:848; }","duration":"623.477031ms","start":"2026-02-12T16:56:51.971614Z","end":"2026-02-12T16:56:52.595091Z","steps":["trace[842281415] 'read index received' (duration: 623.239605ms)","trace[842281415] 'applied index is now lower than readState.Index' (duration: 235.966µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:56:52.595332Z","caller":"traceutil/trace.go:171","msg":"trace[1091578] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"691.298754ms","start":"2026-02-12T16:56:51.904009Z","end":"2026-02-12T16:56:52.595308Z","steps":["trace[1091578] 'process raft request' (duration: 690.877684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:52.595607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:51.903996Z","time spent":"691.364855ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:56:52.595762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.042123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T16:56:52.595859Z","caller":"traceutil/trace.go:171","msg":"trace[1769714724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:818; }","duration":"607.202626ms","start":"2026-02-12T16:56:51.988628Z","end":"2026-02-12T16:56:52.59583Z","steps":["trace[1769714724] 'agreement among raft nodes before linearized reading' (duration: 606.92959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:52.595904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:51.988615Z","time spent":"607.281339ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T16:56:52.596071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.661408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:56:52.596119Z","caller":"traceutil/trace.go:171","msg":"trace[1068652263] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:818; }","duration":"199.781361ms","start":"2026-02-12T16:56:52.396318Z","end":"2026-02-12T16:56:52.596099Z","steps":["trace[1068652263] 'agreement among raft nodes before linearized reading' (duration: 199.706049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:52.596197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.739435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-12T16:56:52.596234Z","caller":"traceutil/trace.go:171","msg":"trace[1370863580] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:818; }","duration":"578.861888ms","start":"2026-02-12T16:56:52.017364Z","end":"2026-02-12T16:56:52.596226Z","steps":["trace[1370863580] 'agreement among raft nodes before linearized reading' (duration: 578.741775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:52.596286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:52.017327Z","time spent":"578.94452ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T16:56:52.596361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.738342ms","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-02-12T16:56:52.596386Z","caller":"traceutil/trace.go:171","msg":"trace[90648247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:818; }","duration":"624.806243ms","start":"2026-02-12T16:56:51.971573Z","end":"2026-02-12T16:56:52.596379Z","steps":["trace[90648247] 'agreement among raft nodes before linearized reading' (duration: 624.69381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:52.596413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:51.971553Z","time spent":"624.854764ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T16:56:52.596458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.229407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-12T16:56:52.596482Z","caller":"traceutil/trace.go:171","msg":"trace[707069844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:818; }","duration":"596.291999ms","start":"2026-02-12T16:56:52.000184Z","end":"2026-02-12T16:56:52.596476Z","steps":["trace[707069844] 'agreement among raft nodes before linearized reading' (duration: 596.188456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:52.596502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:52.000173Z","time spent":"596.324449ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T16:56:53.191911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.250033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:56:53.191992Z","caller":"traceutil/trace.go:171","msg":"trace[1376488400] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"585.938524ms","start":"2026-02-12T16:56:52.606039Z","end":"2026-02-12T16:56:53.191977Z","steps":["trace[1376488400] 'process raft request' (duration: 189.563978ms)","trace[1376488400] 'compare' (duration: 395.787532ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:56:53.192029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:52.605926Z","time spent":"586.089418ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:56:53.246059Z","caller":"traceutil/trace.go:171","msg":"trace[427494459] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:849; }","duration":"516.338151ms","start":"2026-02-12T16:56:52.729702Z","end":"2026-02-12T16:56:53.24604Z","steps":["trace[427494459] 'read index received' (duration: 68.872497ms)","trace[427494459] 'applied index is now lower than readState.Index' (duration: 447.465164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:56:53.246183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.467333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:56:53.246204Z","caller":"traceutil/trace.go:171","msg":"trace[678909341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:822; }","duration":"516.551675ms","start":"2026-02-12T16:56:52.729647Z","end":"2026-02-12T16:56:53.246198Z","steps":["trace[678909341] 'agreement among raft nodes before linearized reading' (duration: 516.461383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:53.246226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:52.729609Z","time spent":"516.613206ms","remote":"127.0.0.1:47214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T16:56:53.246471Z","caller":"traceutil/trace.go:171","msg":"trace[1129222495] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"639.614132ms","start":"2026-02-12T16:56:52.606846Z","end":"2026-02-12T16:56:53.24646Z","steps":["trace[1129222495] 'process raft request' (duration: 639.170982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:53.24655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:52.606841Z","time spent":"639.670913ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:56:53.246597Z","caller":"traceutil/trace.go:171","msg":"trace[253253758] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"640.446112ms","start":"2026-02-12T16:56:52.606147Z","end":"2026-02-12T16:56:53.246593Z","steps":["trace[253253758] 'process raft request' (duration: 639.755507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:53.246629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:52.606141Z","time spent":"640.468173ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:56:53.246676Z","caller":"traceutil/trace.go:171","msg":"trace[900321436] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"639.994761ms","start":"2026-02-12T16:56:52.606678Z","end":"2026-02-12T16:56:53.246673Z","steps":["trace[900321436] 'process raft request' (duration: 639.316946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:53.246699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:52.60667Z","time spent":"640.015981ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:56:55.085645Z","caller":"traceutil/trace.go:171","msg":"trace[110897017] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:855; }","duration":"357.912277ms","start":"2026-02-12T16:56:54.727713Z","end":"2026-02-12T16:56:55.085626Z","steps":["trace[110897017] 'read index received' (duration: 357.713842ms)","trace[110897017] 'applied index is now lower than readState.Index' (duration: 197.745µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:56:55.085837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.116041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:56:55.085866Z","caller":"traceutil/trace.go:171","msg":"trace[2082774954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:824; }","duration":"358.201723ms","start":"2026-02-12T16:56:54.727657Z","end":"2026-02-12T16:56:55.085859Z","steps":["trace[2082774954] 'agreement among raft nodes before linearized reading' (duration: 358.04672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:55.085892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:54.727638Z","time spent":"358.248135ms","remote":"127.0.0.1:47220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T16:56:55.086188Z","caller":"traceutil/trace.go:171","msg":"trace[1791852183] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"472.35724ms","start":"2026-02-12T16:56:54.613821Z","end":"2026-02-12T16:56:55.086178Z","steps":["trace[1791852183] 'process raft request' (duration: 471.686574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:55.086277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:54.613803Z","time spent":"472.428802ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:56:57.31489Z","caller":"traceutil/trace.go:171","msg":"trace[1028645778] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"209.237253ms","start":"2026-02-12T16:56:57.105623Z","end":"2026-02-12T16:56:57.31486Z","steps":["trace[1028645778] 'process raft request' (duration: 209.0806ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:56:57.317457Z","caller":"traceutil/trace.go:171","msg":"trace[913851068] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:862; }","duration":"113.461271ms","start":"2026-02-12T16:56:57.203965Z","end":"2026-02-12T16:56:57.317426Z","steps":["trace[913851068] 'read index received' (duration: 113.455691ms)","trace[913851068] 'applied index is now lower than readState.Index' (duration: 4.61µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:56:57.317837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.85526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-12T16:56:57.317963Z","caller":"traceutil/trace.go:171","msg":"trace[1808102888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:830; }","duration":"113.932971ms","start":"2026-02-12T16:56:57.203933Z","end":"2026-02-12T16:56:57.317866Z","steps":["trace[1808102888] 'agreement among raft nodes before linearized reading' (duration: 113.836649ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:56:57.694571Z","caller":"traceutil/trace.go:171","msg":"trace[1051513882] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"369.843459ms","start":"2026-02-12T16:56:57.324708Z","end":"2026-02-12T16:56:57.694551Z","steps":["trace[1051513882] 'process raft request' (duration: 369.559892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:57.69476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:57.324692Z","time spent":"369.94023ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:56:57.69829Z","caller":"traceutil/trace.go:171","msg":"trace[1196390597] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"372.066291ms","start":"2026-02-12T16:56:57.326212Z","end":"2026-02-12T16:56:57.698278Z","steps":["trace[1196390597] 'process raft request' (duration: 370.894034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:57.698363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:57.326202Z","time spent":"372.126513ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:56:57.699514Z","caller":"traceutil/trace.go:171","msg":"trace[870664815] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"373.229389ms","start":"2026-02-12T16:56:57.326272Z","end":"2026-02-12T16:56:57.699501Z","steps":["trace[870664815] 'process raft request' (duration: 370.926404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:57.699578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:57.326268Z","time spent":"373.27004ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:56:57.701425Z","caller":"traceutil/trace.go:171","msg":"trace[1688127129] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"372.180074ms","start":"2026-02-12T16:56:57.329234Z","end":"2026-02-12T16:56:57.701414Z","steps":["trace[1688127129] 'process raft request' (duration: 370.224608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:57.701486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:56:57.329221Z","time spent":"372.235515ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:56:57.703142Z","caller":"traceutil/trace.go:171","msg":"trace[2034327228] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:862; }","duration":"169.690129ms","start":"2026-02-12T16:56:57.533432Z","end":"2026-02-12T16:56:57.703122Z","steps":["trace[2034327228] 'read index received' (duration: 160.84449ms)","trace[2034327228] 'applied index is now lower than readState.Index' (duration: 8.844979ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:56:57.703421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.992666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:56:57.703509Z","caller":"traceutil/trace.go:171","msg":"trace[1044070706] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:835; }","duration":"170.084319ms","start":"2026-02-12T16:56:57.533415Z","end":"2026-02-12T16:56:57.703499Z","steps":["trace[1044070706] 'agreement among raft nodes before linearized reading' (duration: 169.947225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:56:57.704242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.758713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:56:57.704297Z","caller":"traceutil/trace.go:171","msg":"trace[1224732597] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:835; }","duration":"120.872596ms","start":"2026-02-12T16:56:57.583416Z","end":"2026-02-12T16:56:57.704289Z","steps":["trace[1224732597] 'agreement among raft nodes before linearized reading' (duration: 120.796854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:16.389131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036002185274501913,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T16:57:16.507778Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.022343014s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T16:57:16.508615Z","caller":"traceutil/trace.go:171","msg":"trace[62939503] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"1.023305598s","start":"2026-02-12T16:57:15.485287Z","end":"2026-02-12T16:57:16.508592Z","steps":["trace[62939503] 'process raft request' (duration: 1.022728914s)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:16.508458Z","caller":"traceutil/trace.go:171","msg":"trace[1937438073] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"619.809451ms","start":"2026-02-12T16:57:15.888512Z","end":"2026-02-12T16:57:16.508321Z","steps":["trace[1937438073] 'read index received' (duration: 619.3753ms)","trace[1937438073] 'applied index is now lower than readState.Index' (duration: 432.901µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:57:16.508857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:15.485274Z","time spent":"1.02338518s","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:57:16.509071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.485717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-12T16:57:16.509124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.458457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-12T16:57:16.509163Z","caller":"traceutil/trace.go:171","msg":"trace[198800386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:934; }","duration":"612.525159ms","start":"2026-02-12T16:57:15.896626Z","end":"2026-02-12T16:57:16.509152Z","steps":["trace[198800386] 'agreement among raft nodes before linearized reading' (duration: 612.411416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:16.509194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:15.896593Z","time spent":"612.59648ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-12T16:57:16.509169Z","caller":"traceutil/trace.go:171","msg":"trace[613582049] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:934; }","duration":"620.694432ms","start":"2026-02-12T16:57:15.888445Z","end":"2026-02-12T16:57:16.50914Z","steps":["trace[613582049] 'agreement among raft nodes before linearized reading' (duration: 620.299943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:16.509243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:15.888425Z","time spent":"620.807604ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T16:57:16.509298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.34967ms","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-02-12T16:57:16.509446Z","caller":"traceutil/trace.go:171","msg":"trace[1987695869] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"589.580346ms","start":"2026-02-12T16:57:15.919861Z","end":"2026-02-12T16:57:16.509441Z","steps":["trace[1987695869] 'agreement among raft nodes before linearized reading' (duration: 589.397691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:16.50947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:15.91985Z","time spent":"589.614967ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T16:57:16.509347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.092232ms","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-02-12T16:57:16.509517Z","caller":"traceutil/trace.go:171","msg":"trace[1976431338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"594.278647ms","start":"2026-02-12T16:57:15.915235Z","end":"2026-02-12T16:57:16.509514Z","steps":["trace[1976431338] 'agreement among raft nodes before linearized reading' (duration: 594.072422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:16.509534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:15.915227Z","time spent":"594.303338ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T16:57:16.931045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.224798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:57:16.931128Z","caller":"traceutil/trace.go:171","msg":"trace[1437511810] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"415.101212ms","start":"2026-02-12T16:57:16.516012Z","end":"2026-02-12T16:57:16.931113Z","steps":["trace[1437511810] 'process raft request' (duration: 234.707609ms)","trace[1437511810] 'compare' (duration: 179.691436ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:57:16.931181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:16.515998Z","time spent":"415.160134ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:57:17.428966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.853429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:57:17.429174Z","caller":"traceutil/trace.go:171","msg":"trace[323608752] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:971; }","duration":"703.127234ms","start":"2026-02-12T16:57:16.726034Z","end":"2026-02-12T16:57:17.429161Z","steps":["trace[323608752] 'read index received' (duration: 24.690916ms)","trace[323608752] 'applied index is now lower than readState.Index' (duration: 678.435848ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:57:17.429225Z","caller":"traceutil/trace.go:171","msg":"trace[457664668] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"904.875443ms","start":"2026-02-12T16:57:16.524345Z","end":"2026-02-12T16:57:17.42922Z","steps":["trace[457664668] 'process raft request' (duration: 904.684298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:17.429267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:16.524338Z","time spent":"904.906483ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:57:17.429254Z","caller":"traceutil/trace.go:171","msg":"trace[453900542] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"907.618688ms","start":"2026-02-12T16:57:16.521615Z","end":"2026-02-12T16:57:17.429234Z","steps":["trace[453900542] 'process raft request' (duration: 692.427171ms)","trace[453900542] 'compare' (duration: 214.497321ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:57:17.429369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:16.521602Z","time spent":"907.69934ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:57:17.429402Z","caller":"traceutil/trace.go:171","msg":"trace[1601520002] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"904.996116ms","start":"2026-02-12T16:57:16.5244Z","end":"2026-02-12T16:57:17.429397Z","steps":["trace[1601520002] 'process raft request' (duration: 904.694769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:17.429433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:16.524397Z","time spent":"905.021606ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:57:17.429609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.705438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T16:57:17.429627Z","caller":"traceutil/trace.go:171","msg":"trace[26030472] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:939; }","duration":"467.762409ms","start":"2026-02-12T16:57:16.961859Z","end":"2026-02-12T16:57:17.429621Z","steps":["trace[26030472] 'agreement among raft nodes before linearized reading' (duration: 467.638846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:17.429646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:16.961841Z","time spent":"467.79838ms","remote":"127.0.0.1:47304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-12T16:57:17.429707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.676537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:57:17.429738Z","caller":"traceutil/trace.go:171","msg":"trace[359435729] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"506.005015ms","start":"2026-02-12T16:57:16.923728Z","end":"2026-02-12T16:57:17.429733Z","steps":["trace[359435729] 'process raft request' (duration: 505.412451ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:17.429733Z","caller":"traceutil/trace.go:171","msg":"trace[1561054751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"703.734339ms","start":"2026-02-12T16:57:16.725991Z","end":"2026-02-12T16:57:17.429725Z","steps":["trace[1561054751] 'agreement among raft nodes before linearized reading' (duration: 703.479193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:17.429773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:16.923697Z","time spent":"506.055216ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:57:17.429903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:16.725974Z","time spent":"703.785811ms","remote":"127.0.0.1:47214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T16:57:20.728534Z","caller":"traceutil/trace.go:171","msg":"trace[287599432] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"198.750058ms","start":"2026-02-12T16:57:20.529766Z","end":"2026-02-12T16:57:20.728516Z","steps":["trace[287599432] 'process raft request' (duration: 198.612415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:21.162638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.949665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-12T16:57:21.162711Z","caller":"traceutil/trace.go:171","msg":"trace[1965037994] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:946; }","duration":"216.065718ms","start":"2026-02-12T16:57:20.946623Z","end":"2026-02-12T16:57:21.162689Z","steps":["trace[1965037994] 'range keys from in-memory index tree' (duration: 215.801702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:27.8738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.881742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:57:27.873873Z","caller":"traceutil/trace.go:171","msg":"trace[655788994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"148.010525ms","start":"2026-02-12T16:57:27.725846Z","end":"2026-02-12T16:57:27.873856Z","steps":["trace[655788994] 'range keys from in-memory index tree' (duration: 147.81194ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:29.639638Z","caller":"traceutil/trace.go:171","msg":"trace[482675067] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"137.259451ms","start":"2026-02-12T16:57:29.502359Z","end":"2026-02-12T16:57:29.639619Z","steps":["trace[482675067] 'process raft request' (duration: 137.076687ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:29.639904Z","caller":"traceutil/trace.go:171","msg":"trace[1775840464] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1011; }","duration":"131.519876ms","start":"2026-02-12T16:57:29.508376Z","end":"2026-02-12T16:57:29.639896Z","steps":["trace[1775840464] 'read index received' (duration: 131.517306ms)","trace[1775840464] 'applied index is now lower than readState.Index' (duration: 2.26µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:57:29.640016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.734145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T16:57:29.640036Z","caller":"traceutil/trace.go:171","msg":"trace[1807462923] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:971; }","duration":"124.781906ms","start":"2026-02-12T16:57:29.515249Z","end":"2026-02-12T16:57:29.640031Z","steps":["trace[1807462923] 'agreement among raft nodes before linearized reading' (duration: 124.691654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:29.640242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.864223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T16:57:29.640256Z","caller":"traceutil/trace.go:171","msg":"trace[517729253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:971; }","duration":"131.892863ms","start":"2026-02-12T16:57:29.508359Z","end":"2026-02-12T16:57:29.640252Z","steps":["trace[517729253] 'agreement among raft nodes before linearized reading' (duration: 131.828603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:32.369415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.10661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:57:32.369597Z","caller":"traceutil/trace.go:171","msg":"trace[224206675] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"627.508313ms","start":"2026-02-12T16:57:31.742064Z","end":"2026-02-12T16:57:32.369573Z","steps":["trace[224206675] 'process raft request' (duration: 522.164127ms)","trace[224206675] 'compare' (duration: 105.005168ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:57:32.36967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:31.742053Z","time spent":"627.586995ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:57:32.369749Z","caller":"traceutil/trace.go:171","msg":"trace[2138336121] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"624.926332ms","start":"2026-02-12T16:57:31.744797Z","end":"2026-02-12T16:57:32.369723Z","steps":["trace[2138336121] 'process raft request' (duration: 624.744607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:32.369878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:31.744779Z","time spent":"625.027044ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:57:45.194775Z","caller":"traceutil/trace.go:171","msg":"trace[134557966] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"238.725464ms","start":"2026-02-12T16:57:44.955969Z","end":"2026-02-12T16:57:45.194695Z","steps":["trace[134557966] 'process raft request' (duration: 238.452099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:45.727427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.07871ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:57:45.775247Z","caller":"traceutil/trace.go:171","msg":"trace[743871891] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1056; }","duration":"182.917013ms","start":"2026-02-12T16:57:45.592246Z","end":"2026-02-12T16:57:45.775163Z","steps":["trace[743871891] 'range keys from in-memory index tree' (duration: 135.055119ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:46.02151Z","caller":"traceutil/trace.go:171","msg":"trace[1488128765] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"146.711724ms","start":"2026-02-12T16:57:45.87475Z","end":"2026-02-12T16:57:46.021462Z","steps":["trace[1488128765] 'process raft request' (duration: 146.502729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:46.730932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.55434ms","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:508"} {"level":"info","ts":"2026-02-12T16:57:46.730998Z","caller":"traceutil/trace.go:171","msg":"trace[909978632] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1057; }","duration":"653.674883ms","start":"2026-02-12T16:57:46.077303Z","end":"2026-02-12T16:57:46.730978Z","steps":["trace[909978632] 'range keys from in-memory index tree' (duration: 653.379116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:46.731043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:46.077283Z","time spent":"653.742695ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-12T16:57:46.731384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.996904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T16:57:46.731411Z","caller":"traceutil/trace.go:171","msg":"trace[907675005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1057; }","duration":"291.091234ms","start":"2026-02-12T16:57:46.440307Z","end":"2026-02-12T16:57:46.731399Z","steps":["trace[907675005] 'range keys from in-memory index tree' (duration: 290.895791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:46.731539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.61636ms","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-02-12T16:57:46.731563Z","caller":"traceutil/trace.go:171","msg":"trace[1976154781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1057; }","duration":"259.66382ms","start":"2026-02-12T16:57:46.471893Z","end":"2026-02-12T16:57:46.731557Z","steps":["trace[1976154781] 'range keys from in-memory index tree' (duration: 259.524347ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:46.898728Z","caller":"traceutil/trace.go:171","msg":"trace[1781812525] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"145.482956ms","start":"2026-02-12T16:57:46.753214Z","end":"2026-02-12T16:57:46.898697Z","steps":["trace[1781812525] 'process raft request' (duration: 145.23587ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:46.89927Z","caller":"traceutil/trace.go:171","msg":"trace[1053014500] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"144.966905ms","start":"2026-02-12T16:57:46.754279Z","end":"2026-02-12T16:57:46.899245Z","steps":["trace[1053014500] 'process raft request' (duration: 144.78524ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:46.899765Z","caller":"traceutil/trace.go:171","msg":"trace[193969726] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"142.247409ms","start":"2026-02-12T16:57:46.757504Z","end":"2026-02-12T16:57:46.899751Z","steps":["trace[193969726] 'process raft request' (duration: 141.688057ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:47.703221Z","caller":"traceutil/trace.go:171","msg":"trace[1919553616] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"362.147056ms","start":"2026-02-12T16:57:47.34091Z","end":"2026-02-12T16:57:47.703057Z","steps":["trace[1919553616] 'process raft request' (duration: 361.390289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:47.703967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:47.340895Z","time spent":"362.643578ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:57:51.606916Z","caller":"traceutil/trace.go:171","msg":"trace[2026857070] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"377.017269ms","start":"2026-02-12T16:57:51.229826Z","end":"2026-02-12T16:57:51.606844Z","steps":["trace[2026857070] 'process raft request' (duration: 376.853955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:51.607599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:51.229788Z","time spent":"377.537332ms","remote":"127.0.0.1:47464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T17:01:26.519835Z","caller":"traceutil/trace.go:171","msg":"trace[1973795867] linearizableReadLoop","detail":"{readStateIndex:2092; appliedIndex:2091; }","duration":"165.664978ms","start":"2026-02-12T17:01:26.354087Z","end":"2026-02-12T17:01:26.519752Z","steps":["trace[1973795867] 'read index received' (duration: 165.259808ms)","trace[1973795867] 'applied index is now lower than readState.Index' (duration: 404.6µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:01:26.520274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.081998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T17:01:26.520314Z","caller":"traceutil/trace.go:171","msg":"trace[1060372114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2003; }","duration":"166.237172ms","start":"2026-02-12T17:01:26.354064Z","end":"2026-02-12T17:01:26.520301Z","steps":["trace[1060372114] 'agreement among raft nodes before linearized reading' (duration: 165.969446ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:01:26.520649Z","caller":"traceutil/trace.go:171","msg":"trace[148291539] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"180.578224ms","start":"2026-02-12T17:01:26.34005Z","end":"2026-02-12T17:01:26.520628Z","steps":["trace[148291539] 'process raft request' (duration: 179.382845ms)"],"step_count":1}