{"level":"warn","ts":"2026-01-29T02:57:08.822181Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T02:57:08.822324Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.122: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.122:2380","--initial-cluster=instance=https://199.204.45.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.122:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-01-29T02:57:08.822428Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T02:57:08.822453Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-01-29T02:57:08.822479Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-29T02:57:08.823167Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"]} {"level":"info","ts":"2026-01-29T02:57:08.823308Z","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.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.122:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-01-29T02:57:08.828063Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.451615ms"} {"level":"info","ts":"2026-01-29T02:57:08.843551Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d2c3f297b56db0c4","cluster-id":"becafec23d27c587"} {"level":"info","ts":"2026-01-29T02:57:08.843682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=()"} {"level":"info","ts":"2026-01-29T02:57:08.843747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 0"} {"level":"info","ts":"2026-01-29T02:57:08.843775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d2c3f297b56db0c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-29T02:57:08.843824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 1"} {"level":"info","ts":"2026-01-29T02:57:08.843895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"warn","ts":"2026-01-29T02:57:08.857036Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-29T02:57:08.859824Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-29T02:57:08.86715Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-01-29T02:57:08.873982Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d2c3f297b56db0c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-29T02:57:08.874773Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d2c3f297b56db0c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-29T02:57:08.875451Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-29T02:57:08.880185Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-29T02:57:08.880226Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-29T02:57:08.877863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"info","ts":"2026-01-29T02:57:08.880458Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","added-peer-id":"d2c3f297b56db0c4","added-peer-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-01-29T02:57:08.895564Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-29T02:57:08.895792Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d2c3f297b56db0c4","initial-advertise-peer-urls":["https://199.204.45.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-29T02:57:08.898057Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-01-29T02:57:08.899136Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-01-29T02:57:08.899083Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-29T02:57:09.644148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 is starting a new election at term 1"} {"level":"info","ts":"2026-01-29T02:57:09.644194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-29T02:57:09.644225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgPreVoteResp from d2c3f297b56db0c4 at term 1"} {"level":"info","ts":"2026-01-29T02:57:09.644239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became candidate at term 2"} {"level":"info","ts":"2026-01-29T02:57:09.644248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgVoteResp from d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-01-29T02:57:09.644255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became leader at term 2"} {"level":"info","ts":"2026-01-29T02:57:09.644262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d2c3f297b56db0c4 elected leader d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-01-29T02:57:09.647437Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T02:57:09.649608Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d2c3f297b56db0c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.122:2379]}","request-path":"/0/members/d2c3f297b56db0c4/attributes","cluster-id":"becafec23d27c587","publish-timeout":"7s"} {"level":"info","ts":"2026-01-29T02:57:09.649627Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T02:57:09.64989Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T02:57:09.657058Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-29T02:57:09.673962Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-29T02:57:09.673976Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-29T02:57:09.674217Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T02:57:09.674263Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T02:57:09.674276Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T02:57:09.720802Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.122:2379"} {"level":"info","ts":"2026-01-29T02:57:37.570138Z","caller":"traceutil/trace.go:171","msg":"trace[1500991257] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:519; }","duration":"145.968473ms","start":"2026-01-29T02:57:37.424153Z","end":"2026-01-29T02:57:37.570122Z","steps":["trace[1500991257] 'read index received' (duration: 145.815109ms)","trace[1500991257] 'applied index is now lower than readState.Index' (duration: 152.504µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:57:37.570251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.090307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T02:57:37.570291Z","caller":"traceutil/trace.go:171","msg":"trace[1176262033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:506; }","duration":"146.190849ms","start":"2026-01-29T02:57:37.424094Z","end":"2026-01-29T02:57:37.570285Z","steps":["trace[1176262033] 'agreement among raft nodes before linearized reading' (duration: 146.102077ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:57:37.570378Z","caller":"traceutil/trace.go:171","msg":"trace[426536482] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"151.765384ms","start":"2026-01-29T02:57:37.418607Z","end":"2026-01-29T02:57:37.570372Z","steps":["trace[426536482] 'process raft request' (duration: 151.405894ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:57:37.746482Z","caller":"traceutil/trace.go:171","msg":"trace[865084822] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"172.57492ms","start":"2026-01-29T02:57:37.57389Z","end":"2026-01-29T02:57:37.746465Z","steps":["trace[865084822] 'process raft request' (duration: 165.360881ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:57:37.74767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.149778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-29T02:57:37.747702Z","caller":"traceutil/trace.go:171","msg":"trace[1605991128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:507; }","duration":"120.21631ms","start":"2026-01-29T02:57:37.627475Z","end":"2026-01-29T02:57:37.747692Z","steps":["trace[1605991128] 'agreement among raft nodes before linearized reading' (duration: 120.142278ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:57:37.748325Z","caller":"traceutil/trace.go:171","msg":"trace[1642134378] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:520; }","duration":"120.808617ms","start":"2026-01-29T02:57:37.627507Z","end":"2026-01-29T02:57:37.748316Z","steps":["trace[1642134378] 'read index received' (duration: 111.710105ms)","trace[1642134378] 'applied index is now lower than readState.Index' (duration: 6.786858ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T02:57:39.110671Z","caller":"traceutil/trace.go:171","msg":"trace[1552317961] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"110.355337ms","start":"2026-01-29T02:57:39.000291Z","end":"2026-01-29T02:57:39.110646Z","steps":["trace[1552317961] 'read index received' (duration: 110.008637ms)","trace[1552317961] 'applied index is now lower than readState.Index' (duration: 345.88µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:57:39.110764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.45348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T02:57:39.110812Z","caller":"traceutil/trace.go:171","msg":"trace[1675725325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"110.501211ms","start":"2026-01-29T02:57:39.000274Z","end":"2026-01-29T02:57:39.110775Z","steps":["trace[1675725325] 'agreement among raft nodes before linearized reading' (duration: 110.445039ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:57:39.110993Z","caller":"traceutil/trace.go:171","msg":"trace[617310679] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"121.815274ms","start":"2026-01-29T02:57:38.989172Z","end":"2026-01-29T02:57:39.110987Z","steps":["trace[617310679] 'process raft request' (duration: 121.155156ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:57:41.995837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.693279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T02:57:41.995879Z","caller":"traceutil/trace.go:171","msg":"trace[825949428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"235.766741ms","start":"2026-01-29T02:57:41.760103Z","end":"2026-01-29T02:57:41.995869Z","steps":["trace[825949428] 'range keys from in-memory index tree' (duration: 235.610957ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:57:41.996016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.593211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-29T02:57:41.996044Z","caller":"traceutil/trace.go:171","msg":"trace[2115019803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"233.629912ms","start":"2026-01-29T02:57:41.762409Z","end":"2026-01-29T02:57:41.996039Z","steps":["trace[2115019803] 'range keys from in-memory index tree' (duration: 233.546029ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:57:45.338427Z","caller":"traceutil/trace.go:171","msg":"trace[1556051887] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"127.750648ms","start":"2026-01-29T02:57:45.210657Z","end":"2026-01-29T02:57:45.338407Z","steps":["trace[1556051887] 'process raft request' (duration: 127.608024ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:57:47.664003Z","caller":"traceutil/trace.go:171","msg":"trace[1303810485] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"141.425316ms","start":"2026-01-29T02:57:47.522539Z","end":"2026-01-29T02:57:47.663964Z","steps":["trace[1303810485] 'process raft request' (duration: 141.267611ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:57:51.918107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.317364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T02:57:51.918435Z","caller":"traceutil/trace.go:171","msg":"trace[537016853] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"220.293512ms","start":"2026-01-29T02:57:51.698129Z","end":"2026-01-29T02:57:51.918423Z","steps":["trace[537016853] 'process raft request' (duration: 78.053792ms)","trace[537016853] 'compare' (duration: 141.15911ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T02:57:59.278656Z","caller":"traceutil/trace.go:171","msg":"trace[300811555] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"152.199816ms","start":"2026-01-29T02:57:59.126416Z","end":"2026-01-29T02:57:59.278615Z","steps":["trace[300811555] 'read index received' (duration: 152.035402ms)","trace[300811555] 'applied index is now lower than readState.Index' (duration: 163.564µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:57:59.278867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.427893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-29T02:57:59.278903Z","caller":"traceutil/trace.go:171","msg":"trace[1417396488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:668; }","duration":"152.504384ms","start":"2026-01-29T02:57:59.126386Z","end":"2026-01-29T02:57:59.27889Z","steps":["trace[1417396488] 'agreement among raft nodes before linearized reading' (duration: 152.337779ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:57:59.279139Z","caller":"traceutil/trace.go:171","msg":"trace[1704241280] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"198.888429ms","start":"2026-01-29T02:57:59.08024Z","end":"2026-01-29T02:57:59.279129Z","steps":["trace[1704241280] 'process raft request' (duration: 198.268062ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:57:59.844869Z","caller":"traceutil/trace.go:171","msg":"trace[1311785796] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"226.162715ms","start":"2026-01-29T02:57:59.618691Z","end":"2026-01-29T02:57:59.844854Z","steps":["trace[1311785796] 'process raft request' (duration: 226.036281ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:00.228343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.829296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T02:58:00.228442Z","caller":"traceutil/trace.go:171","msg":"trace[1208883136] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:691; }","duration":"376.666593ms","start":"2026-01-29T02:57:59.851766Z","end":"2026-01-29T02:58:00.228432Z","steps":["trace[1208883136] 'read index received' (duration: 207.694613ms)","trace[1208883136] 'applied index is now lower than readState.Index' (duration: 168.97147ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T02:58:00.228635Z","caller":"traceutil/trace.go:171","msg":"trace[1319059858] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"378.327559ms","start":"2026-01-29T02:57:59.850301Z","end":"2026-01-29T02:58:00.228628Z","steps":["trace[1319059858] 'process raft request' (duration: 209.151303ms)","trace[1319059858] 'compare' (duration: 168.745894ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:58:00.228665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:57:59.850289Z","time spent":"378.36057ms","remote":"127.0.0.1:57100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1292,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T02:58:00.228806Z","caller":"traceutil/trace.go:171","msg":"trace[737503785] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"378.3264ms","start":"2026-01-29T02:57:59.850474Z","end":"2026-01-29T02:58:00.228801Z","steps":["trace[737503785] 'process raft request' (duration: 377.927739ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:00.22884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:57:59.850468Z","time spent":"378.35891ms","remote":"127.0.0.1:57002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":783,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T02:58:00.228943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.175957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-tddj2\" ","response":"range_response_count:1 size:5026"} {"level":"info","ts":"2026-01-29T02:58:00.228956Z","caller":"traceutil/trace.go:171","msg":"trace[1521495594] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-tddj2; range_end:; response_count:1; response_revision:673; }","duration":"377.202928ms","start":"2026-01-29T02:57:59.851749Z","end":"2026-01-29T02:58:00.228952Z","steps":["trace[1521495594] 'agreement among raft nodes before linearized reading' (duration: 377.146926ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:00.228969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:57:59.851743Z","time spent":"377.223318ms","remote":"127.0.0.1:57020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5050,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-tddj2\" "} {"level":"warn","ts":"2026-01-29T02:58:00.229085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.129432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T02:58:00.229097Z","caller":"traceutil/trace.go:171","msg":"trace[1043879004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:673; }","duration":"283.169784ms","start":"2026-01-29T02:57:59.945923Z","end":"2026-01-29T02:58:00.229093Z","steps":["trace[1043879004] 'agreement among raft nodes before linearized reading' (duration: 283.105352ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:00.229245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.295001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-29T02:58:00.229258Z","caller":"traceutil/trace.go:171","msg":"trace[849696391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:673; }","duration":"103.328782ms","start":"2026-01-29T02:58:00.125926Z","end":"2026-01-29T02:58:00.229255Z","steps":["trace[849696391] 'agreement among raft nodes before linearized reading' (duration: 103.279681ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:00.229344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.677661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-29T02:58:00.229355Z","caller":"traceutil/trace.go:171","msg":"trace[176118631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:673; }","duration":"112.722082ms","start":"2026-01-29T02:58:00.11663Z","end":"2026-01-29T02:58:00.229352Z","steps":["trace[176118631] 'agreement among raft nodes before linearized reading' (duration: 112.689431ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:58:00.437453Z","caller":"traceutil/trace.go:171","msg":"trace[2111060861] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"147.523557ms","start":"2026-01-29T02:58:00.28991Z","end":"2026-01-29T02:58:00.437434Z","steps":["trace[2111060861] 'process raft request' (duration: 146.92485ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:58:00.442374Z","caller":"traceutil/trace.go:171","msg":"trace[230755690] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"152.337309ms","start":"2026-01-29T02:58:00.290024Z","end":"2026-01-29T02:58:00.442362Z","steps":["trace[230755690] 'process raft request' (duration: 152.270057ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:58:05.419422Z","caller":"traceutil/trace.go:171","msg":"trace[792209693] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"118.497412ms","start":"2026-01-29T02:58:05.3009Z","end":"2026-01-29T02:58:05.419398Z","steps":["trace[792209693] 'process raft request' (duration: 118.375039ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:06.152347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.34536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T02:58:06.152398Z","caller":"traceutil/trace.go:171","msg":"trace[542306419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"665.434053ms","start":"2026-01-29T02:58:05.486951Z","end":"2026-01-29T02:58:06.152385Z","steps":["trace[542306419] 'range keys from in-memory index tree' (duration: 665.227267ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:06.152424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:05.486938Z","time spent":"665.480734ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-29T02:58:06.331813Z","caller":"traceutil/trace.go:171","msg":"trace[706581950] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"170.357169ms","start":"2026-01-29T02:58:06.161436Z","end":"2026-01-29T02:58:06.331793Z","steps":["trace[706581950] 'process raft request' (duration: 169.697551ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:06.687792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.422423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T02:58:06.687883Z","caller":"traceutil/trace.go:171","msg":"trace[1266649400] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"346.727195ms","start":"2026-01-29T02:58:06.34114Z","end":"2026-01-29T02:58:06.687867Z","steps":["trace[1266649400] 'process raft request' (duration: 68.169678ms)","trace[1266649400] 'compare' (duration: 278.29221ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:58:06.687923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:06.341125Z","time spent":"346.780697ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T02:58:06.692861Z","caller":"traceutil/trace.go:171","msg":"trace[420971383] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"346.91907ms","start":"2026-01-29T02:58:06.34593Z","end":"2026-01-29T02:58:06.69285Z","steps":["trace[420971383] 'process raft request' (duration: 346.895909ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:06.692926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:06.345925Z","time spent":"346.967881ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T02:58:06.693136Z","caller":"traceutil/trace.go:171","msg":"trace[277000422] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"347.99815ms","start":"2026-01-29T02:58:06.345132Z","end":"2026-01-29T02:58:06.69313Z","steps":["trace[277000422] 'process raft request' (duration: 347.64375ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:06.693173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:06.34512Z","time spent":"348.033391ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T02:58:15.079592Z","caller":"traceutil/trace.go:171","msg":"trace[580131344] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"355.285202ms","start":"2026-01-29T02:58:14.724291Z","end":"2026-01-29T02:58:15.079577Z","steps":["trace[580131344] 'read index received' (duration: 355.115597ms)","trace[580131344] 'applied index is now lower than readState.Index' (duration: 168.795µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T02:58:15.07979Z","caller":"traceutil/trace.go:171","msg":"trace[1510138055] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"365.193345ms","start":"2026-01-29T02:58:14.714591Z","end":"2026-01-29T02:58:15.079784Z","steps":["trace[1510138055] 'process raft request' (duration: 364.893407ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:15.079864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:14.714581Z","time spent":"365.224147ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T02:58:15.080032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.740574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T02:58:15.080052Z","caller":"traceutil/trace.go:171","msg":"trace[1016180143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"355.772374ms","start":"2026-01-29T02:58:14.724271Z","end":"2026-01-29T02:58:15.080044Z","steps":["trace[1016180143] 'agreement among raft nodes before linearized reading' (duration: 355.695102ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:15.080066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:14.724264Z","time spent":"355.798965ms","remote":"127.0.0.1:57088","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-01-29T02:58:15.099392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.48839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T02:58:15.099436Z","caller":"traceutil/trace.go:171","msg":"trace[1414559913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:744; }","duration":"371.546161ms","start":"2026-01-29T02:58:14.727881Z","end":"2026-01-29T02:58:15.099427Z","steps":["trace[1414559913] 'agreement among raft nodes before linearized reading' (duration: 371.388687ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:15.099457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:14.727877Z","time spent":"371.576672ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T02:58:15.099592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.724207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T02:58:15.09961Z","caller":"traceutil/trace.go:171","msg":"trace[2056355052] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:744; }","duration":"184.767448ms","start":"2026-01-29T02:58:14.914836Z","end":"2026-01-29T02:58:15.099603Z","steps":["trace[2056355052] 'agreement among raft nodes before linearized reading' (duration: 184.728467ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:58:17.38238Z","caller":"traceutil/trace.go:171","msg":"trace[6771884] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"259.317733ms","start":"2026-01-29T02:58:17.123048Z","end":"2026-01-29T02:58:17.382365Z","steps":["trace[6771884] 'process raft request' (duration: 259.20674ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:58:17.410629Z","caller":"traceutil/trace.go:171","msg":"trace[1799792429] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"208.063603ms","start":"2026-01-29T02:58:17.202541Z","end":"2026-01-29T02:58:17.410605Z","steps":["trace[1799792429] 'read index received' (duration: 180.189401ms)","trace[1799792429] 'applied index is now lower than readState.Index' (duration: 27.873302ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:58:17.410839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.268429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T02:58:17.41088Z","caller":"traceutil/trace.go:171","msg":"trace[1623389686] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:752; }","duration":"208.367041ms","start":"2026-01-29T02:58:17.202498Z","end":"2026-01-29T02:58:17.410865Z","steps":["trace[1623389686] 'agreement among raft nodes before linearized reading' (duration: 208.193387ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:58:17.411096Z","caller":"traceutil/trace.go:171","msg":"trace[2102020953] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"287.439092ms","start":"2026-01-29T02:58:17.12364Z","end":"2026-01-29T02:58:17.411079Z","steps":["trace[2102020953] 'process raft request' (duration: 286.853676ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:58:18.00392Z","caller":"traceutil/trace.go:171","msg":"trace[856825385] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"449.049718ms","start":"2026-01-29T02:58:17.554838Z","end":"2026-01-29T02:58:18.003887Z","steps":["trace[856825385] 'process raft request' (duration: 448.917505ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:18.004059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:17.554784Z","time spent":"449.185922ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T02:58:18.606109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.114168ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-29T02:58:18.606215Z","caller":"traceutil/trace.go:171","msg":"trace[1262320236] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"606.298573ms","start":"2026-01-29T02:58:17.999894Z","end":"2026-01-29T02:58:18.606192Z","steps":["trace[1262320236] 'read index received' (duration: 4.605717ms)","trace[1262320236] 'applied index is now lower than readState.Index' (duration: 601.691866ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:58:18.606303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.407417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T02:58:18.60632Z","caller":"traceutil/trace.go:171","msg":"trace[1689954913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"606.457878ms","start":"2026-01-29T02:58:17.999856Z","end":"2026-01-29T02:58:18.606314Z","steps":["trace[1689954913] 'agreement among raft nodes before linearized reading' (duration: 606.413476ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:18.606342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:17.999841Z","time spent":"606.496949ms","remote":"127.0.0.1:50256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T02:58:18.606577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.14206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T02:58:18.606598Z","caller":"traceutil/trace.go:171","msg":"trace[630095269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:753; }","duration":"219.185821ms","start":"2026-01-29T02:58:18.387406Z","end":"2026-01-29T02:58:18.606592Z","steps":["trace[630095269] 'agreement among raft nodes before linearized reading' (duration: 219.091289ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:58:27.438412Z","caller":"traceutil/trace.go:171","msg":"trace[1684555803] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"299.66601ms","start":"2026-01-29T02:58:27.138721Z","end":"2026-01-29T02:58:27.438387Z","steps":["trace[1684555803] 'process raft request' (duration: 299.092105ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:27.677731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.911416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T02:58:27.677823Z","caller":"traceutil/trace.go:171","msg":"trace[784606423] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"194.640191ms","start":"2026-01-29T02:58:27.483168Z","end":"2026-01-29T02:58:27.677808Z","steps":["trace[784606423] 'process raft request' (duration: 89.589291ms)","trace[784606423] 'compare' (duration: 104.797033ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:58:28.161228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.315118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T02:58:28.161341Z","caller":"traceutil/trace.go:171","msg":"trace[1043018975] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:780; }","duration":"296.493833ms","start":"2026-01-29T02:58:27.864821Z","end":"2026-01-29T02:58:28.161315Z","steps":["trace[1043018975] 'count revisions from in-memory index tree' (duration: 296.159583ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:28.491591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.516641ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-29T02:58:28.491718Z","caller":"traceutil/trace.go:171","msg":"trace[1821209554] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"473.233809ms","start":"2026-01-29T02:58:28.018468Z","end":"2026-01-29T02:58:28.491701Z","steps":["trace[1821209554] 'read index received' (duration: 1.581364ms)","trace[1821209554] 'applied index is now lower than readState.Index' (duration: 471.650895ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:58:28.491955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.282866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-29T02:58:28.491985Z","caller":"traceutil/trace.go:171","msg":"trace[2027950146] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:780; }","duration":"453.374908ms","start":"2026-01-29T02:58:28.038601Z","end":"2026-01-29T02:58:28.491976Z","steps":["trace[2027950146] 'agreement among raft nodes before linearized reading' (duration: 453.229844ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:28.492018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:28.03859Z","time spent":"453.419479ms","remote":"127.0.0.1:57088","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-01-29T02:58:28.493779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.221874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T02:58:28.493883Z","caller":"traceutil/trace.go:171","msg":"trace[561620798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"475.414619ms","start":"2026-01-29T02:58:28.018441Z","end":"2026-01-29T02:58:28.493855Z","steps":["trace[561620798] 'agreement among raft nodes before linearized reading' (duration: 473.363763ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:28.493953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:28.018427Z","time spent":"475.516352ms","remote":"127.0.0.1:50256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T02:58:28.835196Z","caller":"traceutil/trace.go:171","msg":"trace[540149150] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"141.18585ms","start":"2026-01-29T02:58:28.693973Z","end":"2026-01-29T02:58:28.835159Z","steps":["trace[540149150] 'process raft request' (duration: 140.909273ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:58:39.908112Z","caller":"traceutil/trace.go:171","msg":"trace[688571066] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:892; }","duration":"288.198489ms","start":"2026-01-29T02:58:39.619874Z","end":"2026-01-29T02:58:39.908072Z","steps":["trace[688571066] 'read index received' (duration: 288.003284ms)","trace[688571066] 'applied index is now lower than readState.Index' (duration: 194.255µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T02:58:39.908259Z","caller":"traceutil/trace.go:171","msg":"trace[1456608968] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"314.945704ms","start":"2026-01-29T02:58:39.593305Z","end":"2026-01-29T02:58:39.908251Z","steps":["trace[1456608968] 'process raft request' (duration: 314.610655ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:39.908431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:39.593294Z","time spent":"315.000556ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T02:58:39.908511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.234834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T02:58:39.908591Z","caller":"traceutil/trace.go:171","msg":"trace[62738166] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:862; }","duration":"230.373618ms","start":"2026-01-29T02:58:39.678197Z","end":"2026-01-29T02:58:39.908571Z","steps":["trace[62738166] 'agreement among raft nodes before linearized reading' (duration: 230.236064ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:39.908849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.979251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T02:58:39.908867Z","caller":"traceutil/trace.go:171","msg":"trace[265851702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:862; }","duration":"289.024442ms","start":"2026-01-29T02:58:39.619835Z","end":"2026-01-29T02:58:39.90886Z","steps":["trace[265851702] 'agreement among raft nodes before linearized reading' (duration: 288.931129ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:39.90898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.589782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T02:58:39.909012Z","caller":"traceutil/trace.go:171","msg":"trace[792906748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:862; }","duration":"184.685115ms","start":"2026-01-29T02:58:39.724319Z","end":"2026-01-29T02:58:39.909004Z","steps":["trace[792906748] 'agreement among raft nodes before linearized reading' (duration: 184.50816ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:58:41.074138Z","caller":"traceutil/trace.go:171","msg":"trace[1705419871] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"170.843969ms","start":"2026-01-29T02:58:40.903275Z","end":"2026-01-29T02:58:41.074119Z","steps":["trace[1705419871] 'process raft request' (duration: 170.756457ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:58:42.260912Z","caller":"traceutil/trace.go:171","msg":"trace[2011528864] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"278.379205ms","start":"2026-01-29T02:58:41.982516Z","end":"2026-01-29T02:58:42.260895Z","steps":["trace[2011528864] 'process raft request' (duration: 229.754671ms)","trace[2011528864] 'compare' (duration: 48.554942ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T02:58:42.292374Z","caller":"traceutil/trace.go:171","msg":"trace[2143162918] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:898; }","duration":"286.618954ms","start":"2026-01-29T02:58:42.005727Z","end":"2026-01-29T02:58:42.292346Z","steps":["trace[2143162918] 'read index received' (duration: 206.561034ms)","trace[2143162918] 'applied index is now lower than readState.Index' (duration: 80.05722ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T02:58:42.294115Z","caller":"traceutil/trace.go:171","msg":"trace[705749676] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"310.428838ms","start":"2026-01-29T02:58:41.983658Z","end":"2026-01-29T02:58:42.294087Z","steps":["trace[705749676] 'process raft request' (duration: 308.534365ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:42.296154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:41.983648Z","time spent":"312.391253ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T02:58:42.294201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.457575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T02:58:42.296459Z","caller":"traceutil/trace.go:171","msg":"trace[524392167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:869; }","duration":"290.737999ms","start":"2026-01-29T02:58:42.005707Z","end":"2026-01-29T02:58:42.296445Z","steps":["trace[524392167] 'agreement among raft nodes before linearized reading' (duration: 288.454496ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:42.735159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.639354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-29T02:58:42.735257Z","caller":"traceutil/trace.go:171","msg":"trace[1975604007] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:869; }","duration":"105.778267ms","start":"2026-01-29T02:58:42.629454Z","end":"2026-01-29T02:58:42.735233Z","steps":["trace[1975604007] 'range keys from in-memory index tree' (duration: 105.443138ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:43.070699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.958956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T02:58:43.070923Z","caller":"traceutil/trace.go:171","msg":"trace[153717722] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"320.584101ms","start":"2026-01-29T02:58:42.750327Z","end":"2026-01-29T02:58:43.070911Z","steps":["trace[153717722] 'process raft request' (duration: 113.365108ms)","trace[153717722] 'compare' (duration: 206.829342ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:58:43.070956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:42.750312Z","time spent":"320.627583ms","remote":"127.0.0.1:50306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T02:58:46.32574Z","caller":"traceutil/trace.go:171","msg":"trace[224168907] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:910; }","duration":"324.84711ms","start":"2026-01-29T02:58:46.000865Z","end":"2026-01-29T02:58:46.325712Z","steps":["trace[224168907] 'read index received' (duration: 324.603093ms)","trace[224168907] 'applied index is now lower than readState.Index' (duration: 243.337µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T02:58:46.325883Z","caller":"traceutil/trace.go:171","msg":"trace[477717627] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"339.808117ms","start":"2026-01-29T02:58:45.986069Z","end":"2026-01-29T02:58:46.325877Z","steps":["trace[477717627] 'process raft request' (duration: 339.472047ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:46.326007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.271335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-01-29T02:58:46.326038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:45.986036Z","time spent":"339.869038ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T02:58:46.326069Z","caller":"traceutil/trace.go:171","msg":"trace[1743754814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:879; }","duration":"305.348897ms","start":"2026-01-29T02:58:46.020703Z","end":"2026-01-29T02:58:46.326052Z","steps":["trace[1743754814] 'agreement among raft nodes before linearized reading' (duration: 305.211233ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:46.3261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:46.020696Z","time spent":"305.398728ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T02:58:46.326196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.340714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T02:58:46.326211Z","caller":"traceutil/trace.go:171","msg":"trace[1235938444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:879; }","duration":"325.381115ms","start":"2026-01-29T02:58:46.000823Z","end":"2026-01-29T02:58:46.326204Z","steps":["trace[1235938444] 'agreement among raft nodes before linearized reading' (duration: 325.361884ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:46.326223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:46.000803Z","time spent":"325.417826ms","remote":"127.0.0.1:50256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T02:58:53.151468Z","caller":"traceutil/trace.go:171","msg":"trace[1623204710] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"148.186119ms","start":"2026-01-29T02:58:53.003264Z","end":"2026-01-29T02:58:53.15145Z","steps":["trace[1623204710] 'read index received' (duration: 147.568382ms)","trace[1623204710] 'applied index is now lower than readState.Index' (duration: 617.107µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T02:58:53.15181Z","caller":"traceutil/trace.go:171","msg":"trace[1442856831] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"294.667609ms","start":"2026-01-29T02:58:52.857109Z","end":"2026-01-29T02:58:53.151776Z","steps":["trace[1442856831] 'process raft request' (duration: 293.780374ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:53.151973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.692793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T02:58:53.152Z","caller":"traceutil/trace.go:171","msg":"trace[1817433742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"148.776005ms","start":"2026-01-29T02:58:53.003216Z","end":"2026-01-29T02:58:53.151992Z","steps":["trace[1817433742] 'agreement among raft nodes before linearized reading' (duration: 148.725023ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:58:53.273517Z","caller":"traceutil/trace.go:171","msg":"trace[761213954] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"115.315483ms","start":"2026-01-29T02:58:53.15807Z","end":"2026-01-29T02:58:53.273385Z","steps":["trace[761213954] 'process raft request' (duration: 75.196425ms)","trace[761213954] 'compare' (duration: 40.001894ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T02:58:54.903474Z","caller":"traceutil/trace.go:171","msg":"trace[2048251814] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"354.172927ms","start":"2026-01-29T02:58:54.549274Z","end":"2026-01-29T02:58:54.903447Z","steps":["trace[2048251814] 'process raft request' (duration: 354.020843ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:54.90363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:54.54926Z","time spent":"354.28586ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T02:58:54.904036Z","caller":"traceutil/trace.go:171","msg":"trace[1371244534] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:936; }","duration":"354.64016ms","start":"2026-01-29T02:58:54.549388Z","end":"2026-01-29T02:58:54.904028Z","steps":["trace[1371244534] 'read index received' (duration: 354.6338ms)","trace[1371244534] 'applied index is now lower than readState.Index' (duration: 5.64µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:58:54.904157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.764174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T02:58:54.904176Z","caller":"traceutil/trace.go:171","msg":"trace[1876589002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:902; }","duration":"354.793164ms","start":"2026-01-29T02:58:54.549377Z","end":"2026-01-29T02:58:54.90417Z","steps":["trace[1876589002] 'agreement among raft nodes before linearized reading' (duration: 354.692081ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:54.904192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:54.549373Z","time spent":"354.815765ms","remote":"127.0.0.1:57088","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-01-29T02:58:54.904289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.392973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T02:58:54.904302Z","caller":"traceutil/trace.go:171","msg":"trace[464644112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:902; }","duration":"354.413644ms","start":"2026-01-29T02:58:54.549883Z","end":"2026-01-29T02:58:54.904297Z","steps":["trace[464644112] 'agreement among raft nodes before linearized reading' (duration: 354.368772ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:54.904315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:54.549879Z","time spent":"354.432564ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T02:58:54.904406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.054689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T02:58:54.904423Z","caller":"traceutil/trace.go:171","msg":"trace[571878593] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:902; }","duration":"324.092029ms","start":"2026-01-29T02:58:54.580325Z","end":"2026-01-29T02:58:54.904417Z","steps":["trace[571878593] 'agreement among raft nodes before linearized reading' (duration: 324.053128ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:54.904436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:54.580313Z","time spent":"324.119079ms","remote":"127.0.0.1:57142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-01-29T02:58:55.481842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.60682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T02:58:55.481987Z","caller":"traceutil/trace.go:171","msg":"trace[939379840] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:936; }","duration":"480.690211ms","start":"2026-01-29T02:58:55.00128Z","end":"2026-01-29T02:58:55.481971Z","steps":["trace[939379840] 'read index received' (duration: 364.817063ms)","trace[939379840] 'applied index is now lower than readState.Index' (duration: 115.872178ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T02:58:55.482128Z","caller":"traceutil/trace.go:171","msg":"trace[1195798828] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"568.397775ms","start":"2026-01-29T02:58:54.91372Z","end":"2026-01-29T02:58:55.482118Z","steps":["trace[1195798828] 'process raft request' (duration: 452.419844ms)","trace[1195798828] 'compare' (duration: 115.285162ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:58:55.482165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:54.9137Z","time spent":"568.445576ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T02:58:55.48228Z","caller":"traceutil/trace.go:171","msg":"trace[1645079149] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"567.842149ms","start":"2026-01-29T02:58:54.914434Z","end":"2026-01-29T02:58:55.482276Z","steps":["trace[1645079149] 'process raft request' (duration: 567.49999ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:55.482304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:54.914428Z","time spent":"567.86255ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T02:58:55.482392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.120994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T02:58:55.482407Z","caller":"traceutil/trace.go:171","msg":"trace[724411940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"481.187575ms","start":"2026-01-29T02:58:55.001214Z","end":"2026-01-29T02:58:55.482401Z","steps":["trace[724411940] 'agreement among raft nodes before linearized reading' (duration: 481.158504ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:55.482424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:55.001189Z","time spent":"481.229656ms","remote":"127.0.0.1:50260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T02:58:55.482577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.64916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-29T02:58:55.482592Z","caller":"traceutil/trace.go:171","msg":"trace[930224513] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:904; }","duration":"326.717542ms","start":"2026-01-29T02:58:55.155867Z","end":"2026-01-29T02:58:55.482585Z","steps":["trace[930224513] 'agreement among raft nodes before linearized reading' (duration: 326.6306ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:58:55.482606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:58:55.155839Z","time spent":"326.762223ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-29T02:58:55.48274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.8954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-29T02:58:55.482755Z","caller":"traceutil/trace.go:171","msg":"trace[1921827352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:904; }","duration":"203.942992ms","start":"2026-01-29T02:58:55.278807Z","end":"2026-01-29T02:58:55.48275Z","steps":["trace[1921827352] 'agreement among raft nodes before linearized reading' (duration: 203.90158ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:59:00.106582Z","caller":"traceutil/trace.go:171","msg":"trace[310548617] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"227.007714ms","start":"2026-01-29T02:58:59.879553Z","end":"2026-01-29T02:59:00.10656Z","steps":["trace[310548617] 'process raft request' (duration: 226.86745ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:59:00.106955Z","caller":"traceutil/trace.go:171","msg":"trace[339978111] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:955; }","duration":"179.942813ms","start":"2026-01-29T02:58:59.927004Z","end":"2026-01-29T02:59:00.106947Z","steps":["trace[339978111] 'read index received' (duration: 179.938583ms)","trace[339978111] 'applied index is now lower than readState.Index' (duration: 3.32µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:59:00.107068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.483871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T02:59:00.107089Z","caller":"traceutil/trace.go:171","msg":"trace[1274225697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"104.577814ms","start":"2026-01-29T02:59:00.002504Z","end":"2026-01-29T02:59:00.107082Z","steps":["trace[1274225697] 'agreement among raft nodes before linearized reading' (duration: 104.485711ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:59:00.107304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.306583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4740"} {"level":"info","ts":"2026-01-29T02:59:00.107323Z","caller":"traceutil/trace.go:171","msg":"trace[204922733] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:920; }","duration":"180.351814ms","start":"2026-01-29T02:58:59.926965Z","end":"2026-01-29T02:59:00.107317Z","steps":["trace[204922733] 'agreement among raft nodes before linearized reading' (duration: 180.251032ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:59:00.511604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.116068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-29T02:59:00.511702Z","caller":"traceutil/trace.go:171","msg":"trace[706075700] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"315.694565ms","start":"2026-01-29T02:59:00.195993Z","end":"2026-01-29T02:59:00.511688Z","steps":["trace[706075700] 'read index received' (duration: 42.35581ms)","trace[706075700] 'applied index is now lower than readState.Index' (duration: 273.338075ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:59:00.511819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.822138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T02:59:00.511832Z","caller":"traceutil/trace.go:171","msg":"trace[144228577] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:921; }","duration":"315.891181ms","start":"2026-01-29T02:59:00.195936Z","end":"2026-01-29T02:59:00.511827Z","steps":["trace[144228577] 'agreement among raft nodes before linearized reading' (duration: 315.783838ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:59:00.511851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:59:00.195908Z","time spent":"315.938142ms","remote":"127.0.0.1:57226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"info","ts":"2026-01-29T02:59:00.512003Z","caller":"traceutil/trace.go:171","msg":"trace[927374404] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"391.957259ms","start":"2026-01-29T02:59:00.120039Z","end":"2026-01-29T02:59:00.511996Z","steps":["trace[927374404] 'process raft request' (duration: 118.390658ms)","trace[927374404] 'compare' (duration: 272.968965ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:59:00.512033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:59:00.120027Z","time spent":"391.994151ms","remote":"127.0.0.1:50360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":799,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T02:59:01.224949Z","caller":"traceutil/trace.go:171","msg":"trace[2070235258] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"221.4733ms","start":"2026-01-29T02:59:01.003446Z","end":"2026-01-29T02:59:01.224919Z","steps":["trace[2070235258] 'read index received' (duration: 221.206392ms)","trace[2070235258] 'applied index is now lower than readState.Index' (duration: 265.948µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:59:01.225143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.662206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T02:59:01.225188Z","caller":"traceutil/trace.go:171","msg":"trace[81690058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"221.737428ms","start":"2026-01-29T02:59:01.00344Z","end":"2026-01-29T02:59:01.225178Z","steps":["trace[81690058] 'agreement among raft nodes before linearized reading' (duration: 221.591314ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:59:01.225653Z","caller":"traceutil/trace.go:171","msg":"trace[1062063852] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"244.385509ms","start":"2026-01-29T02:59:00.981253Z","end":"2026-01-29T02:59:01.225639Z","steps":["trace[1062063852] 'process raft request' (duration: 243.485344ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:59:01.856301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.167313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T02:59:01.856504Z","caller":"traceutil/trace.go:171","msg":"trace[2039285029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:922; }","duration":"300.537333ms","start":"2026-01-29T02:59:01.555925Z","end":"2026-01-29T02:59:01.856462Z","steps":["trace[2039285029] 'range keys from in-memory index tree' (duration: 300.021929ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:59:01.856638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:59:01.555904Z","time spent":"300.666087ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T02:59:01.857333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.80334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T02:59:01.857365Z","caller":"traceutil/trace.go:171","msg":"trace[1867495813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:922; }","duration":"286.877423ms","start":"2026-01-29T02:59:01.570477Z","end":"2026-01-29T02:59:01.857355Z","steps":["trace[1867495813] 'range keys from in-memory index tree' (duration: 286.591944ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:59:01.857661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.103651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T02:59:01.857699Z","caller":"traceutil/trace.go:171","msg":"trace[1443137476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:922; }","duration":"286.151592ms","start":"2026-01-29T02:59:01.571537Z","end":"2026-01-29T02:59:01.857689Z","steps":["trace[1443137476] 'range keys from in-memory index tree' (duration: 286.019169ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:59:01.860478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.809108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-29T02:59:01.860524Z","caller":"traceutil/trace.go:171","msg":"trace[277103474] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:922; }","duration":"287.867019ms","start":"2026-01-29T02:59:01.572646Z","end":"2026-01-29T02:59:01.860513Z","steps":["trace[277103474] 'range keys from in-memory index tree' (duration: 287.734416ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:59:02.030289Z","caller":"traceutil/trace.go:171","msg":"trace[1361544611] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"147.252503ms","start":"2026-01-29T02:59:01.882986Z","end":"2026-01-29T02:59:02.030239Z","steps":["trace[1361544611] 'process raft request' (duration: 147.117679ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:59:02.309343Z","caller":"traceutil/trace.go:171","msg":"trace[1811351452] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"426.173812ms","start":"2026-01-29T02:59:01.88314Z","end":"2026-01-29T02:59:02.309314Z","steps":["trace[1811351452] 'process raft request' (duration: 342.879762ms)","trace[1811351452] 'compare' (duration: 82.670613ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T02:59:02.309423Z","caller":"traceutil/trace.go:171","msg":"trace[670048821] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:957; }","duration":"301.349925ms","start":"2026-01-29T02:59:02.008038Z","end":"2026-01-29T02:59:02.309388Z","steps":["trace[670048821] 'read index received' (duration: 22.168578ms)","trace[670048821] 'applied index is now lower than readState.Index' (duration: 279.156287ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:59:02.3095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:59:01.883106Z","time spent":"426.309446ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T02:59:02.309624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.546091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T02:59:02.309662Z","caller":"traceutil/trace.go:171","msg":"trace[1903673801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"301.645684ms","start":"2026-01-29T02:59:02.008005Z","end":"2026-01-29T02:59:02.309651Z","steps":["trace[1903673801] 'agreement among raft nodes before linearized reading' (duration: 301.50719ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:59:02.3097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:59:02.007989Z","time spent":"301.703015ms","remote":"127.0.0.1:50256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T02:59:02.30986Z","caller":"traceutil/trace.go:171","msg":"trace[2058833746] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"426.600394ms","start":"2026-01-29T02:59:01.883248Z","end":"2026-01-29T02:59:02.309848Z","steps":["trace[2058833746] 'process raft request' (duration: 425.936076ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:59:02.30993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:59:01.883241Z","time spent":"426.648746ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T02:59:02.310006Z","caller":"traceutil/trace.go:171","msg":"trace[2048625151] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"407.625436ms","start":"2026-01-29T02:59:01.902363Z","end":"2026-01-29T02:59:02.309989Z","steps":["trace[2048625151] 'process raft request' (duration: 406.947597ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:59:02.310133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:59:01.902318Z","time spent":"407.719939ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T02:59:03.234227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.721934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T02:59:03.234296Z","caller":"traceutil/trace.go:171","msg":"trace[1515203654] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"477.145132ms","start":"2026-01-29T02:59:02.757141Z","end":"2026-01-29T02:59:03.234286Z","steps":["trace[1515203654] 'read index received' (duration: 44.209631ms)","trace[1515203654] 'applied index is now lower than readState.Index' (duration: 432.934331ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T02:59:03.234356Z","caller":"traceutil/trace.go:171","msg":"trace[1150772795] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"580.169652ms","start":"2026-01-29T02:59:02.654178Z","end":"2026-01-29T02:59:03.234348Z","steps":["trace[1150772795] 'process raft request' (duration: 147.262862ms)","trace[1150772795] 'compare' (duration: 432.491938ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:59:03.234389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:59:02.65415Z","time spent":"580.221122ms","remote":"127.0.0.1:50306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T02:59:03.234548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.416939ms","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-01-29T02:59:03.234562Z","caller":"traceutil/trace.go:171","msg":"trace[1790682380] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:927; }","duration":"477.475492ms","start":"2026-01-29T02:59:02.757082Z","end":"2026-01-29T02:59:03.234558Z","steps":["trace[1790682380] 'agreement among raft nodes before linearized reading' (duration: 477.41353ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:59:03.234578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:59:02.757058Z","time spent":"477.516462ms","remote":"127.0.0.1:56958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-01-29T02:59:03.234654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.187204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T02:59:03.234667Z","caller":"traceutil/trace.go:171","msg":"trace[954679635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"220.198915ms","start":"2026-01-29T02:59:03.014464Z","end":"2026-01-29T02:59:03.234662Z","steps":["trace[954679635] 'agreement among raft nodes before linearized reading' (duration: 220.177944ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:59:06.464399Z","caller":"traceutil/trace.go:171","msg":"trace[1734421404] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"133.51785ms","start":"2026-01-29T02:59:06.33086Z","end":"2026-01-29T02:59:06.464378Z","steps":["trace[1734421404] 'process raft request' (duration: 133.229462ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:59:06.464694Z","caller":"traceutil/trace.go:171","msg":"trace[1458006897] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:987; }","duration":"130.29365ms","start":"2026-01-29T02:59:06.33439Z","end":"2026-01-29T02:59:06.464684Z","steps":["trace[1458006897] 'read index received' (duration: 129.621381ms)","trace[1458006897] 'applied index is now lower than readState.Index' (duration: 671.649µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T02:59:06.464844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.052273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T02:59:06.46487Z","caller":"traceutil/trace.go:171","msg":"trace[593633598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:952; }","duration":"130.107665ms","start":"2026-01-29T02:59:06.334756Z","end":"2026-01-29T02:59:06.464863Z","steps":["trace[593633598] 'agreement among raft nodes before linearized reading' (duration: 129.989162ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:59:06.464976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.583358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-29T02:59:06.464995Z","caller":"traceutil/trace.go:171","msg":"trace[2109819230] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:952; }","duration":"130.612609ms","start":"2026-01-29T02:59:06.334378Z","end":"2026-01-29T02:59:06.464991Z","steps":["trace[2109819230] 'agreement among raft nodes before linearized reading' (duration: 130.564847ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T02:59:19.793607Z","caller":"traceutil/trace.go:171","msg":"trace[745425837] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"345.150004ms","start":"2026-01-29T02:59:19.448426Z","end":"2026-01-29T02:59:19.793576Z","steps":["trace[745425837] 'process raft request' (duration: 344.659361ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T02:59:19.793991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T02:59:19.448409Z","time spent":"345.472363ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T03:02:24.762544Z","caller":"traceutil/trace.go:171","msg":"trace[1895886666] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"104.119247ms","start":"2026-01-29T03:02:24.6584Z","end":"2026-01-29T03:02:24.76252Z","steps":["trace[1895886666] 'process raft request' (duration: 103.972673ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T03:02:25.820735Z","caller":"traceutil/trace.go:171","msg":"trace[1645204043] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1898; }","duration":"233.867613ms","start":"2026-01-29T03:02:25.586853Z","end":"2026-01-29T03:02:25.820721Z","steps":["trace[1645204043] 'read index received' (duration: 233.733919ms)","trace[1645204043] 'applied index is now lower than readState.Index' (duration: 131.504µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T03:02:25.820895Z","caller":"traceutil/trace.go:171","msg":"trace[1647936144] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"234.998874ms","start":"2026-01-29T03:02:25.58589Z","end":"2026-01-29T03:02:25.820889Z","steps":["trace[1647936144] 'process raft request' (duration: 234.736697ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T03:02:25.821047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.179242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-29T03:02:25.821063Z","caller":"traceutil/trace.go:171","msg":"trace[1154915264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1822; }","duration":"234.221403ms","start":"2026-01-29T03:02:25.586836Z","end":"2026-01-29T03:02:25.821058Z","steps":["trace[1154915264] 'agreement among raft nodes before linearized reading' (duration: 234.144731ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T03:02:25.821146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.524438ms","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-01-29T03:02:25.821157Z","caller":"traceutil/trace.go:171","msg":"trace[1806963967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1822; }","duration":"177.575849ms","start":"2026-01-29T03:02:25.643578Z","end":"2026-01-29T03:02:25.821154Z","steps":["trace[1806963967] 'agreement among raft nodes before linearized reading' (duration: 177.541558ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T03:02:25.995891Z","caller":"traceutil/trace.go:171","msg":"trace[1901167844] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"170.106016ms","start":"2026-01-29T03:02:25.825764Z","end":"2026-01-29T03:02:25.99587Z","steps":["trace[1901167844] 'process raft request' (duration: 150.593405ms)","trace[1901167844] 'compare' (duration: 19.256654ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T03:02:34.077276Z","caller":"traceutil/trace.go:171","msg":"trace[2106791805] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"153.854493ms","start":"2026-01-29T03:02:33.923334Z","end":"2026-01-29T03:02:34.077188Z","steps":["trace[2106791805] 'read index received' (duration: 153.604026ms)","trace[2106791805] 'applied index is now lower than readState.Index' (duration: 249.527µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T03:02:34.077392Z","caller":"traceutil/trace.go:171","msg":"trace[1352753987] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"155.476737ms","start":"2026-01-29T03:02:33.921902Z","end":"2026-01-29T03:02:34.077379Z","steps":["trace[1352753987] 'process raft request' (duration: 155.094057ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T03:02:34.07759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.238393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T03:02:34.077634Z","caller":"traceutil/trace.go:171","msg":"trace[617267730] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1861; }","duration":"154.327436ms","start":"2026-01-29T03:02:33.923294Z","end":"2026-01-29T03:02:34.077622Z","steps":["trace[617267730] 'agreement among raft nodes before linearized reading' (duration: 154.249014ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T03:02:34.078296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.226808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-29T03:02:34.078337Z","caller":"traceutil/trace.go:171","msg":"trace[1610968938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1861; }","duration":"112.270659ms","start":"2026-01-29T03:02:33.966056Z","end":"2026-01-29T03:02:34.078327Z","steps":["trace[1610968938] 'agreement among raft nodes before linearized reading' (duration: 112.063363ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T03:02:34.255932Z","caller":"traceutil/trace.go:171","msg":"trace[1447521585] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"165.377366ms","start":"2026-01-29T03:02:34.090536Z","end":"2026-01-29T03:02:34.255913Z","steps":["trace[1447521585] 'process raft request' (duration: 74.986203ms)","trace[1447521585] 'compare' (duration: 90.122086ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T03:02:34.256078Z","caller":"traceutil/trace.go:171","msg":"trace[1576141518] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"165.48121ms","start":"2026-01-29T03:02:34.090582Z","end":"2026-01-29T03:02:34.256064Z","steps":["trace[1576141518] 'process raft request' (duration: 165.247563ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T03:02:34.25636Z","caller":"traceutil/trace.go:171","msg":"trace[706476381] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"165.743986ms","start":"2026-01-29T03:02:34.090608Z","end":"2026-01-29T03:02:34.256352Z","steps":["trace[706476381] 'process raft request' (duration: 165.427338ms)"],"step_count":1}