{"level":"warn","ts":"2026-02-08T21:41:41.366063Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-08T21:41:41.366142Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-08T21:41:41.368967Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-08T21:41:41.368987Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-02-08T21:41:41.369052Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-08T21:41:41.369702Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-02-08T21:41:41.371701Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-08T21:41:41.382532Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.100915ms"} {"level":"info","ts":"2026-02-08T21:41:41.394887Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-02-08T21:41:41.394982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T21:41:41.395007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-02-08T21:41:41.395018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T21:41:41.395059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-02-08T21:41:41.395097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-02-08T21:41:41.411401Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T21:41:41.413325Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T21:41:41.417821Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-08T21:41:41.421049Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T21:41:41.425007Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T21:41:41.425173Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-08T21:41:41.425215Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-08T21:41:41.425233Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-08T21:41:41.425859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-02-08T21:41:41.426029Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-02-08T21:41:41.442342Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-08T21:41:41.44253Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-02-08T21:41:41.442589Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-02-08T21:41:41.442847Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T21:41:41.442921Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T21:41:41.899059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T21:41:41.8991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T21:41:41.899119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-02-08T21:41:41.89913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-02-08T21:41:41.899135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-02-08T21:41:41.899144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-02-08T21:41:41.89915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-02-08T21:41:41.909778Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T21:41:41.909909Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:41.910769Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T21:41:41.911918Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T21:41:41.913396Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-08T21:41:41.915642Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"info","ts":"2026-02-08T21:41:41.924958Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T21:41:41.924978Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T21:41:41.925003Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:41.925058Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:41.925071Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:50.802048Z","caller":"traceutil/trace.go:171","msg":"trace[321221756] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:317; }","duration":"225.083697ms","start":"2026-02-08T21:41:50.576946Z","end":"2026-02-08T21:41:50.802029Z","steps":["trace[321221756] 'read index received' (duration: 224.918124ms)","trace[321221756] 'applied index is now lower than readState.Index' (duration: 164.673µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:41:50.802338Z","caller":"traceutil/trace.go:171","msg":"trace[621415686] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"273.368492ms","start":"2026-02-08T21:41:50.528958Z","end":"2026-02-08T21:41:50.802327Z","steps":["trace[621415686] 'process raft request' (duration: 272.958603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:50.802538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.572728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-08T21:41:50.802598Z","caller":"traceutil/trace.go:171","msg":"trace[2076291790] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:307; }","duration":"225.733601ms","start":"2026-02-08T21:41:50.576838Z","end":"2026-02-08T21:41:50.802572Z","steps":["trace[2076291790] 'agreement among raft nodes before linearized reading' (duration: 225.640839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:50.80275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.690298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-08T21:41:50.802777Z","caller":"traceutil/trace.go:171","msg":"trace[1134394462] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:307; }","duration":"175.748399ms","start":"2026-02-08T21:41:50.62702Z","end":"2026-02-08T21:41:50.802769Z","steps":["trace[1134394462] 'agreement among raft nodes before linearized reading' (duration: 175.702018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:50.803195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.050863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-08T21:41:50.803229Z","caller":"traceutil/trace.go:171","msg":"trace[646984584] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:307; }","duration":"126.084014ms","start":"2026-02-08T21:41:50.677134Z","end":"2026-02-08T21:41:50.803218Z","steps":["trace[646984584] 'agreement among raft nodes before linearized reading' (duration: 126.033603ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:57.345498Z","caller":"traceutil/trace.go:171","msg":"trace[2118130393] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"352.634962ms","start":"2026-02-08T21:41:56.992649Z","end":"2026-02-08T21:41:57.345284Z","steps":["trace[2118130393] 'process raft request' (duration: 352.485149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:57.346036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:56.992627Z","time spent":"352.93579ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:41:58.083332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.723277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T21:41:58.08341Z","caller":"traceutil/trace.go:171","msg":"trace[2010631310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:366; }","duration":"662.831409ms","start":"2026-02-08T21:41:57.420561Z","end":"2026-02-08T21:41:58.083392Z","steps":["trace[2010631310] 'range keys from in-memory index tree' (duration: 662.647456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:58.083451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:57.420534Z","time spent":"662.908341ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:41:58.083789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.507775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T21:41:58.083824Z","caller":"traceutil/trace.go:171","msg":"trace[1645155857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:366; }","duration":"382.575216ms","start":"2026-02-08T21:41:57.701237Z","end":"2026-02-08T21:41:58.083812Z","steps":["trace[1645155857] 'range keys from in-memory index tree' (duration: 382.434783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:58.083853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:57.701214Z","time spent":"382.633077ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T21:41:58.083986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.95966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:41:58.084013Z","caller":"traceutil/trace.go:171","msg":"trace[2098774742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:366; }","duration":"353.008262ms","start":"2026-02-08T21:41:57.730996Z","end":"2026-02-08T21:41:58.084005Z","steps":["trace[2098774742] 'range keys from in-memory index tree' (duration: 352.905889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:58.084039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:57.730985Z","time spent":"353.048172ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:41:58.084334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.207122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:41:58.084413Z","caller":"traceutil/trace.go:171","msg":"trace[1147577251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:366; }","duration":"351.324824ms","start":"2026-02-08T21:41:57.73307Z","end":"2026-02-08T21:41:58.084394Z","steps":["trace[1147577251] 'range keys from in-memory index tree' (duration: 351.12071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:58.084487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:57.73306Z","time spent":"351.392725ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-08T21:41:58.26231Z","caller":"traceutil/trace.go:171","msg":"trace[2122763604] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"170.681678ms","start":"2026-02-08T21:41:58.091614Z","end":"2026-02-08T21:41:58.262296Z","steps":["trace[2122763604] 'process raft request' (duration: 170.601956ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:58.606175Z","caller":"traceutil/trace.go:171","msg":"trace[2029517931] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:380; }","duration":"258.503478ms","start":"2026-02-08T21:41:58.347649Z","end":"2026-02-08T21:41:58.606152Z","steps":["trace[2029517931] 'read index received' (duration: 181.402173ms)","trace[2029517931] 'applied index is now lower than readState.Index' (duration: 77.100515ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:58.606347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.678671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4012"} {"level":"info","ts":"2026-02-08T21:41:58.606379Z","caller":"traceutil/trace.go:171","msg":"trace[526083213] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:370; }","duration":"258.759982ms","start":"2026-02-08T21:41:58.34761Z","end":"2026-02-08T21:41:58.60637Z","steps":["trace[526083213] 'agreement among raft nodes before linearized reading' (duration: 258.6436ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:58.606607Z","caller":"traceutil/trace.go:171","msg":"trace[1700644712] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"514.802806ms","start":"2026-02-08T21:41:58.09178Z","end":"2026-02-08T21:41:58.606583Z","steps":["trace[1700644712] 'process raft request' (duration: 514.249194ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:58.606661Z","caller":"traceutil/trace.go:171","msg":"trace[1793064565] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"514.802615ms","start":"2026-02-08T21:41:58.091846Z","end":"2026-02-08T21:41:58.606648Z","steps":["trace[1793064565] 'process raft request' (duration: 514.265564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:58.60676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:58.091841Z","time spent":"514.853006ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:41:58.606766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:58.091776Z","time spent":"514.895518ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:58.607102Z","caller":"traceutil/trace.go:171","msg":"trace[2042619399] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"515.340037ms","start":"2026-02-08T21:41:58.091744Z","end":"2026-02-08T21:41:58.607084Z","steps":["trace[2042619399] 'process raft request' (duration: 437.361913ms)","trace[2042619399] 'compare' (duration: 76.120533ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:58.607182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:58.091737Z","time spent":"515.404518ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:58.822977Z","caller":"traceutil/trace.go:171","msg":"trace[1495184964] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"203.097687ms","start":"2026-02-08T21:41:58.619858Z","end":"2026-02-08T21:41:58.822956Z","steps":["trace[1495184964] 'process raft request' (duration: 202.615126ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:00.477951Z","caller":"traceutil/trace.go:171","msg":"trace[236837848] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"138.936785ms","start":"2026-02-08T21:42:00.338993Z","end":"2026-02-08T21:42:00.47793Z","steps":["trace[236837848] 'process raft request' (duration: 47.865405ms)","trace[236837848] 'compare' (duration: 90.964488ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:00.491901Z","caller":"traceutil/trace.go:171","msg":"trace[649788332] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"152.178235ms","start":"2026-02-08T21:42:00.339708Z","end":"2026-02-08T21:42:00.491887Z","steps":["trace[649788332] 'process raft request' (duration: 152.134004ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:00.491969Z","caller":"traceutil/trace.go:171","msg":"trace[455004601] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"152.689246ms","start":"2026-02-08T21:42:00.339239Z","end":"2026-02-08T21:42:00.491928Z","steps":["trace[455004601] 'process raft request' (duration: 152.526703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:00.663979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.781183ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-08T21:42:00.664811Z","caller":"traceutil/trace.go:171","msg":"trace[534192855] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"156.025027ms","start":"2026-02-08T21:42:00.508762Z","end":"2026-02-08T21:42:00.664787Z","steps":["trace[534192855] 'process raft request' (duration: 155.970386ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:00.66558Z","caller":"traceutil/trace.go:171","msg":"trace[2034268810] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"160.330383ms","start":"2026-02-08T21:42:00.505084Z","end":"2026-02-08T21:42:00.665414Z","steps":["trace[2034268810] 'process raft request' (duration: 159.278639ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:00.666127Z","caller":"traceutil/trace.go:171","msg":"trace[1903982436] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"158.953103ms","start":"2026-02-08T21:42:00.507158Z","end":"2026-02-08T21:42:00.666111Z","steps":["trace[1903982436] 'process raft request' (duration: 157.404239ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:00.666794Z","caller":"traceutil/trace.go:171","msg":"trace[1452857619] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"158.067702ms","start":"2026-02-08T21:42:00.508705Z","end":"2026-02-08T21:42:00.666773Z","steps":["trace[1452857619] 'process raft request' (duration: 155.933595ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:00.667179Z","caller":"traceutil/trace.go:171","msg":"trace[1396886797] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"158.38843ms","start":"2026-02-08T21:42:00.508736Z","end":"2026-02-08T21:42:00.667125Z","steps":["trace[1396886797] 'process raft request' (duration: 155.949936ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:00.960483Z","caller":"traceutil/trace.go:171","msg":"trace[563484754] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"123.470278ms","start":"2026-02-08T21:42:00.836989Z","end":"2026-02-08T21:42:00.960459Z","steps":["trace[563484754] 'process raft request' (duration: 96.198551ms)","trace[563484754] 'compare' (duration: 27.022391ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:00.96084Z","caller":"traceutil/trace.go:171","msg":"trace[458765480] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"123.296644ms","start":"2026-02-08T21:42:00.837535Z","end":"2026-02-08T21:42:00.960832Z","steps":["trace[458765480] 'process raft request' (duration: 122.981047ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:00.961006Z","caller":"traceutil/trace.go:171","msg":"trace[16765149] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"123.954708ms","start":"2026-02-08T21:42:00.837043Z","end":"2026-02-08T21:42:00.960998Z","steps":["trace[16765149] 'process raft request' (duration: 123.379996ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:00.96115Z","caller":"traceutil/trace.go:171","msg":"trace[1184494259] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"124.06275ms","start":"2026-02-08T21:42:00.83708Z","end":"2026-02-08T21:42:00.961143Z","steps":["trace[1184494259] 'process raft request' (duration: 123.403196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:00.961628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.861597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-02-08T21:42:00.961693Z","caller":"traceutil/trace.go:171","msg":"trace[330957580] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:401; }","duration":"124.940399ms","start":"2026-02-08T21:42:00.836742Z","end":"2026-02-08T21:42:00.961682Z","steps":["trace[330957580] 'agreement among raft nodes before linearized reading' (duration: 124.850217ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:00.960965Z","caller":"traceutil/trace.go:171","msg":"trace[1698566587] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"113.08898ms","start":"2026-02-08T21:42:00.847854Z","end":"2026-02-08T21:42:00.960943Z","steps":["trace[1698566587] 'process raft request' (duration: 112.727932ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:12.84505Z","caller":"traceutil/trace.go:171","msg":"trace[1791020009] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"104.407181ms","start":"2026-02-08T21:42:12.740624Z","end":"2026-02-08T21:42:12.845032Z","steps":["trace[1791020009] 'process raft request' (duration: 103.997352ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:13.929452Z","caller":"traceutil/trace.go:171","msg":"trace[573221890] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"541.181682ms","start":"2026-02-08T21:42:13.388251Z","end":"2026-02-08T21:42:13.929433Z","steps":["trace[573221890] 'process raft request' (duration: 540.769472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:13.929575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:13.388228Z","time spent":"541.285414ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4210,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:14.260007Z","caller":"traceutil/trace.go:171","msg":"trace[46704073] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"219.135538ms","start":"2026-02-08T21:42:14.040851Z","end":"2026-02-08T21:42:14.259986Z","steps":["trace[46704073] 'process raft request' (duration: 209.543798ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:17.123054Z","caller":"traceutil/trace.go:171","msg":"trace[1545561163] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"239.608355ms","start":"2026-02-08T21:42:16.883424Z","end":"2026-02-08T21:42:17.123032Z","steps":["trace[1545561163] 'process raft request' (duration: 239.490121ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:17.42655Z","caller":"traceutil/trace.go:171","msg":"trace[412979248] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"540.907916ms","start":"2026-02-08T21:42:16.885623Z","end":"2026-02-08T21:42:17.426531Z","steps":["trace[412979248] 'read index received' (duration: 237.900357ms)","trace[412979248] 'applied index is now lower than readState.Index' (duration: 303.006649ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:17.42667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.026958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T21:42:17.426701Z","caller":"traceutil/trace.go:171","msg":"trace[1189117100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:480; }","duration":"541.072079ms","start":"2026-02-08T21:42:16.88562Z","end":"2026-02-08T21:42:17.426692Z","steps":["trace[1189117100] 'agreement among raft nodes before linearized reading' (duration: 540.986907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:17.42673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:16.885599Z","time spent":"541.12361ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-08T21:42:17.426956Z","caller":"traceutil/trace.go:171","msg":"trace[895416109] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"541.737694ms","start":"2026-02-08T21:42:16.885204Z","end":"2026-02-08T21:42:17.426942Z","steps":["trace[895416109] 'process raft request' (duration: 515.49487ms)","trace[895416109] 'compare' (duration: 25.748523ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:17.427034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:16.885187Z","time spent":"541.796274ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:22.25896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.722948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-08T21:42:22.259032Z","caller":"traceutil/trace.go:171","msg":"trace[1026103713] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:491; }","duration":"945.846361ms","start":"2026-02-08T21:42:21.313163Z","end":"2026-02-08T21:42:22.259009Z","steps":["trace[1026103713] 'range keys from in-memory index tree' (duration: 945.622987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:22.259077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:21.313131Z","time spent":"945.934504ms","remote":"127.0.0.1:46640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-08T21:42:22.259319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.25562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:42:22.259346Z","caller":"traceutil/trace.go:171","msg":"trace[1268803564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"816.314301ms","start":"2026-02-08T21:42:21.443024Z","end":"2026-02-08T21:42:22.259338Z","steps":["trace[1268803564] 'range keys from in-memory index tree' (duration: 816.128107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:22.259372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:21.443007Z","time spent":"816.359082ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:22.259555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.12548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T21:42:22.259584Z","caller":"traceutil/trace.go:171","msg":"trace[2097161862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:491; }","duration":"801.193272ms","start":"2026-02-08T21:42:21.458383Z","end":"2026-02-08T21:42:22.259577Z","steps":["trace[2097161862] 'range keys from in-memory index tree' (duration: 801.029088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:22.259609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:21.458364Z","time spent":"801.239972ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:22.259751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.238013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:22.259778Z","caller":"traceutil/trace.go:171","msg":"trace[111487167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:491; }","duration":"422.290475ms","start":"2026-02-08T21:42:21.837478Z","end":"2026-02-08T21:42:22.259769Z","steps":["trace[111487167] 'range keys from in-memory index tree' (duration: 422.193442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:22.259806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:21.837463Z","time spent":"422.337225ms","remote":"127.0.0.1:46536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:42:22.259983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.693659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sb9gg\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-08T21:42:22.260012Z","caller":"traceutil/trace.go:171","msg":"trace[957896522] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sb9gg; range_end:; response_count:1; response_revision:491; }","duration":"268.743541ms","start":"2026-02-08T21:42:21.991259Z","end":"2026-02-08T21:42:22.260002Z","steps":["trace[957896522] 'range keys from in-memory index tree' (duration: 268.576367ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:22.58856Z","caller":"traceutil/trace.go:171","msg":"trace[513959222] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"321.156786ms","start":"2026-02-08T21:42:22.267382Z","end":"2026-02-08T21:42:22.588539Z","steps":["trace[513959222] 'process raft request' (duration: 321.050063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:22.588807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:22.26737Z","time spent":"321.331799ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:22.852426Z","caller":"traceutil/trace.go:171","msg":"trace[1137347332] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:510; }","duration":"575.356329ms","start":"2026-02-08T21:42:22.277044Z","end":"2026-02-08T21:42:22.8524Z","steps":["trace[1137347332] 'read index received' (duration: 311.341671ms)","trace[1137347332] 'applied index is now lower than readState.Index' (duration: 264.013578ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:22.852742Z","caller":"traceutil/trace.go:171","msg":"trace[22271460] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"584.924478ms","start":"2026-02-08T21:42:22.267806Z","end":"2026-02-08T21:42:22.852731Z","steps":["trace[22271460] 'process raft request' (duration: 584.396886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:22.852841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:22.267787Z","time spent":"584.985159ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:22.85327Z","caller":"traceutil/trace.go:171","msg":"trace[396050508] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"572.165498ms","start":"2026-02-08T21:42:22.281092Z","end":"2026-02-08T21:42:22.853258Z","steps":["trace[396050508] 'process raft request' (duration: 571.240408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:22.853335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:22.281081Z","time spent":"572.21934ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:22.853514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.464542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-08T21:42:22.853549Z","caller":"traceutil/trace.go:171","msg":"trace[1020897636] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:494; }","duration":"576.515294ms","start":"2026-02-08T21:42:22.277024Z","end":"2026-02-08T21:42:22.85354Z","steps":["trace[1020897636] 'agreement among raft nodes before linearized reading' (duration: 576.419482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:22.853577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:22.277016Z","time spent":"576.554845ms","remote":"127.0.0.1:46626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6557,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"warn","ts":"2026-02-08T21:42:22.853776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.72041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:42:22.853802Z","caller":"traceutil/trace.go:171","msg":"trace[237540768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:494; }","duration":"447.781282ms","start":"2026-02-08T21:42:22.406013Z","end":"2026-02-08T21:42:22.853794Z","steps":["trace[237540768] 'agreement among raft nodes before linearized reading' (duration: 447.68576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:22.853834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:22.405992Z","time spent":"447.833202ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-08T21:42:27.267465Z","caller":"traceutil/trace.go:171","msg":"trace[261347757] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"104.83671ms","start":"2026-02-08T21:42:27.162612Z","end":"2026-02-08T21:42:27.267448Z","steps":["trace[261347757] 'process raft request' (duration: 104.058043ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:32.917322Z","caller":"traceutil/trace.go:171","msg":"trace[1456944260] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"286.830596ms","start":"2026-02-08T21:42:32.630477Z","end":"2026-02-08T21:42:32.917308Z","steps":["trace[1456944260] 'process raft request' (duration: 286.547189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:33.42493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988148963524450353,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:42:33.717158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.577036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:33.717239Z","caller":"traceutil/trace.go:171","msg":"trace[1362718208] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"794.678859ms","start":"2026-02-08T21:42:32.92254Z","end":"2026-02-08T21:42:33.717219Z","steps":["trace[1362718208] 'process raft request' (duration: 607.996131ms)","trace[1362718208] 'compare' (duration: 186.463513ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:33.717275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:32.922522Z","time spent":"794.73855ms","remote":"127.0.0.1:38398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2260,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T21:42:33.734788Z","caller":"traceutil/trace.go:171","msg":"trace[1156308593] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:630; }","duration":"810.386051ms","start":"2026-02-08T21:42:32.924381Z","end":"2026-02-08T21:42:33.734767Z","steps":["trace[1156308593] 'read index received' (duration: 606.237833ms)","trace[1156308593] 'applied index is now lower than readState.Index' (duration: 204.146988ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:33.73508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.676298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-08T21:42:33.735104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.93373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:42:33.735131Z","caller":"traceutil/trace.go:171","msg":"trace[1133748790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"810.760971ms","start":"2026-02-08T21:42:32.924358Z","end":"2026-02-08T21:42:33.735119Z","steps":["trace[1133748790] 'agreement among raft nodes before linearized reading' (duration: 810.559666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:33.735158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:32.924352Z","time spent":"810.801912ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-08T21:42:33.735156Z","caller":"traceutil/trace.go:171","msg":"trace[1953259045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"771.015782ms","start":"2026-02-08T21:42:32.964128Z","end":"2026-02-08T21:42:33.735144Z","steps":["trace[1953259045] 'agreement among raft nodes before linearized reading' (duration: 770.866118ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:33.735297Z","caller":"traceutil/trace.go:171","msg":"trace[954341608] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"579.601571ms","start":"2026-02-08T21:42:33.155689Z","end":"2026-02-08T21:42:33.735291Z","steps":["trace[954341608] 'process raft request' (duration: 578.928405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:33.735341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:33.155672Z","time spent":"579.639522ms","remote":"127.0.0.1:38398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2259,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-08T21:42:33.735394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:32.964114Z","time spent":"771.166036ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:42:33.735577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.054271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T21:42:33.735597Z","caller":"traceutil/trace.go:171","msg":"trace[1949205823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"439.110042ms","start":"2026-02-08T21:42:33.296481Z","end":"2026-02-08T21:42:33.735591Z","steps":["trace[1949205823] 'agreement among raft nodes before linearized reading' (duration: 439.060621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:33.735614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:33.296432Z","time spent":"439.176253ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:42:33.73569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.822553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-08T21:42:33.735725Z","caller":"traceutil/trace.go:171","msg":"trace[313211013] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:610; }","duration":"751.882185ms","start":"2026-02-08T21:42:32.983833Z","end":"2026-02-08T21:42:33.735715Z","steps":["trace[313211013] 'agreement among raft nodes before linearized reading' (duration: 751.764012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:33.735754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:32.983819Z","time spent":"751.927845ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-08T21:42:36.01455Z","caller":"traceutil/trace.go:171","msg":"trace[859380265] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"249.178033ms","start":"2026-02-08T21:42:35.765353Z","end":"2026-02-08T21:42:36.014531Z","steps":["trace[859380265] 'process raft request' (duration: 249.076761ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:36.015034Z","caller":"traceutil/trace.go:171","msg":"trace[550132470] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:684; }","duration":"249.372738ms","start":"2026-02-08T21:42:35.765653Z","end":"2026-02-08T21:42:36.015025Z","steps":["trace[550132470] 'read index received' (duration: 249.369668ms)","trace[550132470] 'applied index is now lower than readState.Index' (duration: 2.62µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:36.015141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.47522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T21:42:36.015161Z","caller":"traceutil/trace.go:171","msg":"trace[62378131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"249.506741ms","start":"2026-02-08T21:42:35.765648Z","end":"2026-02-08T21:42:36.015155Z","steps":["trace[62378131] 'agreement among raft nodes before linearized reading' (duration: 249.418899ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:36.025282Z","caller":"traceutil/trace.go:171","msg":"trace[917123457] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"257.033045ms","start":"2026-02-08T21:42:35.768235Z","end":"2026-02-08T21:42:36.025268Z","steps":["trace[917123457] 'process raft request' (duration: 256.950733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:36.025646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.945818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:42:36.025671Z","caller":"traceutil/trace.go:171","msg":"trace[2107503176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"259.989039ms","start":"2026-02-08T21:42:35.765674Z","end":"2026-02-08T21:42:36.025663Z","steps":["trace[2107503176] 'agreement among raft nodes before linearized reading' (duration: 259.902027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:36.025773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.435805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:36.025792Z","caller":"traceutil/trace.go:171","msg":"trace[346450929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"188.477177ms","start":"2026-02-08T21:42:35.837308Z","end":"2026-02-08T21:42:36.025785Z","steps":["trace[346450929] 'agreement among raft nodes before linearized reading' (duration: 188.449766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:46.430716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.96169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:46.430808Z","caller":"traceutil/trace.go:171","msg":"trace[2130422957] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"267.072236ms","start":"2026-02-08T21:42:46.163714Z","end":"2026-02-08T21:42:46.430786Z","steps":["trace[2130422957] 'process raft request' (duration: 68.980943ms)","trace[2130422957] 'compare' (duration: 197.875929ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:46.431257Z","caller":"traceutil/trace.go:171","msg":"trace[1995340282] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"265.687616ms","start":"2026-02-08T21:42:46.165561Z","end":"2026-02-08T21:42:46.431249Z","steps":["trace[1995340282] 'process raft request' (duration: 265.664216ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:46.431414Z","caller":"traceutil/trace.go:171","msg":"trace[820475409] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"266.300229ms","start":"2026-02-08T21:42:46.165106Z","end":"2026-02-08T21:42:46.431406Z","steps":["trace[820475409] 'process raft request' (duration: 266.086835ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:51.335699Z","caller":"traceutil/trace.go:171","msg":"trace[1252628643] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"179.544853ms","start":"2026-02-08T21:42:51.156134Z","end":"2026-02-08T21:42:51.335679Z","steps":["trace[1252628643] 'process raft request' (duration: 179.432441ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:02.361712Z","caller":"traceutil/trace.go:171","msg":"trace[1361829912] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"126.021596ms","start":"2026-02-08T21:43:02.235672Z","end":"2026-02-08T21:43:02.361694Z","steps":["trace[1361829912] 'process raft request' (duration: 125.914523ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:02.648343Z","caller":"traceutil/trace.go:171","msg":"trace[1222246244] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"143.028184ms","start":"2026-02-08T21:43:02.505295Z","end":"2026-02-08T21:43:02.648323Z","steps":["trace[1222246244] 'process raft request' (duration: 101.586457ms)","trace[1222246244] 'compare' (duration: 41.310424ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:02.648444Z","caller":"traceutil/trace.go:171","msg":"trace[330877786] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"140.893338ms","start":"2026-02-08T21:43:02.507532Z","end":"2026-02-08T21:43:02.648426Z","steps":["trace[330877786] 'process raft request' (duration: 140.762205ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:03.878502Z","caller":"traceutil/trace.go:171","msg":"trace[1808794265] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"334.875164ms","start":"2026-02-08T21:43:03.543609Z","end":"2026-02-08T21:43:03.878484Z","steps":["trace[1808794265] 'read index received' (duration: 334.735621ms)","trace[1808794265] 'applied index is now lower than readState.Index' (duration: 138.853µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:03.878611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.976056ms","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-02-08T21:43:03.878634Z","caller":"traceutil/trace.go:171","msg":"trace[1046704078] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:744; }","duration":"335.022627ms","start":"2026-02-08T21:43:03.543604Z","end":"2026-02-08T21:43:03.878626Z","steps":["trace[1046704078] 'agreement among raft nodes before linearized reading' (duration: 334.945585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:03.878658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:03.543554Z","time spent":"335.098168ms","remote":"127.0.0.1:46980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-02-08T21:43:03.878878Z","caller":"traceutil/trace.go:171","msg":"trace[1728285020] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"500.181719ms","start":"2026-02-08T21:43:03.37869Z","end":"2026-02-08T21:43:03.878872Z","steps":["trace[1728285020] 'process raft request' (duration: 499.702699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:03.878938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:03.378678Z","time spent":"500.21802ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:10.138632Z","caller":"traceutil/trace.go:171","msg":"trace[2129462155] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"232.092949ms","start":"2026-02-08T21:43:09.906509Z","end":"2026-02-08T21:43:10.138602Z","steps":["trace[2129462155] 'process raft request' (duration: 231.69977ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:10.616139Z","caller":"traceutil/trace.go:171","msg":"trace[669408329] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"216.216786ms","start":"2026-02-08T21:43:10.399897Z","end":"2026-02-08T21:43:10.616114Z","steps":["trace[669408329] 'process raft request' (duration: 216.105394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:11.149719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.245906ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-08T21:43:11.149818Z","caller":"traceutil/trace.go:171","msg":"trace[1920055558] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"533.023468ms","start":"2026-02-08T21:43:10.616778Z","end":"2026-02-08T21:43:11.149801Z","steps":["trace[1920055558] 'read index received' (duration: 132.603317ms)","trace[1920055558] 'applied index is now lower than readState.Index' (duration: 400.418741ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:11.150387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.594711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T21:43:11.150424Z","caller":"traceutil/trace.go:171","msg":"trace[321471133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:763; }","duration":"533.663153ms","start":"2026-02-08T21:43:10.616748Z","end":"2026-02-08T21:43:11.150411Z","steps":["trace[321471133] 'agreement among raft nodes before linearized reading' (duration: 533.5244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:11.150457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:10.616735Z","time spent":"533.715184ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:43:11.150703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.535835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:43:11.150731Z","caller":"traceutil/trace.go:171","msg":"trace[706463381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"469.595427ms","start":"2026-02-08T21:43:10.681128Z","end":"2026-02-08T21:43:11.150723Z","steps":["trace[706463381] 'agreement among raft nodes before linearized reading' (duration: 469.515735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:11.150757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:10.681111Z","time spent":"469.640107ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:43:11.150958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.530684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:11.150988Z","caller":"traceutil/trace.go:171","msg":"trace[1058361275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"314.584434ms","start":"2026-02-08T21:43:10.836394Z","end":"2026-02-08T21:43:11.150979Z","steps":["trace[1058361275] 'agreement among raft nodes before linearized reading' (duration: 314.540683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:11.151015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:10.836381Z","time spent":"314.626806ms","remote":"127.0.0.1:46536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:11.151356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.080942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-08T21:43:11.15143Z","caller":"traceutil/trace.go:171","msg":"trace[843737373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"467.183464ms","start":"2026-02-08T21:43:10.684226Z","end":"2026-02-08T21:43:11.15141Z","steps":["trace[843737373] 'agreement among raft nodes before linearized reading' (duration: 466.921119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:11.151475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:10.684216Z","time spent":"467.250167ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-08T21:43:11.404129Z","caller":"traceutil/trace.go:171","msg":"trace[1511472656] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"243.218051ms","start":"2026-02-08T21:43:11.16089Z","end":"2026-02-08T21:43:11.404108Z","steps":["trace[1511472656] 'process raft request' (duration: 242.953975ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:11.43059Z","caller":"traceutil/trace.go:171","msg":"trace[289196845] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"268.787114ms","start":"2026-02-08T21:43:11.161789Z","end":"2026-02-08T21:43:11.430576Z","steps":["trace[289196845] 'process raft request' (duration: 268.738323ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:11.430964Z","caller":"traceutil/trace.go:171","msg":"trace[550187515] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"269.373296ms","start":"2026-02-08T21:43:11.161578Z","end":"2026-02-08T21:43:11.430951Z","steps":["trace[550187515] 'process raft request' (duration: 268.862535ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:12.253927Z","caller":"traceutil/trace.go:171","msg":"trace[1594820139] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"104.557561ms","start":"2026-02-08T21:43:12.149352Z","end":"2026-02-08T21:43:12.253909Z","steps":["trace[1594820139] 'process raft request' (duration: 92.688924ms)","trace[1594820139] 'compare' (duration: 11.122351ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:25.026639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.037355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:43:25.026691Z","caller":"traceutil/trace.go:171","msg":"trace[1160499899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:852; }","duration":"187.131147ms","start":"2026-02-08T21:43:24.839549Z","end":"2026-02-08T21:43:25.026681Z","steps":["trace[1160499899] 'range keys from in-memory index tree' (duration: 186.935383ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:25.283319Z","caller":"traceutil/trace.go:171","msg":"trace[2129041312] linearizableReadLoop","detail":"{readStateIndex:888; appliedIndex:887; }","duration":"179.047422ms","start":"2026-02-08T21:43:25.104257Z","end":"2026-02-08T21:43:25.283304Z","steps":["trace[2129041312] 'read index received' (duration: 178.96191ms)","trace[2129041312] 'applied index is now lower than readState.Index' (duration: 84.662µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:25.283448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.192146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-08T21:43:25.283465Z","caller":"traceutil/trace.go:171","msg":"trace[247023707] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:853; }","duration":"179.243686ms","start":"2026-02-08T21:43:25.104216Z","end":"2026-02-08T21:43:25.28346Z","steps":["trace[247023707] 'agreement among raft nodes before linearized reading' (duration: 179.144614ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:25.28358Z","caller":"traceutil/trace.go:171","msg":"trace[987067493] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"251.755376ms","start":"2026-02-08T21:43:25.031805Z","end":"2026-02-08T21:43:25.28356Z","steps":["trace[987067493] 'process raft request' (duration: 251.368457ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:25.726807Z","caller":"traceutil/trace.go:171","msg":"trace[1190064830] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"452.076489ms","start":"2026-02-08T21:43:25.274707Z","end":"2026-02-08T21:43:25.726783Z","steps":["trace[1190064830] 'process raft request' (duration: 404.706644ms)","trace[1190064830] 'compare' (duration: 47.282003ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:25.726933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:25.27469Z","time spent":"452.18011ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:25.786632Z","caller":"traceutil/trace.go:171","msg":"trace[227658251] linearizableReadLoop","detail":"{readStateIndex:890; appliedIndex:888; }","duration":"333.310769ms","start":"2026-02-08T21:43:25.453304Z","end":"2026-02-08T21:43:25.786615Z","steps":["trace[227658251] 'read index received' (duration: 226.116941ms)","trace[227658251] 'applied index is now lower than readState.Index' (duration: 107.192928ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:25.786717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:25.284492Z","time spent":"502.222571ms","remote":"127.0.0.1:46574","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-08T21:43:25.78698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.672327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T21:43:25.786998Z","caller":"traceutil/trace.go:171","msg":"trace[73293281] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:854; }","duration":"333.739948ms","start":"2026-02-08T21:43:25.453253Z","end":"2026-02-08T21:43:25.786993Z","steps":["trace[73293281] 'agreement among raft nodes before linearized reading' (duration: 333.645816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:25.787013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:25.453232Z","time spent":"333.778049ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:43:25.787115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.833892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:43:25.787128Z","caller":"traceutil/trace.go:171","msg":"trace[1305533240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:854; }","duration":"285.882393ms","start":"2026-02-08T21:43:25.501241Z","end":"2026-02-08T21:43:25.787123Z","steps":["trace[1305533240] 'agreement among raft nodes before linearized reading' (duration: 285.844132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:25.787248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.129394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-08T21:43:25.787261Z","caller":"traceutil/trace.go:171","msg":"trace[359608504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:854; }","duration":"259.165135ms","start":"2026-02-08T21:43:25.528092Z","end":"2026-02-08T21:43:25.787257Z","steps":["trace[359608504] 'agreement among raft nodes before linearized reading' (duration: 259.122294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:26.247177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.617367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:26.24729Z","caller":"traceutil/trace.go:171","msg":"trace[231269273] linearizableReadLoop","detail":"{readStateIndex:894; appliedIndex:891; }","duration":"433.827553ms","start":"2026-02-08T21:43:25.813452Z","end":"2026-02-08T21:43:26.24728Z","steps":["trace[231269273] 'read index received' (duration: 50.016832ms)","trace[231269273] 'applied index is now lower than readState.Index' (duration: 383.810181ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:26.247402Z","caller":"traceutil/trace.go:171","msg":"trace[811623835] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"455.781748ms","start":"2026-02-08T21:43:25.791614Z","end":"2026-02-08T21:43:26.247396Z","steps":["trace[811623835] 'process raft request' (duration: 71.898235ms)","trace[811623835] 'compare' (duration: 383.400982ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:26.247433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:25.791599Z","time spent":"455.818179ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:26.247522Z","caller":"traceutil/trace.go:171","msg":"trace[1674792559] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"455.115244ms","start":"2026-02-08T21:43:25.7924Z","end":"2026-02-08T21:43:26.247515Z","steps":["trace[1674792559] 'process raft request' (duration: 454.832578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:26.247548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:25.792378Z","time spent":"455.155365ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:26.247609Z","caller":"traceutil/trace.go:171","msg":"trace[1474281982] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"455.156415ms","start":"2026-02-08T21:43:25.792445Z","end":"2026-02-08T21:43:26.247601Z","steps":["trace[1474281982] 'process raft request' (duration: 454.815038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:26.247631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:25.792439Z","time spent":"455.179995ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:26.247724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.277252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-08T21:43:26.247752Z","caller":"traceutil/trace.go:171","msg":"trace[795664016] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:858; }","duration":"434.308643ms","start":"2026-02-08T21:43:25.813424Z","end":"2026-02-08T21:43:26.247732Z","steps":["trace[795664016] 'agreement among raft nodes before linearized reading' (duration: 434.278522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:26.247767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:25.813416Z","time spent":"434.347974ms","remote":"127.0.0.1:46684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-08T21:43:26.247843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.771905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:26.247856Z","caller":"traceutil/trace.go:171","msg":"trace[897741117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:858; }","duration":"410.815485ms","start":"2026-02-08T21:43:25.837036Z","end":"2026-02-08T21:43:26.247851Z","steps":["trace[897741117] 'agreement among raft nodes before linearized reading' (duration: 410.793925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:26.247869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:25.837024Z","time spent":"410.841187ms","remote":"127.0.0.1:46536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T21:43:35.075783Z","caller":"traceutil/trace.go:171","msg":"trace[1419270025] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"307.969151ms","start":"2026-02-08T21:43:34.767796Z","end":"2026-02-08T21:43:35.075766Z","steps":["trace[1419270025] 'read index received' (duration: 307.852729ms)","trace[1419270025] 'applied index is now lower than readState.Index' (duration: 115.852µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:35.075912Z","caller":"traceutil/trace.go:171","msg":"trace[1818597815] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"622.902531ms","start":"2026-02-08T21:43:34.453004Z","end":"2026-02-08T21:43:35.075906Z","steps":["trace[1818597815] 'process raft request' (duration: 622.661776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:35.075981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:34.452995Z","time spent":"622.931863ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:35.076081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.843661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:35.076144Z","caller":"traceutil/trace.go:171","msg":"trace[1421746931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:880; }","duration":"241.932452ms","start":"2026-02-08T21:43:34.834198Z","end":"2026-02-08T21:43:35.076131Z","steps":["trace[1421746931] 'agreement among raft nodes before linearized reading' (duration: 241.84395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:35.076233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.434441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:35.076254Z","caller":"traceutil/trace.go:171","msg":"trace[1996291817] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:880; }","duration":"308.477142ms","start":"2026-02-08T21:43:34.767771Z","end":"2026-02-08T21:43:35.076248Z","steps":["trace[1996291817] 'agreement among raft nodes before linearized reading' (duration: 308.443321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:35.076269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:34.767758Z","time spent":"308.507472ms","remote":"127.0.0.1:46604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-02-08T21:43:37.4348Z","caller":"traceutil/trace.go:171","msg":"trace[472801565] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"116.695893ms","start":"2026-02-08T21:43:37.318083Z","end":"2026-02-08T21:43:37.434779Z","steps":["trace[472801565] 'read index received' (duration: 116.56516ms)","trace[472801565] 'applied index is now lower than readState.Index' (duration: 130.123µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:37.434943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.842676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:43:37.434959Z","caller":"traceutil/trace.go:171","msg":"trace[408391359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:887; }","duration":"116.909718ms","start":"2026-02-08T21:43:37.318045Z","end":"2026-02-08T21:43:37.434955Z","steps":["trace[408391359] 'agreement among raft nodes before linearized reading' (duration: 116.798775ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:37.435149Z","caller":"traceutil/trace.go:171","msg":"trace[354053095] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"353.959525ms","start":"2026-02-08T21:43:37.081184Z","end":"2026-02-08T21:43:37.435143Z","steps":["trace[354053095] 'process raft request' (duration: 353.507615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:37.435195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:37.081173Z","time spent":"353.988655ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:37.820558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.759408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:37.82066Z","caller":"traceutil/trace.go:171","msg":"trace[48955312] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"382.515764ms","start":"2026-02-08T21:43:37.438127Z","end":"2026-02-08T21:43:37.820643Z","steps":["trace[48955312] 'process raft request' (duration: 214.01324ms)","trace[48955312] 'compare' (duration: 167.543013ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:37.82072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:37.438115Z","time spent":"382.575775ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:39.721716Z","caller":"traceutil/trace.go:171","msg":"trace[472912822] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"281.781884ms","start":"2026-02-08T21:43:39.439917Z","end":"2026-02-08T21:43:39.721699Z","steps":["trace[472912822] 'process raft request' (duration: 281.663942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:40.059919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.786327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:43:40.059965Z","caller":"traceutil/trace.go:171","msg":"trace[1664047204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:893; }","duration":"234.858728ms","start":"2026-02-08T21:43:39.825095Z","end":"2026-02-08T21:43:40.059954Z","steps":["trace[1664047204] 'range keys from in-memory index tree' (duration: 234.646614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:40.060091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.329323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:40.060104Z","caller":"traceutil/trace.go:171","msg":"trace[825970505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:893; }","duration":"225.342334ms","start":"2026-02-08T21:43:39.834758Z","end":"2026-02-08T21:43:40.0601Z","steps":["trace[825970505] 'range keys from in-memory index tree' (duration: 225.278192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:40.47883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.582344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:43:40.482053Z","caller":"traceutil/trace.go:171","msg":"trace[742010992] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:894; }","duration":"231.812804ms","start":"2026-02-08T21:43:40.250197Z","end":"2026-02-08T21:43:40.482009Z","steps":["trace[742010992] 'count revisions from in-memory index tree' (duration: 228.527973ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:40.813258Z","caller":"traceutil/trace.go:171","msg":"trace[1253558841] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"326.819168ms","start":"2026-02-08T21:43:40.486424Z","end":"2026-02-08T21:43:40.813243Z","steps":["trace[1253558841] 'process raft request' (duration: 265.508792ms)","trace[1253558841] 'compare' (duration: 61.231584ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:40.813352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:40.486416Z","time spent":"326.888479ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:40.823319Z","caller":"traceutil/trace.go:171","msg":"trace[561384533] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"265.604675ms","start":"2026-02-08T21:43:40.557702Z","end":"2026-02-08T21:43:40.823306Z","steps":["trace[561384533] 'process raft request' (duration: 265.537593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:45.376508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.697422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:43:45.376563Z","caller":"traceutil/trace.go:171","msg":"trace[321512833] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:925; }","duration":"214.771914ms","start":"2026-02-08T21:43:45.161779Z","end":"2026-02-08T21:43:45.376551Z","steps":["trace[321512833] 'count revisions from in-memory index tree' (duration: 214.5925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:45.376797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.507382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:45.376813Z","caller":"traceutil/trace.go:171","msg":"trace[891166618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"542.548933ms","start":"2026-02-08T21:43:44.834258Z","end":"2026-02-08T21:43:45.376807Z","steps":["trace[891166618] 'range keys from in-memory index tree' (duration: 542.463081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:45.376826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:44.834243Z","time spent":"542.579544ms","remote":"127.0.0.1:46536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:45.376963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.14465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-08T21:43:45.376977Z","caller":"traceutil/trace.go:171","msg":"trace[1131670571] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:925; }","duration":"539.17325ms","start":"2026-02-08T21:43:44.837799Z","end":"2026-02-08T21:43:45.376973Z","steps":["trace[1131670571] 'range keys from in-memory index tree' (duration: 539.072949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:45.376992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:44.837793Z","time spent":"539.194031ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:45.377091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.416014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:43:45.377103Z","caller":"traceutil/trace.go:171","msg":"trace[1440312725] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:925; }","duration":"532.464056ms","start":"2026-02-08T21:43:44.844635Z","end":"2026-02-08T21:43:45.377099Z","steps":["trace[1440312725] 'range keys from in-memory index tree' (duration: 532.322533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:45.377118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:44.844615Z","time spent":"532.497966ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:43:45.37734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.755587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-08T21:43:45.377355Z","caller":"traceutil/trace.go:171","msg":"trace[1903824867] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:925; }","duration":"271.783429ms","start":"2026-02-08T21:43:45.105567Z","end":"2026-02-08T21:43:45.377351Z","steps":["trace[1903824867] 'range keys from in-memory index tree' (duration: 271.653664ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:45.494181Z","caller":"traceutil/trace.go:171","msg":"trace[485459201] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:965; }","duration":"112.062874ms","start":"2026-02-08T21:43:45.382106Z","end":"2026-02-08T21:43:45.494169Z","steps":["trace[485459201] 'read index received' (duration: 112.057634ms)","trace[485459201] 'applied index is now lower than readState.Index' (duration: 2.88µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:45.494252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.137135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:45.494269Z","caller":"traceutil/trace.go:171","msg":"trace[988213054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"112.200897ms","start":"2026-02-08T21:43:45.382063Z","end":"2026-02-08T21:43:45.494264Z","steps":["trace[988213054] 'agreement among raft nodes before linearized reading' (duration: 112.156496ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:45.510537Z","caller":"traceutil/trace.go:171","msg":"trace[305271363] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"125.77245ms","start":"2026-02-08T21:43:45.384755Z","end":"2026-02-08T21:43:45.510527Z","steps":["trace[305271363] 'process raft request' (duration: 125.752789ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:45.510747Z","caller":"traceutil/trace.go:171","msg":"trace[173507786] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"126.047566ms","start":"2026-02-08T21:43:45.384695Z","end":"2026-02-08T21:43:45.510742Z","steps":["trace[173507786] 'process raft request' (duration: 125.74104ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:48.753302Z","caller":"traceutil/trace.go:171","msg":"trace[1301826311] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"102.936057ms","start":"2026-02-08T21:43:48.65035Z","end":"2026-02-08T21:43:48.753286Z","steps":["trace[1301826311] 'process raft request' (duration: 102.849365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:50.620396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.680311ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-08T21:47:04.18325Z","caller":"traceutil/trace.go:171","msg":"trace[653487739] linearizableReadLoop","detail":"{readStateIndex:1881; appliedIndex:1880; }","duration":"478.263083ms","start":"2026-02-08T21:47:03.704946Z","end":"2026-02-08T21:47:04.183209Z","steps":["trace[653487739] 'read index received' (duration: 475.140984ms)","trace[653487739] 'applied index is now lower than readState.Index' (duration: 3.121189ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:04.18874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.780535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-08T21:47:04.183542Z","caller":"traceutil/trace.go:171","msg":"trace[2127385076] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"524.649871ms","start":"2026-02-08T21:47:03.658876Z","end":"2026-02-08T21:47:04.183525Z","steps":["trace[2127385076] 'process raft request' (duration: 521.322368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:04.189102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:47:03.658854Z","time spent":"530.164681ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:47:04.188984Z","caller":"traceutil/trace.go:171","msg":"trace[95322232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1801; }","duration":"484.05484ms","start":"2026-02-08T21:47:03.704914Z","end":"2026-02-08T21:47:04.188969Z","steps":["trace[95322232] 'agreement among raft nodes before linearized reading' (duration: 483.700542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:04.189448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.211675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:47:04.191905Z","caller":"traceutil/trace.go:171","msg":"trace[1868460069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1801; }","duration":"353.69361ms","start":"2026-02-08T21:47:03.838193Z","end":"2026-02-08T21:47:04.191886Z","steps":["trace[1868460069] 'agreement among raft nodes before linearized reading' (duration: 351.221326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:47:04.192216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:47:03.838173Z","time spent":"353.998856ms","remote":"127.0.0.1:46536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:47:04.191014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:47:03.704878Z","time spent":"486.115866ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}