{"level":"warn","ts":"2026-01-19T22:10:06.761245Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-19T22:10:06.761433Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-01-19T22:10:06.761545Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-19T22:10:06.761574Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-01-19T22:10:06.761616Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-19T22:10:06.762337Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]} {"level":"info","ts":"2026-01-19T22:10:06.76245Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-01-19T22:10:06.770705Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.963389ms"} {"level":"info","ts":"2026-01-19T22:10:06.796754Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-01-19T22:10:06.796929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-01-19T22:10:06.797054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-01-19T22:10:06.797208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-19T22:10:06.797266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-01-19T22:10:06.797393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-01-19T22:10:06.815073Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-19T22:10:06.823242Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-19T22:10:06.832121Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-01-19T22:10:06.841761Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-19T22:10:06.842407Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-19T22:10:06.842575Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-19T22:10:06.842638Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-19T22:10:06.842657Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-19T22:10:06.844061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-01-19T22:10:06.86241Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-01-19T22:10:06.87308Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-19T22:10:06.873407Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-01-19T22:10:06.873453Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-01-19T22:10:06.87882Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-19T22:10:06.878899Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-19T22:10:07.498465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-01-19T22:10:07.498673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-19T22:10:07.498695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-01-19T22:10:07.498707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-01-19T22:10:07.498712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-01-19T22:10:07.498721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-01-19T22:10:07.498727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-01-19T22:10:07.50011Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"} {"level":"info","ts":"2026-01-19T22:10:07.500182Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-19T22:10:07.500539Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T22:10:07.500932Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-19T22:10:07.501319Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-19T22:10:07.501385Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-19T22:10:07.50444Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T22:10:07.504548Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T22:10:07.504578Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T22:10:07.50468Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-01-19T22:10:07.513828Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-19T22:10:17.930349Z","caller":"traceutil/trace.go:171","msg":"trace[2067276770] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"103.016841ms","start":"2026-01-19T22:10:17.827315Z","end":"2026-01-19T22:10:17.930332Z","steps":["trace[2067276770] 'process raft request' (duration: 102.902109ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:10:23.781251Z","caller":"traceutil/trace.go:171","msg":"trace[1977151846] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:384; }","duration":"389.316853ms","start":"2026-01-19T22:10:23.391906Z","end":"2026-01-19T22:10:23.781222Z","steps":["trace[1977151846] 'read index received' (duration: 389.036347ms)","trace[1977151846] 'applied index is now lower than readState.Index' (duration: 279.396µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:10:23.781683Z","caller":"traceutil/trace.go:171","msg":"trace[1057164811] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"432.086383ms","start":"2026-01-19T22:10:23.349588Z","end":"2026-01-19T22:10:23.781674Z","steps":["trace[1057164811] 'process raft request' (duration: 431.443519ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:23.783361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:23.349574Z","time spent":"432.135713ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:10:23.78362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.709484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-19T22:10:23.783905Z","caller":"traceutil/trace.go:171","msg":"trace[1902903293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:374; }","duration":"392.033741ms","start":"2026-01-19T22:10:23.39185Z","end":"2026-01-19T22:10:23.783883Z","steps":["trace[1902903293] 'agreement among raft nodes before linearized reading' (duration: 391.722455ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:23.783929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:23.39182Z","time spent":"392.103383ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-19T22:10:23.784082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.453878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-19T22:10:23.784099Z","caller":"traceutil/trace.go:171","msg":"trace[1861920301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:374; }","duration":"390.504199ms","start":"2026-01-19T22:10:23.393589Z","end":"2026-01-19T22:10:23.784093Z","steps":["trace[1861920301] 'agreement among raft nodes before linearized reading' (duration: 390.468788ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:23.784128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:23.393573Z","time spent":"390.55043ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-19T22:10:24.575351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.56359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T22:10:24.575515Z","caller":"traceutil/trace.go:171","msg":"trace[2029649515] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:385; }","duration":"616.99924ms","start":"2026-01-19T22:10:23.958497Z","end":"2026-01-19T22:10:24.575497Z","steps":["trace[2029649515] 'read index received' (duration: 266.011601ms)","trace[2029649515] 'applied index is now lower than readState.Index' (duration: 350.986759ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:10:24.57612Z","caller":"traceutil/trace.go:171","msg":"trace[1066237121] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"774.60002ms","start":"2026-01-19T22:10:23.801499Z","end":"2026-01-19T22:10:24.576099Z","steps":["trace[1066237121] 'process raft request' (duration: 422.996308ms)","trace[1066237121] 'compare' (duration: 350.454247ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:10:24.57619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:23.801448Z","time spent":"774.705132ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:10:24.57649Z","caller":"traceutil/trace.go:171","msg":"trace[978424595] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"773.789754ms","start":"2026-01-19T22:10:23.802682Z","end":"2026-01-19T22:10:24.576471Z","steps":["trace[978424595] 'process raft request' (duration: 772.764101ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:24.576584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:23.802651Z","time spent":"773.881636ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:10:24.576872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.376431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-19T22:10:24.576914Z","caller":"traceutil/trace.go:171","msg":"trace[1104347006] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:376; }","duration":"618.459252ms","start":"2026-01-19T22:10:23.958441Z","end":"2026-01-19T22:10:24.576901Z","steps":["trace[1104347006] 'agreement among raft nodes before linearized reading' (duration: 618.37965ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:24.576944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:23.958417Z","time spent":"618.521053ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-19T22:10:24.577102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.563381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:10:24.577128Z","caller":"traceutil/trace.go:171","msg":"trace[798413383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:376; }","duration":"589.619202ms","start":"2026-01-19T22:10:23.987501Z","end":"2026-01-19T22:10:24.57712Z","steps":["trace[798413383] 'agreement among raft nodes before linearized reading' (duration: 589.579711ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:24.577156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:23.987482Z","time spent":"589.668533ms","remote":"127.0.0.1:44712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-19T22:10:24.578667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.211075ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:10:24.578828Z","caller":"traceutil/trace.go:171","msg":"trace[1576743240] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:376; }","duration":"557.399379ms","start":"2026-01-19T22:10:24.021399Z","end":"2026-01-19T22:10:24.578799Z","steps":["trace[1576743240] 'agreement among raft nodes before linearized reading' (duration: 557.173594ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:10:25.017097Z","caller":"traceutil/trace.go:171","msg":"trace[1063520698] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"286.900291ms","start":"2026-01-19T22:10:24.730176Z","end":"2026-01-19T22:10:25.017076Z","steps":["trace[1063520698] 'process raft request' (duration: 286.866001ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:10:25.017445Z","caller":"traceutil/trace.go:171","msg":"trace[1715295059] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"292.49085ms","start":"2026-01-19T22:10:24.724944Z","end":"2026-01-19T22:10:25.017435Z","steps":["trace[1715295059] 'process raft request' (duration: 291.706553ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:10:25.01756Z","caller":"traceutil/trace.go:171","msg":"trace[1609294474] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"289.700291ms","start":"2026-01-19T22:10:24.727852Z","end":"2026-01-19T22:10:25.017552Z","steps":["trace[1609294474] 'process raft request' (duration: 289.105308ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:10:25.017748Z","caller":"traceutil/trace.go:171","msg":"trace[2134905328] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"289.793212ms","start":"2026-01-19T22:10:24.727946Z","end":"2026-01-19T22:10:25.017739Z","steps":["trace[2134905328] 'process raft request' (duration: 289.056087ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:10:39.154132Z","caller":"traceutil/trace.go:171","msg":"trace[624243082] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"359.535021ms","start":"2026-01-19T22:10:38.794578Z","end":"2026-01-19T22:10:39.154113Z","steps":["trace[624243082] 'process raft request' (duration: 359.374338ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:39.154271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:38.794566Z","time spent":"359.636784ms","remote":"127.0.0.1:44864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:10:39.154747Z","caller":"traceutil/trace.go:171","msg":"trace[1594729335] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"159.164193ms","start":"2026-01-19T22:10:38.995543Z","end":"2026-01-19T22:10:39.154708Z","steps":["trace[1594729335] 'read index received' (duration: 158.296333ms)","trace[1594729335] 'applied index is now lower than readState.Index' (duration: 867.09µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:10:39.154788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.242584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:10:39.15482Z","caller":"traceutil/trace.go:171","msg":"trace[1879188605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:475; }","duration":"159.307306ms","start":"2026-01-19T22:10:38.995492Z","end":"2026-01-19T22:10:39.154799Z","steps":["trace[1879188605] 'agreement among raft nodes before linearized reading' (duration: 159.273995ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:10:40.883394Z","caller":"traceutil/trace.go:171","msg":"trace[1738645684] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"209.582588ms","start":"2026-01-19T22:10:40.673797Z","end":"2026-01-19T22:10:40.88338Z","steps":["trace[1738645684] 'read index received' (duration: 209.451855ms)","trace[1738645684] 'applied index is now lower than readState.Index' (duration: 129.993µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:10:40.883735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.786115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-19T22:10:40.883763Z","caller":"traceutil/trace.go:171","msg":"trace[1974574545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"191.859687ms","start":"2026-01-19T22:10:40.691897Z","end":"2026-01-19T22:10:40.883757Z","steps":["trace[1974574545] 'agreement among raft nodes before linearized reading' (duration: 191.792376ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:40.883856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.064468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-19T22:10:40.883868Z","caller":"traceutil/trace.go:171","msg":"trace[1299530426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:477; }","duration":"210.102688ms","start":"2026-01-19T22:10:40.673762Z","end":"2026-01-19T22:10:40.883864Z","steps":["trace[1299530426] 'agreement among raft nodes before linearized reading' (duration: 210.079778ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:40.88393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.264751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-19T22:10:40.883944Z","caller":"traceutil/trace.go:171","msg":"trace[467079705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:477; }","duration":"189.297932ms","start":"2026-01-19T22:10:40.694641Z","end":"2026-01-19T22:10:40.883939Z","steps":["trace[467079705] 'agreement among raft nodes before linearized reading' (duration: 189.276681ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:41.249167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.925642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T22:10:41.249239Z","caller":"traceutil/trace.go:171","msg":"trace[392104890] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"361.241999ms","start":"2026-01-19T22:10:40.887985Z","end":"2026-01-19T22:10:41.249227Z","steps":["trace[392104890] 'process raft request' (duration: 140.203625ms)","trace[392104890] 'compare' (duration: 220.81485ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:10:41.249272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:40.887973Z","time spent":"361.285411ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:10:41.320941Z","caller":"traceutil/trace.go:171","msg":"trace[1125383312] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:492; }","duration":"334.930455ms","start":"2026-01-19T22:10:40.985993Z","end":"2026-01-19T22:10:41.320924Z","steps":["trace[1125383312] 'read index received' (duration: 42.200028ms)","trace[1125383312] 'applied index is now lower than readState.Index' (duration: 292.729907ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:10:41.321106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.102078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:10:41.321122Z","caller":"traceutil/trace.go:171","msg":"trace[1302513380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"335.156769ms","start":"2026-01-19T22:10:40.98596Z","end":"2026-01-19T22:10:41.321117Z","steps":["trace[1302513380] 'agreement among raft nodes before linearized reading' (duration: 335.068397ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:41.321141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:40.985933Z","time spent":"335.20371ms","remote":"127.0.0.1:44712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-19T22:10:41.32153Z","caller":"traceutil/trace.go:171","msg":"trace[1109068219] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"433.386251ms","start":"2026-01-19T22:10:40.888138Z","end":"2026-01-19T22:10:41.321524Z","steps":["trace[1109068219] 'process raft request' (duration: 432.628135ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:41.321588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:40.888134Z","time spent":"433.409612ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:10:41.321679Z","caller":"traceutil/trace.go:171","msg":"trace[1076754592] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"433.481123ms","start":"2026-01-19T22:10:40.888179Z","end":"2026-01-19T22:10:41.321671Z","steps":["trace[1076754592] 'process raft request' (duration: 432.679906ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:41.321712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:40.888175Z","time spent":"433.519014ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:10:41.321772Z","caller":"traceutil/trace.go:171","msg":"trace[915712074] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"433.114994ms","start":"2026-01-19T22:10:40.888652Z","end":"2026-01-19T22:10:41.321767Z","steps":["trace[915712074] 'process raft request' (duration: 432.247286ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:41.321794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:40.888632Z","time spent":"433.150216ms","remote":"127.0.0.1:44728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:10:42.193689Z","caller":"traceutil/trace.go:171","msg":"trace[2146133256] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"316.928226ms","start":"2026-01-19T22:10:41.876741Z","end":"2026-01-19T22:10:42.193669Z","steps":["trace[2146133256] 'process raft request' (duration: 316.821203ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:42.193798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:41.876719Z","time spent":"317.026667ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:10:47.487432Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228087883855909188,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-19T22:10:47.550967Z","caller":"traceutil/trace.go:171","msg":"trace[389151601] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"564.44852ms","start":"2026-01-19T22:10:46.986495Z","end":"2026-01-19T22:10:47.550944Z","steps":["trace[389151601] 'read index received' (duration: 564.242376ms)","trace[389151601] 'applied index is now lower than readState.Index' (duration: 205.234µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:10:47.551085Z","caller":"traceutil/trace.go:171","msg":"trace[589183951] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"820.956136ms","start":"2026-01-19T22:10:46.730109Z","end":"2026-01-19T22:10:47.551065Z","steps":["trace[589183951] 'process raft request' (duration: 820.70915ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:47.551205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:46.730093Z","time spent":"821.020157ms","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:10:47.551344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.846398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:10:47.551379Z","caller":"traceutil/trace.go:171","msg":"trace[410499058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"564.906549ms","start":"2026-01-19T22:10:46.986461Z","end":"2026-01-19T22:10:47.551367Z","steps":["trace[410499058] 'agreement among raft nodes before linearized reading' (duration: 564.855928ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:47.551406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:46.986446Z","time spent":"564.954491ms","remote":"127.0.0.1:44712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-19T22:10:47.551591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.50511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-19T22:10:47.551667Z","caller":"traceutil/trace.go:171","msg":"trace[639180433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:495; }","duration":"285.614783ms","start":"2026-01-19T22:10:47.266021Z","end":"2026-01-19T22:10:47.551636Z","steps":["trace[639180433] 'agreement among raft nodes before linearized reading' (duration: 285.514441ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:47.551711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.726846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-19T22:10:47.551747Z","caller":"traceutil/trace.go:171","msg":"trace[1914978235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"193.783418ms","start":"2026-01-19T22:10:47.357952Z","end":"2026-01-19T22:10:47.551735Z","steps":["trace[1914978235] 'agreement among raft nodes before linearized reading' (duration: 193.725657ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:47.551841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.143042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-19T22:10:47.551867Z","caller":"traceutil/trace.go:171","msg":"trace[1060212561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:495; }","duration":"198.206513ms","start":"2026-01-19T22:10:47.35365Z","end":"2026-01-19T22:10:47.551857Z","steps":["trace[1060212561] 'agreement among raft nodes before linearized reading' (duration: 198.167013ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:47.925862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.671657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T22:10:47.926209Z","caller":"traceutil/trace.go:171","msg":"trace[942434594] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"364.554151ms","start":"2026-01-19T22:10:47.561554Z","end":"2026-01-19T22:10:47.926109Z","steps":["trace[942434594] 'process raft request' (duration: 364.51183ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:10:47.926262Z","caller":"traceutil/trace.go:171","msg":"trace[292119343] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"366.690566ms","start":"2026-01-19T22:10:47.559534Z","end":"2026-01-19T22:10:47.926225Z","steps":["trace[292119343] 'process raft request' (duration: 91.58513ms)","trace[292119343] 'compare' (duration: 274.565015ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:10:47.926314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:47.561549Z","time spent":"364.700194ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:10:47.926443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:47.55952Z","time spent":"366.82431ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:10:47.926545Z","caller":"traceutil/trace.go:171","msg":"trace[1479977533] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"366.872411ms","start":"2026-01-19T22:10:47.559661Z","end":"2026-01-19T22:10:47.926533Z","steps":["trace[1479977533] 'read index received' (duration: 91.469888ms)","trace[1479977533] 'applied index is now lower than readState.Index' (duration: 275.401423ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:10:47.926632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.963163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:10:47.926658Z","caller":"traceutil/trace.go:171","msg":"trace[1186796148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"367.003803ms","start":"2026-01-19T22:10:47.559644Z","end":"2026-01-19T22:10:47.926648Z","steps":["trace[1186796148] 'agreement among raft nodes before linearized reading' (duration: 366.926011ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:47.926683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:47.559639Z","time spent":"367.039024ms","remote":"127.0.0.1:44720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-19T22:10:47.926894Z","caller":"traceutil/trace.go:171","msg":"trace[1196282265] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"366.777988ms","start":"2026-01-19T22:10:47.560107Z","end":"2026-01-19T22:10:47.926884Z","steps":["trace[1196282265] 'process raft request' (duration: 365.91232ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:47.927008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:47.560099Z","time spent":"366.819539ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:10:50.184607Z","caller":"traceutil/trace.go:171","msg":"trace[1297716886] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"253.653935ms","start":"2026-01-19T22:10:49.930927Z","end":"2026-01-19T22:10:50.184581Z","steps":["trace[1297716886] 'read index received' (duration: 208.830131ms)","trace[1297716886] 'applied index is now lower than readState.Index' (duration: 44.822844ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:10:50.184698Z","caller":"traceutil/trace.go:171","msg":"trace[421221584] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"269.525196ms","start":"2026-01-19T22:10:49.915163Z","end":"2026-01-19T22:10:50.184688Z","steps":["trace[421221584] 'process raft request' (duration: 224.644691ms)","trace[421221584] 'compare' (duration: 44.555618ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:10:50.184814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.836709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-19T22:10:50.184894Z","caller":"traceutil/trace.go:171","msg":"trace[1716568003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"253.969362ms","start":"2026-01-19T22:10:49.930905Z","end":"2026-01-19T22:10:50.184874Z","steps":["trace[1716568003] 'agreement among raft nodes before linearized reading' (duration: 253.785868ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:10:50.222815Z","caller":"traceutil/trace.go:171","msg":"trace[343504331] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"139.984031ms","start":"2026-01-19T22:10:50.082815Z","end":"2026-01-19T22:10:50.222799Z","steps":["trace[343504331] 'process raft request' (duration: 139.902518ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:50.223192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.797622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:10:50.223212Z","caller":"traceutil/trace.go:171","msg":"trace[515238608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"235.874683ms","start":"2026-01-19T22:10:49.987331Z","end":"2026-01-19T22:10:50.223206Z","steps":["trace[515238608] 'agreement among raft nodes before linearized reading' (duration: 235.834002ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:50.223301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.301885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-19T22:10:50.223315Z","caller":"traceutil/trace.go:171","msg":"trace[1571740049] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"291.325285ms","start":"2026-01-19T22:10:49.931986Z","end":"2026-01-19T22:10:50.223311Z","steps":["trace[1571740049] 'agreement among raft nodes before linearized reading' (duration: 291.294415ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:50.223378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.399557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-19T22:10:50.223389Z","caller":"traceutil/trace.go:171","msg":"trace[2113755286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:512; }","duration":"291.425727ms","start":"2026-01-19T22:10:49.93196Z","end":"2026-01-19T22:10:50.223386Z","steps":["trace[2113755286] 'agreement among raft nodes before linearized reading' (duration: 291.399867ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:01.69205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058551406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-19T22:11:01.69216Z","caller":"traceutil/trace.go:171","msg":"trace[69917086] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:646; }","duration":"1.05869272s","start":"2026-01-19T22:11:00.633455Z","end":"2026-01-19T22:11:01.692148Z","steps":["trace[69917086] 'range keys from in-memory index tree' (duration: 1.058483415s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:01.692186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:00.633441Z","time spent":"1.05873835s","remote":"127.0.0.1:44728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.209\" "} {"level":"warn","ts":"2026-01-19T22:11:01.692486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.668713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-vnmdl\" ","response":"range_response_count:1 size:4733"} {"level":"info","ts":"2026-01-19T22:11:01.692538Z","caller":"traceutil/trace.go:171","msg":"trace[975185937] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-vnmdl; range_end:; response_count:1; response_revision:646; }","duration":"862.754815ms","start":"2026-01-19T22:11:00.829771Z","end":"2026-01-19T22:11:01.692526Z","steps":["trace[975185937] 'range keys from in-memory index tree' (duration: 862.51811ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:01.692571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:00.829757Z","time spent":"862.809156ms","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4755,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-vnmdl\" "} {"level":"info","ts":"2026-01-19T22:11:02.239653Z","caller":"traceutil/trace.go:171","msg":"trace[310969014] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"540.071586ms","start":"2026-01-19T22:11:01.699565Z","end":"2026-01-19T22:11:02.239637Z","steps":["trace[310969014] 'process raft request' (duration: 539.894622ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:02.239746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:01.699551Z","time spent":"540.147977ms","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5008,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:11:02.290326Z","caller":"traceutil/trace.go:171","msg":"trace[2034770811] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:667; }","duration":"303.493277ms","start":"2026-01-19T22:11:01.986816Z","end":"2026-01-19T22:11:02.290309Z","steps":["trace[2034770811] 'read index received' (duration: 252.680384ms)","trace[2034770811] 'applied index is now lower than readState.Index' (duration: 50.812213ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:11:02.290397Z","caller":"traceutil/trace.go:171","msg":"trace[643940473] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"383.14123ms","start":"2026-01-19T22:11:01.90725Z","end":"2026-01-19T22:11:02.290391Z","steps":["trace[643940473] 'process raft request' (duration: 382.961436ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:02.290477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:01.907234Z","time spent":"383.182621ms","remote":"127.0.0.1:44728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:11:02.290692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.874395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:11:02.290715Z","caller":"traceutil/trace.go:171","msg":"trace[806333067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"303.932616ms","start":"2026-01-19T22:11:01.986776Z","end":"2026-01-19T22:11:02.290708Z","steps":["trace[806333067] 'agreement among raft nodes before linearized reading' (duration: 303.877815ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:02.290732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:01.98676Z","time spent":"303.968567ms","remote":"127.0.0.1:44720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-19T22:11:08.668447Z","caller":"traceutil/trace.go:171","msg":"trace[93114082] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"311.256954ms","start":"2026-01-19T22:11:08.357167Z","end":"2026-01-19T22:11:08.668424Z","steps":["trace[93114082] 'process raft request' (duration: 311.149301ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:08.668574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:08.357155Z","time spent":"311.353806ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:11:08.674681Z","caller":"traceutil/trace.go:171","msg":"trace[526202849] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:714; }","duration":"248.403822ms","start":"2026-01-19T22:11:08.426258Z","end":"2026-01-19T22:11:08.674661Z","steps":["trace[526202849] 'read index received' (duration: 242.002074ms)","trace[526202849] 'applied index is now lower than readState.Index' (duration: 6.400658ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:11:08.674768Z","caller":"traceutil/trace.go:171","msg":"trace[1652305196] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"316.062438ms","start":"2026-01-19T22:11:08.358697Z","end":"2026-01-19T22:11:08.67476Z","steps":["trace[1652305196] 'process raft request' (duration: 315.879074ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:08.674849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:08.358689Z","time spent":"316.097898ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:11:08.674952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.667493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-19T22:11:08.674995Z","caller":"traceutil/trace.go:171","msg":"trace[69593101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"192.733784ms","start":"2026-01-19T22:11:08.48225Z","end":"2026-01-19T22:11:08.674983Z","steps":["trace[69593101] 'agreement among raft nodes before linearized reading' (duration: 192.624612ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:08.675106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.8259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-19T22:11:08.675122Z","caller":"traceutil/trace.go:171","msg":"trace[78654210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:694; }","duration":"248.876651ms","start":"2026-01-19T22:11:08.42624Z","end":"2026-01-19T22:11:08.675116Z","steps":["trace[78654210] 'agreement among raft nodes before linearized reading' (duration: 248.80922ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:08.675232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.919718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-19T22:11:08.67526Z","caller":"traceutil/trace.go:171","msg":"trace[2128389678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"192.961509ms","start":"2026-01-19T22:11:08.482291Z","end":"2026-01-19T22:11:08.675252Z","steps":["trace[2128389678] 'agreement among raft nodes before linearized reading' (duration: 192.857387ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:26.437861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.171212ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-19T22:11:26.437944Z","caller":"traceutil/trace.go:171","msg":"trace[179599799] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:774; }","duration":"452.113173ms","start":"2026-01-19T22:11:25.985821Z","end":"2026-01-19T22:11:26.437934Z","steps":["trace[179599799] 'read index received' (duration: 8.81265ms)","trace[179599799] 'applied index is now lower than readState.Index' (duration: 443.299843ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:11:26.438001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.181165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:11:26.438014Z","caller":"traceutil/trace.go:171","msg":"trace[2097380494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"452.219615ms","start":"2026-01-19T22:11:25.985789Z","end":"2026-01-19T22:11:26.438009Z","steps":["trace[2097380494] 'agreement among raft nodes before linearized reading' (duration: 452.169334ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:26.438029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:25.985763Z","time spent":"452.262526ms","remote":"127.0.0.1:44712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-19T22:11:27.239869Z","caller":"traceutil/trace.go:171","msg":"trace[1501638239] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"442.6228ms","start":"2026-01-19T22:11:26.797226Z","end":"2026-01-19T22:11:27.239849Z","steps":["trace[1501638239] 'process raft request' (duration: 442.490747ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:27.239981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:26.797198Z","time spent":"442.725091ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:11:27.240339Z","caller":"traceutil/trace.go:171","msg":"trace[1702512806] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:776; }","duration":"441.569207ms","start":"2026-01-19T22:11:26.798761Z","end":"2026-01-19T22:11:27.24033Z","steps":["trace[1702512806] 'read index received' (duration: 441.566647ms)","trace[1702512806] 'applied index is now lower than readState.Index' (duration: 1.99µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:11:27.240442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.675559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-19T22:11:27.240462Z","caller":"traceutil/trace.go:171","msg":"trace[1928747205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:750; }","duration":"441.704591ms","start":"2026-01-19T22:11:26.798751Z","end":"2026-01-19T22:11:27.240455Z","steps":["trace[1928747205] 'agreement among raft nodes before linearized reading' (duration: 441.629239ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:27.24048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:26.798744Z","time spent":"441.731731ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-19T22:11:27.444715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.886237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T22:11:27.444841Z","caller":"traceutil/trace.go:171","msg":"trace[1988119127] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"199.644055ms","start":"2026-01-19T22:11:27.245184Z","end":"2026-01-19T22:11:27.444828Z","steps":["trace[1988119127] 'process raft request' (duration: 199.615224ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:27.44499Z","caller":"traceutil/trace.go:171","msg":"trace[1948471755] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"644.786948ms","start":"2026-01-19T22:11:26.800198Z","end":"2026-01-19T22:11:27.444985Z","steps":["trace[1948471755] 'process raft request' (duration: 465.584364ms)","trace[1948471755] 'compare' (duration: 178.704633ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:11:27.445017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:26.800187Z","time spent":"644.814509ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:11:27.445073Z","caller":"traceutil/trace.go:171","msg":"trace[427763787] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"204.705662ms","start":"2026-01-19T22:11:27.240363Z","end":"2026-01-19T22:11:27.445068Z","steps":["trace[427763787] 'read index received' (duration: 25.393596ms)","trace[427763787] 'applied index is now lower than readState.Index' (duration: 179.311616ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:11:27.445145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.958857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-19T22:11:27.445159Z","caller":"traceutil/trace.go:171","msg":"trace[345808032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:752; }","duration":"595.011868ms","start":"2026-01-19T22:11:26.85014Z","end":"2026-01-19T22:11:27.445152Z","steps":["trace[345808032] 'agreement among raft nodes before linearized reading' (duration: 594.942616ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:27.445171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:26.850125Z","time spent":"595.043748ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-19T22:11:27.44526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.675395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:11:27.445273Z","caller":"traceutil/trace.go:171","msg":"trace[399442466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"458.706875ms","start":"2026-01-19T22:11:26.986561Z","end":"2026-01-19T22:11:27.445268Z","steps":["trace[399442466] 'agreement among raft nodes before linearized reading' (duration: 458.686255ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:27.445287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:26.986546Z","time spent":"458.738197ms","remote":"127.0.0.1:44712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-19T22:11:27.445376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.298374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-19T22:11:27.445388Z","caller":"traceutil/trace.go:171","msg":"trace[738281380] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:752; }","duration":"589.311855ms","start":"2026-01-19T22:11:26.856072Z","end":"2026-01-19T22:11:27.445384Z","steps":["trace[738281380] 'agreement among raft nodes before linearized reading' (duration: 589.269964ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:27.445399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:26.856023Z","time spent":"589.373526ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-19T22:11:27.847603Z","caller":"traceutil/trace.go:171","msg":"trace[1111059167] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"399.493371ms","start":"2026-01-19T22:11:27.448091Z","end":"2026-01-19T22:11:27.847585Z","steps":["trace[1111059167] 'process raft request' (duration: 384.809826ms)","trace[1111059167] 'compare' (duration: 14.41733ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:11:27.8477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:27.448083Z","time spent":"399.568543ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:11:27.90398Z","caller":"traceutil/trace.go:171","msg":"trace[723137210] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:778; }","duration":"191.804696ms","start":"2026-01-19T22:11:27.712151Z","end":"2026-01-19T22:11:27.903956Z","steps":["trace[723137210] 'read index received' (duration: 120.755757ms)","trace[723137210] 'applied index is now lower than readState.Index' (duration: 71.043377ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:11:27.904125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.948279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:11:27.90417Z","caller":"traceutil/trace.go:171","msg":"trace[1673430694] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:754; }","duration":"192.042011ms","start":"2026-01-19T22:11:27.712113Z","end":"2026-01-19T22:11:27.904155Z","steps":["trace[1673430694] 'agreement among raft nodes before linearized reading' (duration: 191.929558ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:27.904529Z","caller":"traceutil/trace.go:171","msg":"trace[2053936148] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"455.395855ms","start":"2026-01-19T22:11:27.449116Z","end":"2026-01-19T22:11:27.904512Z","steps":["trace[2053936148] 'process raft request' (duration: 454.69857ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:27.904625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:27.44911Z","time spent":"455.455407ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:11:31.577315Z","caller":"traceutil/trace.go:171","msg":"trace[931373158] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"298.544686ms","start":"2026-01-19T22:11:31.27873Z","end":"2026-01-19T22:11:31.577275Z","steps":["trace[931373158] 'process raft request' (duration: 298.296111ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:31.846227Z","caller":"traceutil/trace.go:171","msg":"trace[354314981] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"423.595067ms","start":"2026-01-19T22:11:31.422583Z","end":"2026-01-19T22:11:31.846178Z","steps":["trace[354314981] 'read index received' (duration: 155.67026ms)","trace[354314981] 'applied index is now lower than readState.Index' (duration: 267.923807ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:11:31.846448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.830643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:11:31.846539Z","caller":"traceutil/trace.go:171","msg":"trace[1821190550] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:762; }","duration":"423.969105ms","start":"2026-01-19T22:11:31.422552Z","end":"2026-01-19T22:11:31.846521Z","steps":["trace[1821190550] 'agreement among raft nodes before linearized reading' (duration: 423.812932ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:31.846541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.192509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-19T22:11:31.846578Z","caller":"traceutil/trace.go:171","msg":"trace[968224974] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:762; }","duration":"389.259661ms","start":"2026-01-19T22:11:31.457309Z","end":"2026-01-19T22:11:31.846568Z","steps":["trace[968224974] 'agreement among raft nodes before linearized reading' (duration: 389.128589ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:31.846589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:31.422539Z","time spent":"424.036137ms","remote":"127.0.0.1:45000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-01-19T22:11:31.84661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:31.457298Z","time spent":"389.304732ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-19T22:11:31.846784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.168445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:11:31.84681Z","caller":"traceutil/trace.go:171","msg":"trace[483484422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"170.230056ms","start":"2026-01-19T22:11:31.676572Z","end":"2026-01-19T22:11:31.846802Z","steps":["trace[483484422] 'agreement among raft nodes before linearized reading' (duration: 170.189015ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:31.846828Z","caller":"traceutil/trace.go:171","msg":"trace[2076444775] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"542.143124ms","start":"2026-01-19T22:11:31.304671Z","end":"2026-01-19T22:11:31.846814Z","steps":["trace[2076444775] 'process raft request' (duration: 503.16802ms)","trace[2076444775] 'compare' (duration: 38.242549ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:11:31.846907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:31.304652Z","time spent":"542.202496ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:11:31.846984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.398692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-19T22:11:31.84701Z","caller":"traceutil/trace.go:171","msg":"trace[58061604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:762; }","duration":"387.424172ms","start":"2026-01-19T22:11:31.459578Z","end":"2026-01-19T22:11:31.847002Z","steps":["trace[58061604] 'agreement among raft nodes before linearized reading' (duration: 387.350261ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:31.847035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:31.459545Z","time spent":"387.485054ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-19T22:11:48.500568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228087883855909982,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-19T22:11:48.507951Z","caller":"traceutil/trace.go:171","msg":"trace[2143946082] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:890; }","duration":"511.77435ms","start":"2026-01-19T22:11:47.996145Z","end":"2026-01-19T22:11:48.507919Z","steps":["trace[2143946082] 'read index received' (duration: 511.580966ms)","trace[2143946082] 'applied index is now lower than readState.Index' (duration: 192.494µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:11:48.508236Z","caller":"traceutil/trace.go:171","msg":"trace[1595311855] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"541.650725ms","start":"2026-01-19T22:11:47.966577Z","end":"2026-01-19T22:11:48.508227Z","steps":["trace[1595311855] 'process raft request' (duration: 541.218235ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:48.508412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:47.966558Z","time spent":"541.696576ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:11:48.508587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.448724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:11:48.508611Z","caller":"traceutil/trace.go:171","msg":"trace[2037344574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:859; }","duration":"512.498686ms","start":"2026-01-19T22:11:47.996105Z","end":"2026-01-19T22:11:48.508603Z","steps":["trace[2037344574] 'agreement among raft nodes before linearized reading' (duration: 512.459445ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:48.508628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:47.996084Z","time spent":"512.540806ms","remote":"127.0.0.1:44712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-19T22:11:48.508848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.754904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-19T22:11:48.508866Z","caller":"traceutil/trace.go:171","msg":"trace[177695650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:859; }","duration":"448.774464ms","start":"2026-01-19T22:11:48.060086Z","end":"2026-01-19T22:11:48.50886Z","steps":["trace[177695650] 'agreement among raft nodes before linearized reading' (duration: 448.645481ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:48.508881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:48.060066Z","time spent":"448.811775ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-19T22:11:48.509027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.146794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-19T22:11:48.509044Z","caller":"traceutil/trace.go:171","msg":"trace[1653764379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:859; }","duration":"384.174685ms","start":"2026-01-19T22:11:48.124864Z","end":"2026-01-19T22:11:48.509039Z","steps":["trace[1653764379] 'agreement among raft nodes before linearized reading' (duration: 384.119953ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:48.509072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:48.124857Z","time spent":"384.210495ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-19T22:11:48.509171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.196473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-19T22:11:48.509189Z","caller":"traceutil/trace.go:171","msg":"trace[1897482290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:859; }","duration":"393.222113ms","start":"2026-01-19T22:11:48.115958Z","end":"2026-01-19T22:11:48.509181Z","steps":["trace[1897482290] 'agreement among raft nodes before linearized reading' (duration: 393.173672ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:48.509204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:48.115953Z","time spent":"393.246604ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-19T22:11:48.509318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.278812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-19T22:11:48.509335Z","caller":"traceutil/trace.go:171","msg":"trace[41880015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:859; }","duration":"424.294613ms","start":"2026-01-19T22:11:48.085033Z","end":"2026-01-19T22:11:48.509328Z","steps":["trace[41880015] 'agreement among raft nodes before linearized reading' (duration: 424.242461ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:48.50935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:48.084983Z","time spent":"424.363855ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-19T22:11:48.819208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.674752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T22:11:48.819316Z","caller":"traceutil/trace.go:171","msg":"trace[1995531982] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"291.06269ms","start":"2026-01-19T22:11:48.528234Z","end":"2026-01-19T22:11:48.819296Z","steps":["trace[1995531982] 'process raft request' (duration: 101.208764ms)","trace[1995531982] 'compare' (duration: 189.364165ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:11:49.10002Z","caller":"traceutil/trace.go:171","msg":"trace[995828697] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"512.419732ms","start":"2026-01-19T22:11:48.587575Z","end":"2026-01-19T22:11:49.099995Z","steps":["trace[995828697] 'process raft request' (duration: 512.382221ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:49.100141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:48.58757Z","time spent":"512.502354ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:11:49.100289Z","caller":"traceutil/trace.go:171","msg":"trace[2134589101] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"512.824681ms","start":"2026-01-19T22:11:48.587458Z","end":"2026-01-19T22:11:49.100283Z","steps":["trace[2134589101] 'process raft request' (duration: 414.286833ms)","trace[2134589101] 'compare' (duration: 98.021217ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:11:49.100322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:48.587433Z","time spent":"512.873001ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:11:49.100386Z","caller":"traceutil/trace.go:171","msg":"trace[293283061] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"512.841851ms","start":"2026-01-19T22:11:48.587538Z","end":"2026-01-19T22:11:49.10038Z","steps":["trace[293283061] 'process raft request' (duration: 512.384181ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:49.100411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:48.587533Z","time spent":"512.862811ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:11:50.811352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.750451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-19T22:11:50.811464Z","caller":"traceutil/trace.go:171","msg":"trace[1294342839] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:864; }","duration":"174.927195ms","start":"2026-01-19T22:11:50.636512Z","end":"2026-01-19T22:11:50.811439Z","steps":["trace[1294342839] 'range keys from in-memory index tree' (duration: 174.521776ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:51.390775Z","caller":"traceutil/trace.go:171","msg":"trace[592391538] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"283.144655ms","start":"2026-01-19T22:11:51.107608Z","end":"2026-01-19T22:11:51.390753Z","steps":["trace[592391538] 'process raft request' (duration: 282.95686ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:51.467332Z","caller":"traceutil/trace.go:171","msg":"trace[2033241259] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:899; }","duration":"350.408849ms","start":"2026-01-19T22:11:51.116897Z","end":"2026-01-19T22:11:51.467306Z","steps":["trace[2033241259] 'read index received' (duration: 273.709517ms)","trace[2033241259] 'applied index is now lower than readState.Index' (duration: 76.697722ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:11:51.467595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.670325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-19T22:11:51.467615Z","caller":"traceutil/trace.go:171","msg":"trace[1922362293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:868; }","duration":"350.722936ms","start":"2026-01-19T22:11:51.116886Z","end":"2026-01-19T22:11:51.467609Z","steps":["trace[1922362293] 'agreement among raft nodes before linearized reading' (duration: 350.515511ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:51.467639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:51.11688Z","time spent":"350.754826ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-19T22:11:51.467778Z","caller":"traceutil/trace.go:171","msg":"trace[1180075787] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"359.55363ms","start":"2026-01-19T22:11:51.108187Z","end":"2026-01-19T22:11:51.46774Z","steps":["trace[1180075787] 'process raft request' (duration: 358.996329ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:51.467918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:51.108181Z","time spent":"359.666752ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:11:52.793626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.237983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T22:11:52.793703Z","caller":"traceutil/trace.go:171","msg":"trace[2002254038] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:870; }","duration":"459.341955ms","start":"2026-01-19T22:11:52.334343Z","end":"2026-01-19T22:11:52.793685Z","steps":["trace[2002254038] 'count revisions from in-memory index tree' (duration: 459.120971ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:52.793733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:52.334267Z","time spent":"459.458788ms","remote":"127.0.0.1:45186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-01-19T22:11:52.793935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.925182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-19T22:11:52.794041Z","caller":"traceutil/trace.go:171","msg":"trace[1721314657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:870; }","duration":"211.012488ms","start":"2026-01-19T22:11:52.583009Z","end":"2026-01-19T22:11:52.794021Z","steps":["trace[1721314657] 'range keys from in-memory index tree' (duration: 205.735759ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:53.19792Z","caller":"traceutil/trace.go:171","msg":"trace[1153745496] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:903; }","duration":"314.373317ms","start":"2026-01-19T22:11:52.883514Z","end":"2026-01-19T22:11:53.197887Z","steps":["trace[1153745496] 'read index received' (duration: 314.154263ms)","trace[1153745496] 'applied index is now lower than readState.Index' (duration: 217.774µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:11:53.198088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.567851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-19T22:11:53.198106Z","caller":"traceutil/trace.go:171","msg":"trace[1179109853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:871; }","duration":"314.638153ms","start":"2026-01-19T22:11:52.883462Z","end":"2026-01-19T22:11:53.198101Z","steps":["trace[1179109853] 'agreement among raft nodes before linearized reading' (duration: 314.51864ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:53.198142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:52.883439Z","time spent":"314.695335ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-01-19T22:11:53.19822Z","caller":"traceutil/trace.go:171","msg":"trace[192336261] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"389.108358ms","start":"2026-01-19T22:11:52.809101Z","end":"2026-01-19T22:11:53.198209Z","steps":["trace[192336261] 'process raft request' (duration: 388.602978ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:53.198307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:52.809077Z","time spent":"389.154469ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:11:54.703543Z","caller":"traceutil/trace.go:171","msg":"trace[163896345] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"120.005027ms","start":"2026-01-19T22:11:54.583509Z","end":"2026-01-19T22:11:54.703514Z","steps":["trace[163896345] 'process raft request' (duration: 119.824593ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:12:04.883728Z","caller":"traceutil/trace.go:171","msg":"trace[523561050] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"161.988823ms","start":"2026-01-19T22:12:04.721699Z","end":"2026-01-19T22:12:04.883687Z","steps":["trace[523561050] 'process raft request' (duration: 161.796879ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:12:05.123269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.129716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-19T22:12:05.123283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.68527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T22:12:05.123345Z","caller":"traceutil/trace.go:171","msg":"trace[316570510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"138.259689ms","start":"2026-01-19T22:12:04.985066Z","end":"2026-01-19T22:12:05.123325Z","steps":["trace[316570510] 'range keys from in-memory index tree' (duration: 138.057855ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:12:05.123355Z","caller":"traceutil/trace.go:171","msg":"trace[1814340272] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:904; }","duration":"208.821713ms","start":"2026-01-19T22:12:04.914517Z","end":"2026-01-19T22:12:05.123339Z","steps":["trace[1814340272] 'count revisions from in-memory index tree' (duration: 208.582217ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:12:05.612098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.888223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-19T22:12:05.612271Z","caller":"traceutil/trace.go:171","msg":"trace[893054842] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:905; }","duration":"366.075737ms","start":"2026-01-19T22:12:05.246182Z","end":"2026-01-19T22:12:05.612257Z","steps":["trace[893054842] 'agreement among raft nodes before linearized reading' (duration: 90.839398ms)","trace[893054842] 'range keys from in-memory index tree' (duration: 275.019225ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:12:05.612309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:12:05.246176Z","time spent":"366.125708ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-19T22:12:05.612576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.003153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-19T22:12:05.612683Z","caller":"traceutil/trace.go:171","msg":"trace[1472680699] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:905; }","duration":"127.145646ms","start":"2026-01-19T22:12:05.48553Z","end":"2026-01-19T22:12:05.612676Z","steps":["trace[1472680699] 'range keys from in-memory index tree' (duration: 126.787968ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:12:05.718548Z","caller":"traceutil/trace.go:171","msg":"trace[1286787818] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"100.881307ms","start":"2026-01-19T22:12:05.6176Z","end":"2026-01-19T22:12:05.718481Z","steps":["trace[1286787818] 'process raft request' (duration: 100.750004ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:12:05.74746Z","caller":"traceutil/trace.go:171","msg":"trace[1212827620] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"129.639298ms","start":"2026-01-19T22:12:05.617795Z","end":"2026-01-19T22:12:05.747434Z","steps":["trace[1212827620] 'process raft request' (duration: 129.493675ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:12:05.747662Z","caller":"traceutil/trace.go:171","msg":"trace[894291779] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"127.773689ms","start":"2026-01-19T22:12:05.619877Z","end":"2026-01-19T22:12:05.74765Z","steps":["trace[894291779] 'process raft request' (duration: 127.526373ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:12:06.25465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.553616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T22:12:06.255808Z","caller":"traceutil/trace.go:171","msg":"trace[1263800420] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"443.110615ms","start":"2026-01-19T22:12:05.812679Z","end":"2026-01-19T22:12:06.25579Z","steps":["trace[1263800420] 'process raft request' (duration: 256.351814ms)","trace[1263800420] 'compare' (duration: 185.438023ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:12:06.255911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:12:05.812666Z","time spent":"443.212337ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:12:06.256203Z","caller":"traceutil/trace.go:171","msg":"trace[575386439] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"289.028977ms","start":"2026-01-19T22:12:05.967165Z","end":"2026-01-19T22:12:06.256194Z","steps":["trace[575386439] 'read index received' (duration: 101.944359ms)","trace[575386439] 'applied index is now lower than readState.Index' (duration: 187.083568ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:12:06.702111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.356033ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-19T22:12:06.702263Z","caller":"traceutil/trace.go:171","msg":"trace[30403753] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"445.883573ms","start":"2026-01-19T22:12:06.256354Z","end":"2026-01-19T22:12:06.702237Z","steps":["trace[30403753] 'read index received' (duration: 114.342µs)","trace[30403753] 'applied index is now lower than readState.Index' (duration: 445.767281ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:12:06.702379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.0163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:12:06.702434Z","caller":"traceutil/trace.go:171","msg":"trace[468501470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:909; }","duration":"710.101572ms","start":"2026-01-19T22:12:05.992303Z","end":"2026-01-19T22:12:06.702404Z","steps":["trace[468501470] 'agreement among raft nodes before linearized reading' (duration: 709.99848ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:12:06.702479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:12:05.992281Z","time spent":"710.186064ms","remote":"127.0.0.1:44712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-19T22:12:06.702999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.83067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T22:12:06.70304Z","caller":"traceutil/trace.go:171","msg":"trace[1040139338] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:909; }","duration":"735.871481ms","start":"2026-01-19T22:12:05.967155Z","end":"2026-01-19T22:12:06.703026Z","steps":["trace[1040139338] 'agreement among raft nodes before linearized reading' (duration: 289.264411ms)","trace[1040139338] 'count revisions from in-memory index tree' (duration: 446.512358ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:12:06.703071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:12:05.967108Z","time spent":"735.954942ms","remote":"127.0.0.1:44848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-01-19T22:12:06.703825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.110485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:12:06.703867Z","caller":"traceutil/trace.go:171","msg":"trace[1552810960] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:909; }","duration":"499.176976ms","start":"2026-01-19T22:12:06.204674Z","end":"2026-01-19T22:12:06.703851Z","steps":["trace[1552810960] 'agreement among raft nodes before linearized reading' (duration: 499.092895ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:12:06.703901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:12:06.204659Z","time spent":"499.233647ms","remote":"127.0.0.1:58780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-01-19T22:12:09.734376Z","caller":"traceutil/trace.go:171","msg":"trace[735391138] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"351.697216ms","start":"2026-01-19T22:12:09.382644Z","end":"2026-01-19T22:12:09.734341Z","steps":["trace[735391138] 'process raft request' (duration: 350.770156ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:12:09.734535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:12:09.382626Z","time spent":"351.828528ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":786,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-19T22:12:09.735798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.958901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-19T22:12:09.735846Z","caller":"traceutil/trace.go:171","msg":"trace[2029010691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:921; }","duration":"353.029823ms","start":"2026-01-19T22:12:09.382804Z","end":"2026-01-19T22:12:09.735834Z","steps":["trace[2029010691] 'agreement among raft nodes before linearized reading' (duration: 352.780988ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:12:09.735877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:12:09.382799Z","time spent":"353.071334ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-19T22:12:09.737575Z","caller":"traceutil/trace.go:171","msg":"trace[762844851] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"350.734735ms","start":"2026-01-19T22:12:09.382823Z","end":"2026-01-19T22:12:09.733557Z","steps":["trace[762844851] 'read index received' (duration: 350.528941ms)","trace[762844851] 'applied index is now lower than readState.Index' (duration: 204.734µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:12:09.73793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.966773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T22:12:09.738016Z","caller":"traceutil/trace.go:171","msg":"trace[1414749087] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:921; }","duration":"218.162077ms","start":"2026-01-19T22:12:09.519835Z","end":"2026-01-19T22:12:09.737997Z","steps":["trace[1414749087] 'agreement among raft nodes before linearized reading' (duration: 217.960913ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:12:09.975321Z","caller":"traceutil/trace.go:171","msg":"trace[1355649039] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"227.157006ms","start":"2026-01-19T22:12:09.748136Z","end":"2026-01-19T22:12:09.975293Z","steps":["trace[1355649039] 'process raft request' (duration: 201.461977ms)","trace[1355649039] 'compare' (duration: 25.253339ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:12:09.977868Z","caller":"traceutil/trace.go:171","msg":"trace[2016564232] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:957; }","duration":"214.709386ms","start":"2026-01-19T22:12:09.763138Z","end":"2026-01-19T22:12:09.977848Z","steps":["trace[2016564232] 'read index received' (duration: 186.357512ms)","trace[2016564232] 'applied index is now lower than readState.Index' (duration: 28.351004ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:12:09.97809Z","caller":"traceutil/trace.go:171","msg":"trace[317753856] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"228.054795ms","start":"2026-01-19T22:12:09.750029Z","end":"2026-01-19T22:12:09.978083Z","steps":["trace[317753856] 'process raft request' (duration: 227.644765ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:12:09.978235Z","caller":"traceutil/trace.go:171","msg":"trace[863907564] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"209.004287ms","start":"2026-01-19T22:12:09.769227Z","end":"2026-01-19T22:12:09.978231Z","steps":["trace[863907564] 'process raft request' (duration: 208.575138ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:12:09.97842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.262137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-19T22:12:09.97844Z","caller":"traceutil/trace.go:171","msg":"trace[1355182087] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:924; }","duration":"215.352669ms","start":"2026-01-19T22:12:09.763082Z","end":"2026-01-19T22:12:09.978434Z","steps":["trace[1355182087] 'agreement among raft nodes before linearized reading' (duration: 215.224676ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:12:09.978543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.287607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-19T22:12:09.978558Z","caller":"traceutil/trace.go:171","msg":"trace[1733291158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"214.313047ms","start":"2026-01-19T22:12:09.764238Z","end":"2026-01-19T22:12:09.978551Z","steps":["trace[1733291158] 'agreement among raft nodes before linearized reading' (duration: 214.271167ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:12:14.295201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.894449ms","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-01-19T22:12:14.295279Z","caller":"traceutil/trace.go:171","msg":"trace[1755553229] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:946; }","duration":"158.019891ms","start":"2026-01-19T22:12:14.137247Z","end":"2026-01-19T22:12:14.295267Z","steps":["trace[1755553229] 'count revisions from in-memory index tree' (duration: 157.792017ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:12:24.397188Z","caller":"traceutil/trace.go:171","msg":"trace[2033439456] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"294.054902ms","start":"2026-01-19T22:12:24.103021Z","end":"2026-01-19T22:12:24.397076Z","steps":["trace[2033439456] 'process raft request' (duration: 293.829067ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:12:24.814782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.433997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T22:12:24.8149Z","caller":"traceutil/trace.go:171","msg":"trace[1154330054] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"707.677802ms","start":"2026-01-19T22:12:24.107202Z","end":"2026-01-19T22:12:24.81488Z","steps":["trace[1154330054] 'read index received' (duration: 289.912845ms)","trace[1154330054] 'applied index is now lower than readState.Index' (duration: 417.763337ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:12:24.817061Z","caller":"traceutil/trace.go:171","msg":"trace[999532151] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"711.506622ms","start":"2026-01-19T22:12:24.104559Z","end":"2026-01-19T22:12:24.816065Z","steps":["trace[999532151] 'process raft request' (duration: 362.673406ms)","trace[999532151] 'compare' (duration: 347.124721ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:12:24.817286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:12:24.104543Z","time spent":"712.574525ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:12:24.817936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.728736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-19T22:12:24.817986Z","caller":"traceutil/trace.go:171","msg":"trace[539839809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1020; }","duration":"710.794897ms","start":"2026-01-19T22:12:24.107175Z","end":"2026-01-19T22:12:24.81797Z","steps":["trace[539839809] 'agreement among raft nodes before linearized reading' (duration: 710.598763ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:12:24.818131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:12:24.107164Z","time spent":"710.95455ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-19T22:12:24.819623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.786512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-19T22:12:24.81967Z","caller":"traceutil/trace.go:171","msg":"trace[1842640070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1020; }","duration":"649.871864ms","start":"2026-01-19T22:12:24.169787Z","end":"2026-01-19T22:12:24.819659Z","steps":["trace[1842640070] 'agreement among raft nodes before linearized reading' (duration: 649.758782ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:12:24.819791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:12:24.169767Z","time spent":"650.009836ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-19T22:12:24.820393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.50568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-19T22:12:24.820437Z","caller":"traceutil/trace.go:171","msg":"trace[865974744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"460.634033ms","start":"2026-01-19T22:12:24.359793Z","end":"2026-01-19T22:12:24.820427Z","steps":["trace[865974744] 'agreement among raft nodes before linearized reading' (duration: 460.49362ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:12:24.820647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:12:24.35977Z","time spent":"460.864827ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-19T22:12:25.049541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.463507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T22:12:25.049797Z","caller":"traceutil/trace.go:171","msg":"trace[608351905] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1059; }","duration":"192.040891ms","start":"2026-01-19T22:12:24.857739Z","end":"2026-01-19T22:12:25.04978Z","steps":["trace[608351905] 'read index received' (duration: 70.193056ms)","trace[608351905] 'applied index is now lower than readState.Index' (duration: 121.846785ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:12:25.049788Z","caller":"traceutil/trace.go:171","msg":"trace[1253650450] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"220.561076ms","start":"2026-01-19T22:12:24.829182Z","end":"2026-01-19T22:12:25.049743Z","steps":["trace[1253650450] 'process raft request' (duration: 98.909005ms)","trace[1253650450] 'compare' (duration: 121.1144ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:12:25.050031Z","caller":"traceutil/trace.go:171","msg":"trace[1510434714] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"218.710178ms","start":"2026-01-19T22:12:24.831312Z","end":"2026-01-19T22:12:25.050022Z","steps":["trace[1510434714] 'process raft request' (duration: 218.371341ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:12:25.050191Z","caller":"traceutil/trace.go:171","msg":"trace[912239395] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"217.477572ms","start":"2026-01-19T22:12:24.832707Z","end":"2026-01-19T22:12:25.050185Z","steps":["trace[912239395] 'process raft request' (duration: 217.023573ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:12:25.051392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.659455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-01-19T22:12:25.051436Z","caller":"traceutil/trace.go:171","msg":"trace[1768118002] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1023; }","duration":"193.733216ms","start":"2026-01-19T22:12:24.857694Z","end":"2026-01-19T22:12:25.051428Z","steps":["trace[1768118002] 'agreement among raft nodes before linearized reading' (duration: 193.585713ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:15:48.564816Z","caller":"traceutil/trace.go:171","msg":"trace[1549472615] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"215.003735ms","start":"2026-01-19T22:15:48.349743Z","end":"2026-01-19T22:15:48.564747Z","steps":["trace[1549472615] 'process raft request' (duration: 214.537545ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:15:49.589042Z","caller":"traceutil/trace.go:171","msg":"trace[2075508497] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1966; }","duration":"168.4254ms","start":"2026-01-19T22:15:49.420594Z","end":"2026-01-19T22:15:49.58902Z","steps":["trace[2075508497] 'read index received' (duration: 168.112514ms)","trace[2075508497] 'applied index is now lower than readState.Index' (duration: 311.976µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:15:49.589271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.653255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-01-19T22:15:49.589318Z","caller":"traceutil/trace.go:171","msg":"trace[888813957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1886; }","duration":"168.700436ms","start":"2026-01-19T22:15:49.420586Z","end":"2026-01-19T22:15:49.589287Z","steps":["trace[888813957] 'agreement among raft nodes before linearized reading' (duration: 168.527952ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:15:49.589591Z","caller":"traceutil/trace.go:171","msg":"trace[1006982288] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"321.922855ms","start":"2026-01-19T22:15:49.267659Z","end":"2026-01-19T22:15:49.589582Z","steps":["trace[1006982288] 'process raft request' (duration: 321.21024ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:15:49.589702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:15:49.267631Z","time spent":"321.979617ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}