{"level":"warn","ts":"2026-02-26T10:17:48.052711Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T10:17:48.052787Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-26T10:17:48.052851Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T10:17:48.052869Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-02-26T10:17:48.052902Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-26T10:17:48.053621Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-02-26T10:17:48.053698Z","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":8,"max-cpu-available":8,"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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-26T10:17:48.058593Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.533536ms"} {"level":"info","ts":"2026-02-26T10:17:48.067196Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-02-26T10:17:48.067255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T10:17:48.067354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-02-26T10:17:48.067368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T10:17:48.067375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-02-26T10:17:48.067443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-02-26T10:17:48.073194Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T10:17:48.076136Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T10:17:48.07769Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-26T10:17:48.079809Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T10:17:48.081436Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T10:17:48.081591Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T10:17:48.081632Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T10:17:48.08164Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T10:17:48.081844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-02-26T10:17:48.08193Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-02-26T10:17:48.08266Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-26T10:17:48.082852Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T10:17:48.082922Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T10:17:48.08304Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-02-26T10:17:48.083055Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-02-26T10:17:48.668497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T10:17:48.668558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T10:17:48.66864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-02-26T10:17:48.66867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-02-26T10:17:48.668683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-02-26T10:17:48.668737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-02-26T10:17:48.66876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-02-26T10:17:48.669828Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T10:17:48.670832Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T10:17:48.670834Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T10:17:48.670867Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T10:17:48.671108Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T10:17:48.671135Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T10:17:48.671384Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T10:17:48.671515Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T10:17:48.671553Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T10:17:48.675627Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T10:17:48.67638Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-02-26T10:17:54.087623Z","caller":"traceutil/trace.go:171","msg":"trace[2021503167] linearizableReadLoop","detail":"{readStateIndex:304; appliedIndex:303; }","duration":"152.712739ms","start":"2026-02-26T10:17:53.934884Z","end":"2026-02-26T10:17:54.087596Z","steps":["trace[2021503167] 'read index received' (duration: 150.836395ms)","trace[2021503167] 'applied index is now lower than readState.Index' (duration: 1.875304ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:17:54.087753Z","caller":"traceutil/trace.go:171","msg":"trace[37238778] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"182.481068ms","start":"2026-02-26T10:17:53.905246Z","end":"2026-02-26T10:17:54.087728Z","steps":["trace[37238778] 'process raft request' (duration: 180.539573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:17:54.087817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.893764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:17:54.087943Z","caller":"traceutil/trace.go:171","msg":"trace[1483501963] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:295; }","duration":"153.101418ms","start":"2026-02-26T10:17:53.934828Z","end":"2026-02-26T10:17:54.08793Z","steps":["trace[1483501963] 'agreement among raft nodes before linearized reading' (duration: 152.884433ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:17:55.600406Z","caller":"traceutil/trace.go:171","msg":"trace[1228316704] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"106.106309ms","start":"2026-02-26T10:17:55.494229Z","end":"2026-02-26T10:17:55.600336Z","steps":["trace[1228316704] 'process raft request' (duration: 105.863203ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:17:55.81383Z","caller":"traceutil/trace.go:171","msg":"trace[313600428] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"278.121225ms","start":"2026-02-26T10:17:55.535674Z","end":"2026-02-26T10:17:55.813796Z","steps":["trace[313600428] 'process raft request' (duration: 277.91396ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:18:01.450311Z","caller":"traceutil/trace.go:171","msg":"trace[1359690545] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"205.907471ms","start":"2026-02-26T10:18:01.24438Z","end":"2026-02-26T10:18:01.450288Z","steps":["trace[1359690545] 'process raft request' (duration: 205.753388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:02.425594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.321108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4325"} {"level":"warn","ts":"2026-02-26T10:18:02.425639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.930688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T10:18:02.425723Z","caller":"traceutil/trace.go:171","msg":"trace[1690381767] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:396; }","duration":"886.521402ms","start":"2026-02-26T10:18:01.539187Z","end":"2026-02-26T10:18:02.425709Z","steps":["trace[1690381767] 'range keys from in-memory index tree' (duration: 886.199445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:02.425906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:01.539151Z","time spent":"886.746247ms","remote":"127.0.0.1:53130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-26T10:18:02.425697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.0034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T10:18:02.426062Z","caller":"traceutil/trace.go:171","msg":"trace[1022404222] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:396; }","duration":"791.44686ms","start":"2026-02-26T10:18:01.634587Z","end":"2026-02-26T10:18:02.426034Z","steps":["trace[1022404222] 'range keys from in-memory index tree' (duration: 790.845675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:02.425717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.192394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:18:02.426193Z","caller":"traceutil/trace.go:171","msg":"trace[620232422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:396; }","duration":"539.747087ms","start":"2026-02-26T10:18:01.88642Z","end":"2026-02-26T10:18:02.426168Z","steps":["trace[620232422] 'range keys from in-memory index tree' (duration: 539.081642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:02.42626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:01.886392Z","time spent":"539.852559ms","remote":"127.0.0.1:52950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T10:18:02.425763Z","caller":"traceutil/trace.go:171","msg":"trace[1052807136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:396; }","duration":"788.128683ms","start":"2026-02-26T10:18:01.63761Z","end":"2026-02-26T10:18:02.425739Z","steps":["trace[1052807136] 'range keys from in-memory index tree' (duration: 787.800565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:02.425815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.642427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-26T10:18:02.426386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:01.637594Z","time spent":"788.733806ms","remote":"127.0.0.1:53204","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":"info","ts":"2026-02-26T10:18:02.426428Z","caller":"traceutil/trace.go:171","msg":"trace[482589995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:396; }","duration":"229.310963ms","start":"2026-02-26T10:18:02.197107Z","end":"2026-02-26T10:18:02.426418Z","steps":["trace[482589995] 'range keys from in-memory index tree' (duration: 228.441493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:02.426132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:01.634563Z","time spent":"791.551862ms","remote":"127.0.0.1:52972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"info","ts":"2026-02-26T10:18:02.772379Z","caller":"traceutil/trace.go:171","msg":"trace[303965583] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"340.105001ms","start":"2026-02-26T10:18:02.43223Z","end":"2026-02-26T10:18:02.772335Z","steps":["trace[303965583] 'process raft request' (duration: 339.754633ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:18:02.772707Z","caller":"traceutil/trace.go:171","msg":"trace[479790011] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"340.366787ms","start":"2026-02-26T10:18:02.432307Z","end":"2026-02-26T10:18:02.772674Z","steps":["trace[479790011] 'process raft request' (duration: 339.867655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:02.773046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:02.432214Z","time spent":"340.250544ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:18:02.773072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:02.432233Z","time spent":"340.566261ms","remote":"127.0.0.1:52972","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-02-26T10:18:02.773599Z","caller":"traceutil/trace.go:171","msg":"trace[251609365] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"340.916859ms","start":"2026-02-26T10:18:02.432661Z","end":"2026-02-26T10:18:02.773578Z","steps":["trace[251609365] 'process raft request' (duration: 339.953157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:02.773708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:02.432644Z","time spent":"341.012352ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:18:02.774228Z","caller":"traceutil/trace.go:171","msg":"trace[1551065076] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"338.895214ms","start":"2026-02-26T10:18:02.434923Z","end":"2026-02-26T10:18:02.773818Z","steps":["trace[1551065076] 'process raft request' (duration: 338.062554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:02.774331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:02.434903Z","time spent":"339.360284ms","remote":"127.0.0.1:53130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:18:03.068332Z","caller":"traceutil/trace.go:171","msg":"trace[159885801] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"284.960954ms","start":"2026-02-26T10:18:02.783348Z","end":"2026-02-26T10:18:03.068309Z","steps":["trace[159885801] 'process raft request' (duration: 284.78693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:19.072745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.832493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T10:18:19.07284Z","caller":"traceutil/trace.go:171","msg":"trace[1029253248] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"186.960233ms","start":"2026-02-26T10:18:18.885865Z","end":"2026-02-26T10:18:19.072825Z","steps":["trace[1029253248] 'read index received' (duration: 80.725961ms)","trace[1029253248] 'applied index is now lower than readState.Index' (duration: 106.233192ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:18:19.072896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.038084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:18:19.072927Z","caller":"traceutil/trace.go:171","msg":"trace[936732793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"187.100856ms","start":"2026-02-26T10:18:18.885818Z","end":"2026-02-26T10:18:19.072919Z","steps":["trace[936732793] 'agreement among raft nodes before linearized reading' (duration: 187.048175ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:18:19.072911Z","caller":"traceutil/trace.go:171","msg":"trace[1876388234] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"197.280002ms","start":"2026-02-26T10:18:18.875607Z","end":"2026-02-26T10:18:19.072887Z","steps":["trace[1876388234] 'process raft request' (duration: 91.03465ms)","trace[1876388234] 'compare' (duration: 105.72434ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:18:20.473982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.509616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T10:18:20.474097Z","caller":"traceutil/trace.go:171","msg":"trace[2124683117] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"587.663078ms","start":"2026-02-26T10:18:19.886408Z","end":"2026-02-26T10:18:20.474071Z","steps":["trace[2124683117] 'read index received' (duration: 380.490927ms)","trace[2124683117] 'applied index is now lower than readState.Index' (duration: 207.170791ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:18:20.474124Z","caller":"traceutil/trace.go:171","msg":"trace[2113049849] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"813.214144ms","start":"2026-02-26T10:18:19.660867Z","end":"2026-02-26T10:18:20.474081Z","steps":["trace[2113049849] 'process raft request' (duration: 606.001472ms)","trace[2113049849] 'compare' (duration: 206.404804ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:18:20.474166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.755061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:18:20.474206Z","caller":"traceutil/trace.go:171","msg":"trace[1940966806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"587.837602ms","start":"2026-02-26T10:18:19.886358Z","end":"2026-02-26T10:18:20.474195Z","steps":["trace[1940966806] 'agreement among raft nodes before linearized reading' (duration: 587.76807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:20.474239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:19.886339Z","time spent":"587.892494ms","remote":"127.0.0.1:52950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T10:18:20.474278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:19.660851Z","time spent":"813.351528ms","remote":"127.0.0.1:53118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:18:25.918007Z","caller":"traceutil/trace.go:171","msg":"trace[1178463115] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"243.011822ms","start":"2026-02-26T10:18:25.674968Z","end":"2026-02-26T10:18:25.91798Z","steps":["trace[1178463115] 'process raft request' (duration: 242.770907ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:18:27.017056Z","caller":"traceutil/trace.go:171","msg":"trace[1579846535] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"373.490055ms","start":"2026-02-26T10:18:26.643546Z","end":"2026-02-26T10:18:27.017036Z","steps":["trace[1579846535] 'read index received' (duration: 373.320611ms)","trace[1579846535] 'applied index is now lower than readState.Index' (duration: 168.704µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:18:27.017082Z","caller":"traceutil/trace.go:171","msg":"trace[1973411525] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"401.312471ms","start":"2026-02-26T10:18:26.615747Z","end":"2026-02-26T10:18:27.01706Z","steps":["trace[1973411525] 'process raft request' (duration: 401.175397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:27.01725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.68967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pd5k9\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-02-26T10:18:27.017288Z","caller":"traceutil/trace.go:171","msg":"trace[440608600] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pd5k9; range_end:; response_count:1; response_revision:527; }","duration":"373.779372ms","start":"2026-02-26T10:18:26.643502Z","end":"2026-02-26T10:18:27.017281Z","steps":["trace[440608600] 'agreement among raft nodes before linearized reading' (duration: 373.637678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:27.017315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:26.643484Z","time spent":"373.824933ms","remote":"127.0.0.1:53130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-pd5k9\" "} {"level":"warn","ts":"2026-02-26T10:18:27.01732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.355988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:18:27.017415Z","caller":"traceutil/trace.go:171","msg":"trace[1555578470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"132.47176ms","start":"2026-02-26T10:18:26.884904Z","end":"2026-02-26T10:18:27.017376Z","steps":["trace[1555578470] 'agreement among raft nodes before linearized reading' (duration: 132.364467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:27.017488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:26.615727Z","time spent":"401.532715ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:18:27.632894Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724556957329297,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T10:18:27.705102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.965623ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:18:27.705238Z","caller":"traceutil/trace.go:171","msg":"trace[1441923053] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:528; }","duration":"165.112036ms","start":"2026-02-26T10:18:27.540104Z","end":"2026-02-26T10:18:27.705216Z","steps":["trace[1441923053] 'range keys from in-memory index tree' (duration: 164.950723ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:18:27.705244Z","caller":"traceutil/trace.go:171","msg":"trace[1020309139] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"675.078373ms","start":"2026-02-26T10:18:27.030138Z","end":"2026-02-26T10:18:27.705217Z","steps":["trace[1020309139] 'process raft request' (duration: 671.129312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:27.705428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:27.030111Z","time spent":"675.191766ms","remote":"127.0.0.1:53130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:18:29.506771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.657645153s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-26T10:18:29.506858Z","caller":"traceutil/trace.go:171","msg":"trace[847815062] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:548; }","duration":"2.374927194s","start":"2026-02-26T10:18:27.131914Z","end":"2026-02-26T10:18:29.506841Z","steps":["trace[847815062] 'read index received' (duration: 569.343103ms)","trace[847815062] 'applied index is now lower than readState.Index' (duration: 1.805582541s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:18:29.507431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.620933203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:18:29.507514Z","caller":"traceutil/trace.go:171","msg":"trace[770750124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"1.621024325s","start":"2026-02-26T10:18:27.886469Z","end":"2026-02-26T10:18:29.507493Z","steps":["trace[770750124] 'agreement among raft nodes before linearized reading' (duration: 1.620887381s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:29.507551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:27.88641Z","time spent":"1.621133548s","remote":"127.0.0.1:52950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T10:18:29.507705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.917297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-26T10:18:29.507752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.569455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T10:18:29.507776Z","caller":"traceutil/trace.go:171","msg":"trace[1462423372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"425.04622ms","start":"2026-02-26T10:18:29.082713Z","end":"2026-02-26T10:18:29.507759Z","steps":["trace[1462423372] 'agreement among raft nodes before linearized reading' (duration: 424.869796ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:18:29.507784Z","caller":"traceutil/trace.go:171","msg":"trace[866365003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"486.633637ms","start":"2026-02-26T10:18:29.021141Z","end":"2026-02-26T10:18:29.507774Z","steps":["trace[866365003] 'agreement among raft nodes before linearized reading' (duration: 486.571096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:29.507813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:29.021121Z","time spent":"486.685658ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T10:18:29.507821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:29.082693Z","time spent":"425.120391ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T10:18:29.507854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.586420664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T10:18:29.507899Z","caller":"traceutil/trace.go:171","msg":"trace[1078076361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"1.586523176s","start":"2026-02-26T10:18:27.921363Z","end":"2026-02-26T10:18:29.507887Z","steps":["trace[1078076361] 'agreement among raft nodes before linearized reading' (duration: 1.586375972s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:29.507906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.375985029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-26T10:18:29.507931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:27.921344Z","time spent":"1.586580726s","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T10:18:29.507967Z","caller":"traceutil/trace.go:171","msg":"trace[1927274896] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"2.376082421s","start":"2026-02-26T10:18:27.131865Z","end":"2026-02-26T10:18:29.507947Z","steps":["trace[1927274896] 'agreement among raft nodes before linearized reading' (duration: 2.375865266s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:29.508009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:27.131844Z","time spent":"2.376156512s","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T10:18:31.058624Z","caller":"traceutil/trace.go:171","msg":"trace[567889069] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"171.75718ms","start":"2026-02-26T10:18:30.886841Z","end":"2026-02-26T10:18:31.058598Z","steps":["trace[567889069] 'read index received' (duration: 171.207207ms)","trace[567889069] 'applied index is now lower than readState.Index' (duration: 548.853µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:18:31.058772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.915433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:18:31.058764Z","caller":"traceutil/trace.go:171","msg":"trace[660448088] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"268.998723ms","start":"2026-02-26T10:18:30.789711Z","end":"2026-02-26T10:18:31.05871Z","steps":["trace[660448088] 'process raft request' (duration: 268.40625ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:18:31.058816Z","caller":"traceutil/trace.go:171","msg":"trace[767282231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"172.006656ms","start":"2026-02-26T10:18:30.886798Z","end":"2026-02-26T10:18:31.058804Z","steps":["trace[767282231] 'agreement among raft nodes before linearized reading' (duration: 171.897963ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:18:31.90806Z","caller":"traceutil/trace.go:171","msg":"trace[1146613891] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"210.759764ms","start":"2026-02-26T10:18:31.697273Z","end":"2026-02-26T10:18:31.908033Z","steps":["trace[1146613891] 'process raft request' (duration: 210.57221ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:18:31.908054Z","caller":"traceutil/trace.go:171","msg":"trace[1076540431] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"180.707677ms","start":"2026-02-26T10:18:31.727322Z","end":"2026-02-26T10:18:31.90803Z","steps":["trace[1076540431] 'process raft request' (duration: 180.653276ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:18:37.729899Z","caller":"traceutil/trace.go:171","msg":"trace[1935198298] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"116.384398ms","start":"2026-02-26T10:18:37.613491Z","end":"2026-02-26T10:18:37.729875Z","steps":["trace[1935198298] 'process raft request' (duration: 116.332617ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:18:37.730016Z","caller":"traceutil/trace.go:171","msg":"trace[434093116] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"118.906206ms","start":"2026-02-26T10:18:37.611054Z","end":"2026-02-26T10:18:37.72996Z","steps":["trace[434093116] 'process raft request' (duration: 112.431445ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:18:43.760001Z","caller":"traceutil/trace.go:171","msg":"trace[18004331] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"115.279572ms","start":"2026-02-26T10:18:43.644686Z","end":"2026-02-26T10:18:43.759965Z","steps":["trace[18004331] 'process raft request' (duration: 115.102258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:48.310738Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724556957329650,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T10:18:48.345723Z","caller":"traceutil/trace.go:171","msg":"trace[1572848605] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:740; }","duration":"534.983473ms","start":"2026-02-26T10:18:47.810702Z","end":"2026-02-26T10:18:48.345686Z","steps":["trace[1572848605] 'read index received' (duration: 534.666866ms)","trace[1572848605] 'applied index is now lower than readState.Index' (duration: 314.977µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:18:48.345774Z","caller":"traceutil/trace.go:171","msg":"trace[546457131] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"555.781577ms","start":"2026-02-26T10:18:47.789953Z","end":"2026-02-26T10:18:48.345734Z","steps":["trace[546457131] 'process raft request' (duration: 555.520811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:48.345976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:47.789935Z","time spent":"555.92165ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:18:48.345988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.263319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T10:18:48.34607Z","caller":"traceutil/trace.go:171","msg":"trace[252058739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:713; }","duration":"535.405682ms","start":"2026-02-26T10:18:47.810643Z","end":"2026-02-26T10:18:48.346048Z","steps":["trace[252058739] 'agreement among raft nodes before linearized reading' (duration: 535.172127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:48.346146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:47.810625Z","time spent":"535.511384ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T10:18:48.346213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.459723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T10:18:48.34628Z","caller":"traceutil/trace.go:171","msg":"trace[665212967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:713; }","duration":"535.552395ms","start":"2026-02-26T10:18:47.810705Z","end":"2026-02-26T10:18:48.346258Z","steps":["trace[665212967] 'agreement among raft nodes before linearized reading' (duration: 535.298399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:48.346287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.13021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:18:48.346323Z","caller":"traceutil/trace.go:171","msg":"trace[2138909387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:713; }","duration":"459.189751ms","start":"2026-02-26T10:18:47.887124Z","end":"2026-02-26T10:18:48.346314Z","steps":["trace[2138909387] 'agreement among raft nodes before linearized reading' (duration: 459.1402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:48.346334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:47.810692Z","time spent":"535.631937ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T10:18:48.346351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:18:47.887111Z","time spent":"459.234933ms","remote":"127.0.0.1:52950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T10:18:48.461836Z","caller":"traceutil/trace.go:171","msg":"trace[60608332] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"107.871052ms","start":"2026-02-26T10:18:48.353941Z","end":"2026-02-26T10:18:48.461812Z","steps":["trace[60608332] 'process raft request' (duration: 104.203458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:18:49.429477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.265285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T10:18:49.429623Z","caller":"traceutil/trace.go:171","msg":"trace[832573600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:715; }","duration":"272.410978ms","start":"2026-02-26T10:18:49.157143Z","end":"2026-02-26T10:18:49.429554Z","steps":["trace[832573600] 'range keys from in-memory index tree' (duration: 272.073891ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:19:06.737704Z","caller":"traceutil/trace.go:171","msg":"trace[566121564] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"197.189843ms","start":"2026-02-26T10:19:06.540492Z","end":"2026-02-26T10:19:06.737682Z","steps":["trace[566121564] 'read index received' (duration: 197.029309ms)","trace[566121564] 'applied index is now lower than readState.Index' (duration: 159.234µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:19:06.737728Z","caller":"traceutil/trace.go:171","msg":"trace[1488335077] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"243.511439ms","start":"2026-02-26T10:19:06.494185Z","end":"2026-02-26T10:19:06.737696Z","steps":["trace[1488335077] 'process raft request' (duration: 243.290803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:06.737945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.429278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T10:19:06.738038Z","caller":"traceutil/trace.go:171","msg":"trace[937901927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:771; }","duration":"197.56354ms","start":"2026-02-26T10:19:06.540454Z","end":"2026-02-26T10:19:06.738017Z","steps":["trace[937901927] 'agreement among raft nodes before linearized reading' (duration: 197.328575ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:19:11.294676Z","caller":"traceutil/trace.go:171","msg":"trace[95864332] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"445.184693ms","start":"2026-02-26T10:19:10.849468Z","end":"2026-02-26T10:19:11.294653Z","steps":["trace[95864332] 'process raft request' (duration: 445.05658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:11.29562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:10.849454Z","time spent":"445.372737ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:19:16.813737Z","caller":"traceutil/trace.go:171","msg":"trace[1512811722] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"322.073892ms","start":"2026-02-26T10:19:16.49164Z","end":"2026-02-26T10:19:16.813714Z","steps":["trace[1512811722] 'process raft request' (duration: 321.831607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:16.813862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:16.491627Z","time spent":"322.169065ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:19:17.5628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.855873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T10:19:17.563028Z","caller":"traceutil/trace.go:171","msg":"trace[1904488493] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"742.008918ms","start":"2026-02-26T10:19:16.820981Z","end":"2026-02-26T10:19:17.56299Z","steps":["trace[1904488493] 'process raft request' (duration: 187.876998ms)","trace[1904488493] 'compare' (duration: 553.600708ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:19:17.563281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:16.820962Z","time spent":"742.160732ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:19:17.6439Z","caller":"traceutil/trace.go:171","msg":"trace[49207961] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:831; }","duration":"260.960716ms","start":"2026-02-26T10:19:17.382909Z","end":"2026-02-26T10:19:17.64387Z","steps":["trace[49207961] 'read index received' (duration: 173.187212ms)","trace[49207961] 'applied index is now lower than readState.Index' (duration: 87.772084ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:19:17.645173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.249186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T10:19:17.645271Z","caller":"traceutil/trace.go:171","msg":"trace[1986036742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"262.372638ms","start":"2026-02-26T10:19:17.382865Z","end":"2026-02-26T10:19:17.645238Z","steps":["trace[1986036742] 'agreement among raft nodes before linearized reading' (duration: 261.180891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:18.438841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.143212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T10:19:18.438964Z","caller":"traceutil/trace.go:171","msg":"trace[1150669750] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"591.206228ms","start":"2026-02-26T10:19:17.847742Z","end":"2026-02-26T10:19:18.438948Z","steps":["trace[1150669750] 'read index received' (duration: 385.87708ms)","trace[1150669750] 'applied index is now lower than readState.Index' (duration: 205.327928ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:19:18.439052Z","caller":"traceutil/trace.go:171","msg":"trace[1506211042] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"787.135299ms","start":"2026-02-26T10:19:17.651863Z","end":"2026-02-26T10:19:18.438998Z","steps":["trace[1506211042] 'process raft request' (duration: 581.75591ms)","trace[1506211042] 'compare' (duration: 205.04984ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:19:18.439131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.390221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T10:19:18.439163Z","caller":"traceutil/trace.go:171","msg":"trace[2042680826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:801; }","duration":"591.462473ms","start":"2026-02-26T10:19:17.847691Z","end":"2026-02-26T10:19:18.439153Z","steps":["trace[2042680826] 'agreement among raft nodes before linearized reading' (duration: 591.3393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:18.439195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:17.847665Z","time spent":"591.522234ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T10:19:18.439282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:17.651846Z","time spent":"787.312612ms","remote":"127.0.0.1:53204","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-02-26T10:19:18.439225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.205609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:19:18.439482Z","caller":"traceutil/trace.go:171","msg":"trace[1797733272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"553.496916ms","start":"2026-02-26T10:19:17.885963Z","end":"2026-02-26T10:19:18.43946Z","steps":["trace[1797733272] 'agreement among raft nodes before linearized reading' (duration: 553.202189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:18.43953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:17.885945Z","time spent":"553.574417ms","remote":"127.0.0.1:52950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T10:19:18.439568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.469486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:19:18.439701Z","caller":"traceutil/trace.go:171","msg":"trace[2054499212] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:801; }","duration":"476.664111ms","start":"2026-02-26T10:19:17.963014Z","end":"2026-02-26T10:19:18.439678Z","steps":["trace[2054499212] 'agreement among raft nodes before linearized reading' (duration: 476.446326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:18.439748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:17.962993Z","time spent":"476.742563ms","remote":"127.0.0.1:53166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-26T10:19:19.270579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.436383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T10:19:19.270663Z","caller":"traceutil/trace.go:171","msg":"trace[1385238579] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"452.809286ms","start":"2026-02-26T10:19:18.817841Z","end":"2026-02-26T10:19:19.270651Z","steps":["trace[1385238579] 'read index received' (duration: 43.121µs)","trace[1385238579] 'applied index is now lower than readState.Index' (duration: 452.765275ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:19:19.270809Z","caller":"traceutil/trace.go:171","msg":"trace[17759439] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"826.213451ms","start":"2026-02-26T10:19:18.444587Z","end":"2026-02-26T10:19:19.270801Z","steps":["trace[17759439] 'process raft request' (duration: 117.472531ms)","trace[17759439] 'compare' (duration: 708.233139ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:19:19.270849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:18.444568Z","time spent":"826.259872ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:19:19.271021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.184385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T10:19:19.27104Z","caller":"traceutil/trace.go:171","msg":"trace[408014763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"453.229036ms","start":"2026-02-26T10:19:18.817803Z","end":"2026-02-26T10:19:19.271032Z","steps":["trace[408014763] 'agreement among raft nodes before linearized reading' (duration: 453.146825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:19.271058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:18.817782Z","time spent":"453.271928ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T10:19:19.271132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.09945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:19:19.271148Z","caller":"traceutil/trace.go:171","msg":"trace[381118243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"384.14889ms","start":"2026-02-26T10:19:18.886994Z","end":"2026-02-26T10:19:19.271143Z","steps":["trace[381118243] 'agreement among raft nodes before linearized reading' (duration: 384.124299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:19.271162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:18.886973Z","time spent":"384.185681ms","remote":"127.0.0.1:52950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T10:19:19.2713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.677675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T10:19:19.271317Z","caller":"traceutil/trace.go:171","msg":"trace[178172750] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:802; }","duration":"127.737157ms","start":"2026-02-26T10:19:19.143575Z","end":"2026-02-26T10:19:19.271312Z","steps":["trace[178172750] 'agreement among raft nodes before linearized reading' (duration: 127.705806ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:19:19.552441Z","caller":"traceutil/trace.go:171","msg":"trace[678639101] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"277.122556ms","start":"2026-02-26T10:19:19.275283Z","end":"2026-02-26T10:19:19.552406Z","steps":["trace[678639101] 'process raft request' (duration: 247.759826ms)","trace[678639101] 'compare' (duration: 29.015262ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:19:19.809375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.855773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T10:19:19.809464Z","caller":"traceutil/trace.go:171","msg":"trace[402671141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:804; }","duration":"125.974485ms","start":"2026-02-26T10:19:19.683473Z","end":"2026-02-26T10:19:19.809448Z","steps":["trace[402671141] 'range keys from in-memory index tree' (duration: 125.670319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:21.55511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.635404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:19:21.55518Z","caller":"traceutil/trace.go:171","msg":"trace[247680374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"668.753917ms","start":"2026-02-26T10:19:20.886411Z","end":"2026-02-26T10:19:21.555165Z","steps":["trace[247680374] 'range keys from in-memory index tree' (duration: 668.570903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:21.555163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.770207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-26T10:19:21.555225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:20.886396Z","time spent":"668.822038ms","remote":"127.0.0.1:52950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T10:19:21.555253Z","caller":"traceutil/trace.go:171","msg":"trace[1411258060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:806; }","duration":"279.904929ms","start":"2026-02-26T10:19:21.275323Z","end":"2026-02-26T10:19:21.555228Z","steps":["trace[1411258060] 'range keys from in-memory index tree' (duration: 279.579152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:21.555298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.566061ms","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-02-26T10:19:21.555329Z","caller":"traceutil/trace.go:171","msg":"trace[967412789] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:806; }","duration":"915.650332ms","start":"2026-02-26T10:19:20.639669Z","end":"2026-02-26T10:19:21.555319Z","steps":["trace[967412789] 'count revisions from in-memory index tree' (duration: 915.452408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:21.555347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:20.639641Z","time spent":"915.701164ms","remote":"127.0.0.1:53468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-26T10:19:22.122526Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724556957329921,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T10:19:22.145118Z","caller":"traceutil/trace.go:171","msg":"trace[1456706104] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"585.59197ms","start":"2026-02-26T10:19:21.559501Z","end":"2026-02-26T10:19:22.145093Z","steps":["trace[1456706104] 'process raft request' (duration: 585.430245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:22.145268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:21.559484Z","time spent":"585.710902ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:19:23.213095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.699519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T10:19:23.213266Z","caller":"traceutil/trace.go:171","msg":"trace[17741143] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"808.109298ms","start":"2026-02-26T10:19:22.40514Z","end":"2026-02-26T10:19:23.213249Z","steps":["trace[17741143] 'process raft request' (duration: 808.055327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:23.213361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:22.405115Z","time spent":"808.19068ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:19:23.213587Z","caller":"traceutil/trace.go:171","msg":"trace[615001410] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"1.653561979s","start":"2026-02-26T10:19:21.560003Z","end":"2026-02-26T10:19:23.213565Z","steps":["trace[615001410] 'process raft request' (duration: 1.096318529s)","trace[615001410] 'compare' (duration: 556.486843ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:19:23.21365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:21.559983Z","time spent":"1.653638882s","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:19:23.213586Z","caller":"traceutil/trace.go:171","msg":"trace[1265782416] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:840; }","duration":"1.591144525s","start":"2026-02-26T10:19:21.622414Z","end":"2026-02-26T10:19:23.213559Z","steps":["trace[1265782416] 'read index received' (duration: 522.504908ms)","trace[1265782416] 'applied index is now lower than readState.Index' (duration: 1.068637277s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:19:23.21382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.591408931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T10:19:23.213859Z","caller":"traceutil/trace.go:171","msg":"trace[789088451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"1.591463492s","start":"2026-02-26T10:19:21.622373Z","end":"2026-02-26T10:19:23.213837Z","steps":["trace[789088451] 'agreement among raft nodes before linearized reading' (duration: 1.59135416s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:23.213876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.573921892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-26T10:19:23.213887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:21.622353Z","time spent":"1.591527173s","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T10:19:23.213914Z","caller":"traceutil/trace.go:171","msg":"trace[2077205962] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:809; }","duration":"1.574016424s","start":"2026-02-26T10:19:21.639887Z","end":"2026-02-26T10:19:23.213904Z","steps":["trace[2077205962] 'agreement among raft nodes before linearized reading' (duration: 1.573872771s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:23.213968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:21.639865Z","time spent":"1.574080746s","remote":"127.0.0.1:52972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-02-26T10:19:23.213991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.850902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-26T10:19:23.214025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.455764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T10:19:23.214052Z","caller":"traceutil/trace.go:171","msg":"trace[618725612] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:809; }","duration":"709.520336ms","start":"2026-02-26T10:19:22.504523Z","end":"2026-02-26T10:19:23.214043Z","steps":["trace[618725612] 'agreement among raft nodes before linearized reading' (duration: 709.435544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:23.214122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.323525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T10:19:23.21412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:22.504502Z","time spent":"709.578848ms","remote":"127.0.0.1:53478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":98,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-26T10:19:23.214163Z","caller":"traceutil/trace.go:171","msg":"trace[567507735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"328.419237ms","start":"2026-02-26T10:19:22.885733Z","end":"2026-02-26T10:19:23.214152Z","steps":["trace[567507735] 'agreement among raft nodes before linearized reading' (duration: 328.337276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:23.2142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:22.885716Z","time spent":"328.475528ms","remote":"127.0.0.1:52950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T10:19:23.214046Z","caller":"traceutil/trace.go:171","msg":"trace[875194085] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:809; }","duration":"676.943754ms","start":"2026-02-26T10:19:22.53709Z","end":"2026-02-26T10:19:23.214033Z","steps":["trace[875194085] 'agreement among raft nodes before linearized reading' (duration: 676.813191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:23.214255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350574623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T10:19:23.214285Z","caller":"traceutil/trace.go:171","msg":"trace[1651280950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"1.350639124s","start":"2026-02-26T10:19:21.863638Z","end":"2026-02-26T10:19:23.214277Z","steps":["trace[1651280950] 'agreement among raft nodes before linearized reading' (duration: 1.350549612s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:23.214285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:22.537069Z","time spent":"677.201249ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T10:19:23.214309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:21.863618Z","time spent":"1.350684575s","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T10:19:23.771208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.931495ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-26T10:19:23.771301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:23.21511Z","time spent":"556.186756ms","remote":"127.0.0.1:52972","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-26T10:19:23.978453Z","caller":"traceutil/trace.go:171","msg":"trace[2092522167] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"759.321321ms","start":"2026-02-26T10:19:23.219113Z","end":"2026-02-26T10:19:23.978435Z","steps":["trace[2092522167] 'process raft request' (duration: 759.25965ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:19:23.978581Z","caller":"traceutil/trace.go:171","msg":"trace[1937022705] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"759.543067ms","start":"2026-02-26T10:19:23.219005Z","end":"2026-02-26T10:19:23.978548Z","steps":["trace[1937022705] 'process raft request' (duration: 759.22669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:23.978677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:23.219091Z","time spent":"759.510856ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:19:23.978681Z","caller":"traceutil/trace.go:171","msg":"trace[1759866926] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:843; }","duration":"750.377647ms","start":"2026-02-26T10:19:23.228274Z","end":"2026-02-26T10:19:23.978651Z","steps":["trace[1759866926] 'read index received' (duration: 410.07638ms)","trace[1759866926] 'applied index is now lower than readState.Index' (duration: 340.298917ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:19:23.97872Z","caller":"traceutil/trace.go:171","msg":"trace[25114031] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"759.094806ms","start":"2026-02-26T10:19:23.219596Z","end":"2026-02-26T10:19:23.978691Z","steps":["trace[25114031] 'process raft request' (duration: 758.81047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:23.978744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:23.218981Z","time spent":"759.670839ms","remote":"127.0.0.1:53204","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-02-26T10:19:23.978817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:23.219587Z","time spent":"759.180719ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:19:23.978837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.52877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:19:23.978882Z","caller":"traceutil/trace.go:171","msg":"trace[1621014683] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:812; }","duration":"750.621933ms","start":"2026-02-26T10:19:23.228246Z","end":"2026-02-26T10:19:23.978868Z","steps":["trace[1621014683] 'agreement among raft nodes before linearized reading' (duration: 750.516151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:23.978941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:23.228238Z","time spent":"750.688234ms","remote":"127.0.0.1:53350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-26T10:19:23.979843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.396935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:19:23.979839Z","caller":"traceutil/trace.go:171","msg":"trace[1098647507] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"207.547457ms","start":"2026-02-26T10:19:23.772249Z","end":"2026-02-26T10:19:23.979797Z","steps":["trace[1098647507] 'process raft request' (duration: 207.462925ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:19:23.979882Z","caller":"traceutil/trace.go:171","msg":"trace[281326070] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:813; }","duration":"250.472947ms","start":"2026-02-26T10:19:23.7294Z","end":"2026-02-26T10:19:23.979873Z","steps":["trace[281326070] 'agreement among raft nodes before linearized reading' (duration: 250.377285ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:19:24.328155Z","caller":"traceutil/trace.go:171","msg":"trace[1297014472] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"174.701528ms","start":"2026-02-26T10:19:24.153433Z","end":"2026-02-26T10:19:24.328135Z","steps":["trace[1297014472] 'process raft request' (duration: 174.582426ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:19:24.760266Z","caller":"traceutil/trace.go:171","msg":"trace[1515009780] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"211.758214ms","start":"2026-02-26T10:19:24.548486Z","end":"2026-02-26T10:19:24.760244Z","steps":["trace[1515009780] 'process raft request' (duration: 211.606631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:25.438347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.870827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:19:25.438412Z","caller":"traceutil/trace.go:171","msg":"trace[1595449354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:815; }","duration":"551.99304ms","start":"2026-02-26T10:19:24.886404Z","end":"2026-02-26T10:19:25.438397Z","steps":["trace[1595449354] 'range keys from in-memory index tree' (duration: 551.781314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:25.438444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:24.886383Z","time spent":"552.054951ms","remote":"127.0.0.1:52950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T10:19:25.438463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.879227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T10:19:25.438526Z","caller":"traceutil/trace.go:171","msg":"trace[1222817308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:815; }","duration":"218.977318ms","start":"2026-02-26T10:19:25.219532Z","end":"2026-02-26T10:19:25.438509Z","steps":["trace[1222817308] 'range keys from in-memory index tree' (duration: 218.698372ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:19:26.015019Z","caller":"traceutil/trace.go:171","msg":"trace[450975291] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:850; }","duration":"126.742403ms","start":"2026-02-26T10:19:25.888256Z","end":"2026-02-26T10:19:26.014998Z","steps":["trace[450975291] 'read index received' (duration: 126.511428ms)","trace[450975291] 'applied index is now lower than readState.Index' (duration: 229.955µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:19:26.015162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.889487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:19:26.015193Z","caller":"traceutil/trace.go:171","msg":"trace[289761295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"126.974709ms","start":"2026-02-26T10:19:25.888209Z","end":"2026-02-26T10:19:26.015184Z","steps":["trace[289761295] 'agreement among raft nodes before linearized reading' (duration: 126.911167ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:19:26.015647Z","caller":"traceutil/trace.go:171","msg":"trace[35254861] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"573.092874ms","start":"2026-02-26T10:19:25.441981Z","end":"2026-02-26T10:19:26.015074Z","steps":["trace[35254861] 'process raft request' (duration: 572.884349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:26.015868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:25.441961Z","time spent":"573.81389ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:19:26.679596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.361499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T10:19:26.679715Z","caller":"traceutil/trace.go:171","msg":"trace[61923011] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"659.38182ms","start":"2026-02-26T10:19:26.020314Z","end":"2026-02-26T10:19:26.679696Z","steps":["trace[61923011] 'process raft request' (duration: 336.869538ms)","trace[61923011] 'compare' (duration: 322.095322ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:19:26.679774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:26.020294Z","time spent":"659.451222ms","remote":"127.0.0.1:53204","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-02-26T10:19:27.402379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.349436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T10:19:27.402583Z","caller":"traceutil/trace.go:171","msg":"trace[196716552] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"1.381557014s","start":"2026-02-26T10:19:26.021004Z","end":"2026-02-26T10:19:27.402561Z","steps":["trace[196716552] 'process raft request' (duration: 790.954733ms)","trace[196716552] 'compare' (duration: 590.262124ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:19:27.402664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:26.020997Z","time spent":"1.381628026s","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:19:27.402737Z","caller":"traceutil/trace.go:171","msg":"trace[665464477] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:851; }","duration":"1.070076666s","start":"2026-02-26T10:19:26.332641Z","end":"2026-02-26T10:19:27.402718Z","steps":["trace[665464477] 'read index received' (duration: 24.5188ms)","trace[665464477] 'applied index is now lower than readState.Index' (duration: 1.045556266s)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:19:27.402774Z","caller":"traceutil/trace.go:171","msg":"trace[1652162382] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"1.370806778s","start":"2026-02-26T10:19:26.031935Z","end":"2026-02-26T10:19:27.402742Z","steps":["trace[1652162382] 'process raft request' (duration: 1.370526882s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:27.402968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:26.031926Z","time spent":"1.370922941s","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:19:27.519033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.49993ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-26T10:19:27.519122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186458263s","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-02-26T10:19:27.519177Z","caller":"traceutil/trace.go:171","msg":"trace[1374818297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:819; }","duration":"1.186550485s","start":"2026-02-26T10:19:26.33261Z","end":"2026-02-26T10:19:27.51916Z","steps":["trace[1374818297] 'agreement among raft nodes before linearized reading' (duration: 1.070194358s)","trace[1374818297] 'range keys from in-memory index tree' (duration: 116.182783ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:19:27.519192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.03514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:19:27.519223Z","caller":"traceutil/trace.go:171","msg":"trace[1943587410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:819; }","duration":"633.092551ms","start":"2026-02-26T10:19:26.886121Z","end":"2026-02-26T10:19:27.519213Z","steps":["trace[1943587410] 'agreement among raft nodes before linearized reading' (duration: 633.035479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:27.519218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:26.332592Z","time spent":"1.186617827s","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T10:19:27.519316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:26.886105Z","time spent":"633.200103ms","remote":"127.0.0.1:52942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T10:19:27.519131Z","caller":"traceutil/trace.go:171","msg":"trace[1539094784] linearizableReadLoop","detail":"{readStateIndex:855; appliedIndex:854; }","duration":"116.345557ms","start":"2026-02-26T10:19:27.402768Z","end":"2026-02-26T10:19:27.519113Z","steps":["trace[1539094784] 'read index received' (duration: 49.261µs)","trace[1539094784] 'applied index is now lower than readState.Index' (duration: 116.294365ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:19:27.519356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.407323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:19:27.519428Z","caller":"traceutil/trace.go:171","msg":"trace[784968773] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:819; }","duration":"578.535626ms","start":"2026-02-26T10:19:26.940876Z","end":"2026-02-26T10:19:27.519411Z","steps":["trace[784968773] 'agreement among raft nodes before linearized reading' (duration: 578.413073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:27.519472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:26.940852Z","time spent":"578.610607ms","remote":"127.0.0.1:53096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-26T10:19:27.647854Z","caller":"traceutil/trace.go:171","msg":"trace[1432715195] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"123.994411ms","start":"2026-02-26T10:19:27.523838Z","end":"2026-02-26T10:19:27.647832Z","steps":["trace[1432715195] 'process raft request' (duration: 123.857108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:31.694171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.833909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T10:19:31.694249Z","caller":"traceutil/trace.go:171","msg":"trace[1223100979] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:829; }","duration":"177.945991ms","start":"2026-02-26T10:19:31.516283Z","end":"2026-02-26T10:19:31.694229Z","steps":["trace[1223100979] 'count revisions from in-memory index tree' (duration: 177.712816ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:19:33.949429Z","caller":"traceutil/trace.go:171","msg":"trace[502782025] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"200.321502ms","start":"2026-02-26T10:19:33.749089Z","end":"2026-02-26T10:19:33.94941Z","steps":["trace[502782025] 'process raft request' (duration: 200.186439ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:19:36.127833Z","caller":"traceutil/trace.go:171","msg":"trace[2056988633] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"171.231269ms","start":"2026-02-26T10:19:35.956579Z","end":"2026-02-26T10:19:36.12781Z","steps":["trace[2056988633] 'process raft request' (duration: 171.091236ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:19:40.206599Z","caller":"traceutil/trace.go:171","msg":"trace[1453438626] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"161.001516ms","start":"2026-02-26T10:19:40.045566Z","end":"2026-02-26T10:19:40.206568Z","steps":["trace[1453438626] 'process raft request' (duration: 157.293661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:41.800576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.47584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T10:19:41.800663Z","caller":"traceutil/trace.go:171","msg":"trace[2112097620] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:908; }","duration":"159.610444ms","start":"2026-02-26T10:19:41.641034Z","end":"2026-02-26T10:19:41.800644Z","steps":["trace[2112097620] 'range keys from in-memory index tree' (duration: 159.324547ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:19:42.74199Z","caller":"traceutil/trace.go:171","msg":"trace[1877891320] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"462.843058ms","start":"2026-02-26T10:19:42.279125Z","end":"2026-02-26T10:19:42.741968Z","steps":["trace[1877891320] 'read index received' (duration: 462.728985ms)","trace[1877891320] 'applied index is now lower than readState.Index' (duration: 113.363µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:19:42.742158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.016853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T10:19:42.742191Z","caller":"traceutil/trace.go:171","msg":"trace[1808617742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:910; }","duration":"463.095704ms","start":"2026-02-26T10:19:42.27908Z","end":"2026-02-26T10:19:42.742176Z","steps":["trace[1808617742] 'agreement among raft nodes before linearized reading' (duration: 462.973141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:42.742226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:42.27906Z","time spent":"463.157906ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T10:19:42.742325Z","caller":"traceutil/trace.go:171","msg":"trace[1197883996] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"519.367679ms","start":"2026-02-26T10:19:42.222949Z","end":"2026-02-26T10:19:42.742317Z","steps":["trace[1197883996] 'process raft request' (duration: 518.893368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:42.742387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:42.222933Z","time spent":"519.40943ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:19:44.08062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724556957330168,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T10:19:44.161839Z","caller":"traceutil/trace.go:171","msg":"trace[1232556304] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"655.638669ms","start":"2026-02-26T10:19:43.506166Z","end":"2026-02-26T10:19:44.161804Z","steps":["trace[1232556304] 'process raft request' (duration: 655.487005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:44.161972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:43.506145Z","time spent":"655.751081ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:19:44.161997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.836697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T10:19:44.162044Z","caller":"traceutil/trace.go:171","msg":"trace[481522999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:913; }","duration":"581.92428ms","start":"2026-02-26T10:19:43.58011Z","end":"2026-02-26T10:19:44.162035Z","steps":["trace[481522999] 'agreement among raft nodes before linearized reading' (duration: 581.767486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:44.162081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:43.580097Z","time spent":"581.976741ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T10:19:44.161829Z","caller":"traceutil/trace.go:171","msg":"trace[1525277070] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"581.636403ms","start":"2026-02-26T10:19:43.580144Z","end":"2026-02-26T10:19:44.16178Z","steps":["trace[1525277070] 'read index received' (duration: 581.468909ms)","trace[1525277070] 'applied index is now lower than readState.Index' (duration: 166.394µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:19:44.162754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.310139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T10:19:44.162787Z","caller":"traceutil/trace.go:171","msg":"trace[871219544] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:913; }","duration":"571.37156ms","start":"2026-02-26T10:19:43.591403Z","end":"2026-02-26T10:19:44.162775Z","steps":["trace[871219544] 'agreement among raft nodes before linearized reading' (duration: 571.272738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:44.162816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:43.591388Z","time spent":"571.419502ms","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-26T10:19:44.162892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.442265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T10:19:44.162924Z","caller":"traceutil/trace.go:171","msg":"trace[87508983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:913; }","duration":"569.495816ms","start":"2026-02-26T10:19:43.593418Z","end":"2026-02-26T10:19:44.162914Z","steps":["trace[87508983] 'agreement among raft nodes before linearized reading' (duration: 569.377984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:44.162947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:43.59341Z","time spent":"569.532397ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T10:19:44.664922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.98273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T10:19:44.665026Z","caller":"traceutil/trace.go:171","msg":"trace[2032707232] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"498.213384ms","start":"2026-02-26T10:19:44.166794Z","end":"2026-02-26T10:19:44.665007Z","steps":["trace[2032707232] 'process raft request' (duration: 86.06859ms)","trace[2032707232] 'compare' (duration: 411.783596ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:19:44.665091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:44.166774Z","time spent":"498.287486ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:19:44.733534Z","caller":"traceutil/trace.go:171","msg":"trace[1569985257] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"377.157219ms","start":"2026-02-26T10:19:44.356355Z","end":"2026-02-26T10:19:44.733513Z","steps":["trace[1569985257] 'read index received' (duration: 377.108948ms)","trace[1569985257] 'applied index is now lower than readState.Index' (duration: 47.631µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:19:44.733627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.257981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:19:44.733646Z","caller":"traceutil/trace.go:171","msg":"trace[305069802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"377.315903ms","start":"2026-02-26T10:19:44.356323Z","end":"2026-02-26T10:19:44.733639Z","steps":["trace[305069802] 'agreement among raft nodes before linearized reading' (duration: 377.272322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:44.733662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:44.356307Z","time spent":"377.352143ms","remote":"127.0.0.1:52950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T10:19:44.733776Z","caller":"traceutil/trace.go:171","msg":"trace[2005646367] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"566.891695ms","start":"2026-02-26T10:19:44.166866Z","end":"2026-02-26T10:19:44.733758Z","steps":["trace[2005646367] 'process raft request' (duration: 566.533726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:19:44.733874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:19:44.166842Z","time spent":"566.972756ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:20:02.0135Z","caller":"traceutil/trace.go:171","msg":"trace[1212717194] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"126.504635ms","start":"2026-02-26T10:20:01.886971Z","end":"2026-02-26T10:20:02.013476Z","steps":["trace[1212717194] 'read index received' (duration: 126.345291ms)","trace[1212717194] 'applied index is now lower than readState.Index' (duration: 158.514µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:20:02.013568Z","caller":"traceutil/trace.go:171","msg":"trace[2102209595] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"249.356903ms","start":"2026-02-26T10:20:01.764179Z","end":"2026-02-26T10:20:02.013536Z","steps":["trace[2102209595] 'process raft request' (duration: 249.177079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:02.013614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.630418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:20:02.013769Z","caller":"traceutil/trace.go:171","msg":"trace[1390819654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"126.841692ms","start":"2026-02-26T10:20:01.886919Z","end":"2026-02-26T10:20:02.013761Z","steps":["trace[1390819654] 'agreement among raft nodes before linearized reading' (duration: 126.631138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:03.301017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724556957330346,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T10:20:03.465232Z","caller":"traceutil/trace.go:171","msg":"trace[1112964500] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"664.61082ms","start":"2026-02-26T10:20:02.800604Z","end":"2026-02-26T10:20:03.465215Z","steps":["trace[1112964500] 'read index received' (duration: 664.493717ms)","trace[1112964500] 'applied index is now lower than readState.Index' (duration: 116.343µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:20:03.465283Z","caller":"traceutil/trace.go:171","msg":"trace[1336906671] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"732.809978ms","start":"2026-02-26T10:20:02.73244Z","end":"2026-02-26T10:20:03.46525Z","steps":["trace[1336906671] 'process raft request' (duration: 732.671585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:03.465479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.997299ms","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":"warn","ts":"2026-02-26T10:20:03.46548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:02.732423Z","time spent":"732.933303ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:20:03.465507Z","caller":"traceutil/trace.go:171","msg":"trace[1551183986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:985; }","duration":"406.083501ms","start":"2026-02-26T10:20:03.059416Z","end":"2026-02-26T10:20:03.4655Z","steps":["trace[1551183986] 'agreement among raft nodes before linearized reading' (duration: 405.954128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:03.465528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:03.059394Z","time spent":"406.129762ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T10:20:03.465607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.270883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T10:20:03.465639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.330825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T10:20:03.465734Z","caller":"traceutil/trace.go:171","msg":"trace[221237420] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:985; }","duration":"500.426167ms","start":"2026-02-26T10:20:02.965266Z","end":"2026-02-26T10:20:03.465692Z","steps":["trace[221237420] 'agreement among raft nodes before linearized reading' (duration: 500.211102ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:20:03.465749Z","caller":"traceutil/trace.go:171","msg":"trace[645021806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:985; }","duration":"461.479178ms","start":"2026-02-26T10:20:03.004231Z","end":"2026-02-26T10:20:03.46571Z","steps":["trace[645021806] 'agreement among raft nodes before linearized reading' (duration: 461.228392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:03.465799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.890588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-26T10:20:03.465794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:02.965249Z","time spent":"500.532769ms","remote":"127.0.0.1:55108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-02-26T10:20:03.465822Z","caller":"traceutil/trace.go:171","msg":"trace[1375034190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:985; }","duration":"639.935559ms","start":"2026-02-26T10:20:02.825881Z","end":"2026-02-26T10:20:03.465816Z","steps":["trace[1375034190] 'agreement among raft nodes before linearized reading' (duration: 639.454586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:03.465807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:03.00421Z","time spent":"461.58402ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T10:20:03.465838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:02.825865Z","time spent":"639.9696ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T10:20:03.466335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.726916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T10:20:03.466398Z","caller":"traceutil/trace.go:171","msg":"trace[938408615] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:985; }","duration":"665.812679ms","start":"2026-02-26T10:20:02.800568Z","end":"2026-02-26T10:20:03.466381Z","steps":["trace[938408615] 'agreement among raft nodes before linearized reading' (duration: 664.718042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:03.466438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:02.800546Z","time spent":"665.882301ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T10:20:04.199561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.897679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T10:20:04.199795Z","caller":"traceutil/trace.go:171","msg":"trace[1665439204] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1030; }","duration":"313.486606ms","start":"2026-02-26T10:20:03.886298Z","end":"2026-02-26T10:20:04.199784Z","steps":["trace[1665439204] 'read index received' (duration: 28.54µs)","trace[1665439204] 'applied index is now lower than readState.Index' (duration: 313.457526ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:20:04.199854Z","caller":"traceutil/trace.go:171","msg":"trace[328515402] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"730.712809ms","start":"2026-02-26T10:20:03.469135Z","end":"2026-02-26T10:20:04.199847Z","steps":["trace[328515402] 'process raft request' (duration: 357.443932ms)","trace[328515402] 'compare' (duration: 372.722364ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:20:04.199884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:03.46911Z","time spent":"730.756891ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:20:04.199922Z","caller":"traceutil/trace.go:171","msg":"trace[629353445] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"729.272537ms","start":"2026-02-26T10:20:03.470627Z","end":"2026-02-26T10:20:04.1999Z","steps":["trace[629353445] 'process raft request' (duration: 729.138494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:04.200027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:03.470607Z","time spent":"729.359728ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:20:04.200064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.771662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:20:04.200082Z","caller":"traceutil/trace.go:171","msg":"trace[1505125224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:989; }","duration":"313.815094ms","start":"2026-02-26T10:20:03.88626Z","end":"2026-02-26T10:20:04.200075Z","steps":["trace[1505125224] 'agreement among raft nodes before linearized reading' (duration: 313.760923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:04.200097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:03.886245Z","time spent":"313.849454ms","remote":"127.0.0.1:52950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T10:20:04.200145Z","caller":"traceutil/trace.go:171","msg":"trace[353779763] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"730.251779ms","start":"2026-02-26T10:20:03.469873Z","end":"2026-02-26T10:20:04.200125Z","steps":["trace[353779763] 'process raft request' (duration: 729.784298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:04.200173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.767936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:20:04.200224Z","caller":"traceutil/trace.go:171","msg":"trace[819584545] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:989; }","duration":"205.827107ms","start":"2026-02-26T10:20:03.994383Z","end":"2026-02-26T10:20:04.20021Z","steps":["trace[819584545] 'agreement among raft nodes before linearized reading' (duration: 205.745185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:04.20024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:03.469854Z","time spent":"730.336701ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:20:04.200167Z","caller":"traceutil/trace.go:171","msg":"trace[186346424] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"729.993743ms","start":"2026-02-26T10:20:03.470167Z","end":"2026-02-26T10:20:04.200161Z","steps":["trace[186346424] 'process raft request' (duration: 729.565563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:04.200321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:03.47016Z","time spent":"730.141726ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:20:04.627481Z","caller":"traceutil/trace.go:171","msg":"trace[937068724] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"332.605567ms","start":"2026-02-26T10:20:04.294859Z","end":"2026-02-26T10:20:04.627464Z","steps":["trace[937068724] 'process raft request' (duration: 332.497884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:04.627593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:04.294835Z","time spent":"332.699268ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:20:04.893423Z","caller":"traceutil/trace.go:171","msg":"trace[1077197343] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"107.205989ms","start":"2026-02-26T10:20:04.786147Z","end":"2026-02-26T10:20:04.893353Z","steps":["trace[1077197343] 'process raft request' (duration: 107.087226ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:20:05.679756Z","caller":"traceutil/trace.go:171","msg":"trace[684277450] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"206.191235ms","start":"2026-02-26T10:20:05.473541Z","end":"2026-02-26T10:20:05.679732Z","steps":["trace[684277450] 'process raft request' (duration: 206.039242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:06.456681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.230476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T10:20:06.456968Z","caller":"traceutil/trace.go:171","msg":"trace[2068140834] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"570.195312ms","start":"2026-02-26T10:20:05.886756Z","end":"2026-02-26T10:20:06.456951Z","steps":["trace[2068140834] 'read index received' (duration: 115.537176ms)","trace[2068140834] 'applied index is now lower than readState.Index' (duration: 454.656316ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:20:06.45746Z","caller":"traceutil/trace.go:171","msg":"trace[1664567214] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"944.895084ms","start":"2026-02-26T10:20:05.512532Z","end":"2026-02-26T10:20:06.457427Z","steps":["trace[1664567214] 'process raft request' (duration: 489.820387ms)","trace[1664567214] 'compare' (duration: 453.873168ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:20:06.457707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.129035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T10:20:06.457762Z","caller":"traceutil/trace.go:171","msg":"trace[176532050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:998; }","duration":"254.218678ms","start":"2026-02-26T10:20:06.203529Z","end":"2026-02-26T10:20:06.457748Z","steps":["trace[176532050] 'agreement among raft nodes before linearized reading' (duration: 254.045054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:06.457752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.31502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-26T10:20:06.457718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.547596ms","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":"warn","ts":"2026-02-26T10:20:06.457915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.696769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T10:20:06.457995Z","caller":"traceutil/trace.go:171","msg":"trace[506318076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:998; }","duration":"254.800892ms","start":"2026-02-26T10:20:06.203176Z","end":"2026-02-26T10:20:06.457977Z","steps":["trace[506318076] 'agreement among raft nodes before linearized reading' (duration: 254.570967ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:20:06.457917Z","caller":"traceutil/trace.go:171","msg":"trace[555628662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:998; }","duration":"254.782831ms","start":"2026-02-26T10:20:06.203122Z","end":"2026-02-26T10:20:06.457905Z","steps":["trace[555628662] 'agreement among raft nodes before linearized reading' (duration: 254.338801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:06.457333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.536341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T10:20:06.458117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:05.512512Z","time spent":"945.017047ms","remote":"127.0.0.1:53204","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-02-26T10:20:06.45782Z","caller":"traceutil/trace.go:171","msg":"trace[1080811599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:998; }","duration":"254.399082ms","start":"2026-02-26T10:20:06.203403Z","end":"2026-02-26T10:20:06.457802Z","steps":["trace[1080811599] 'agreement among raft nodes before linearized reading' (duration: 254.179457ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:20:06.458145Z","caller":"traceutil/trace.go:171","msg":"trace[251884648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"571.425312ms","start":"2026-02-26T10:20:05.886708Z","end":"2026-02-26T10:20:06.458133Z","steps":["trace[251884648] 'agreement among raft nodes before linearized reading' (duration: 570.51546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:06.458504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:05.886687Z","time spent":"571.804051ms","remote":"127.0.0.1:52942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T10:20:06.663872Z","caller":"traceutil/trace.go:171","msg":"trace[2128374306] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"200.558611ms","start":"2026-02-26T10:20:06.463283Z","end":"2026-02-26T10:20:06.663842Z","steps":["trace[2128374306] 'process raft request' (duration: 138.339734ms)","trace[2128374306] 'compare' (duration: 61.927031ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:20:06.696709Z","caller":"traceutil/trace.go:171","msg":"trace[1894813408] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"233.275123ms","start":"2026-02-26T10:20:06.46341Z","end":"2026-02-26T10:20:06.696685Z","steps":["trace[1894813408] 'process raft request' (duration: 233.165571ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:20:06.696804Z","caller":"traceutil/trace.go:171","msg":"trace[598074336] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"233.214932ms","start":"2026-02-26T10:20:06.463579Z","end":"2026-02-26T10:20:06.696794Z","steps":["trace[598074336] 'process raft request' (duration: 233.063918ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:20:06.696723Z","caller":"traceutil/trace.go:171","msg":"trace[263784278] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"233.368125ms","start":"2026-02-26T10:20:06.463318Z","end":"2026-02-26T10:20:06.696686Z","steps":["trace[263784278] 'process raft request' (duration: 233.11596ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:20:07.992002Z","caller":"traceutil/trace.go:171","msg":"trace[1021890124] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"304.79037ms","start":"2026-02-26T10:20:07.687188Z","end":"2026-02-26T10:20:07.991978Z","steps":["trace[1021890124] 'process raft request' (duration: 304.592976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:07.992241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:07.687175Z","time spent":"304.973644ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:20:08.840898Z","caller":"traceutil/trace.go:171","msg":"trace[1307597537] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1049; }","duration":"140.810062ms","start":"2026-02-26T10:20:08.700056Z","end":"2026-02-26T10:20:08.840866Z","steps":["trace[1307597537] 'read index received' (duration: 140.536456ms)","trace[1307597537] 'applied index is now lower than readState.Index' (duration: 272.426µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:20:08.841348Z","caller":"traceutil/trace.go:171","msg":"trace[1744384657] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"166.595201ms","start":"2026-02-26T10:20:08.674739Z","end":"2026-02-26T10:20:08.841334Z","steps":["trace[1744384657] 'process raft request' (duration: 165.928754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:08.841632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.483251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-26T10:20:08.841633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.55039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-26T10:20:08.841717Z","caller":"traceutil/trace.go:171","msg":"trace[595262009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1004; }","duration":"139.584843ms","start":"2026-02-26T10:20:08.702112Z","end":"2026-02-26T10:20:08.841697Z","steps":["trace[595262009] 'agreement among raft nodes before linearized reading' (duration: 139.337607ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:20:08.841727Z","caller":"traceutil/trace.go:171","msg":"trace[904767278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1004; }","duration":"141.700114ms","start":"2026-02-26T10:20:08.700009Z","end":"2026-02-26T10:20:08.841709Z","steps":["trace[904767278] 'agreement among raft nodes before linearized reading' (duration: 141.437767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:08.842097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.284304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T10:20:08.842278Z","caller":"traceutil/trace.go:171","msg":"trace[1103974977] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1004; }","duration":"141.476288ms","start":"2026-02-26T10:20:08.700788Z","end":"2026-02-26T10:20:08.842264Z","steps":["trace[1103974977] 'agreement among raft nodes before linearized reading' (duration: 140.785892ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:20:12.97276Z","caller":"traceutil/trace.go:171","msg":"trace[914122071] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"102.900017ms","start":"2026-02-26T10:20:12.869825Z","end":"2026-02-26T10:20:12.972725Z","steps":["trace[914122071] 'process raft request' (duration: 102.750244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:15.883605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.132768ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T10:20:15.88384Z","caller":"traceutil/trace.go:171","msg":"trace[1307279738] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"966.39049ms","start":"2026-02-26T10:20:14.917431Z","end":"2026-02-26T10:20:15.883821Z","steps":["trace[1307279738] 'process raft request' (duration: 62.963715ms)","trace[1307279738] 'compare' (duration: 902.527684ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:20:15.883921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:14.917411Z","time spent":"966.474002ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:20:15.883703Z","caller":"traceutil/trace.go:171","msg":"trace[282607762] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"916.072933ms","start":"2026-02-26T10:20:14.967615Z","end":"2026-02-26T10:20:15.883688Z","steps":["trace[282607762] 'read index received' (duration: 12.776722ms)","trace[282607762] 'applied index is now lower than readState.Index' (duration: 903.295051ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:20:15.884148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.534735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-26T10:20:15.884292Z","caller":"traceutil/trace.go:171","msg":"trace[1359396699] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1055; }","duration":"916.677658ms","start":"2026-02-26T10:20:14.967564Z","end":"2026-02-26T10:20:15.884242Z","steps":["trace[1359396699] 'agreement among raft nodes before linearized reading' (duration: 916.457823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:15.884421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:14.967546Z","time spent":"916.784571ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-26T10:20:15.884595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.475091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-02-26T10:20:15.884594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.626499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T10:20:15.884631Z","caller":"traceutil/trace.go:171","msg":"trace[304758251] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1055; }","duration":"192.541943ms","start":"2026-02-26T10:20:15.69208Z","end":"2026-02-26T10:20:15.884622Z","steps":["trace[304758251] 'agreement among raft nodes before linearized reading' (duration: 192.449661ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:20:15.884661Z","caller":"traceutil/trace.go:171","msg":"trace[1699261135] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1055; }","duration":"900.694751ms","start":"2026-02-26T10:20:14.983933Z","end":"2026-02-26T10:20:15.884627Z","steps":["trace[1699261135] 'agreement among raft nodes before linearized reading' (duration: 900.567938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:15.8847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:14.983907Z","time spent":"900.784843ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T10:20:15.884489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.230275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-26T10:20:15.884869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.633863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-26T10:20:15.884905Z","caller":"traceutil/trace.go:171","msg":"trace[474941274] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1055; }","duration":"901.710855ms","start":"2026-02-26T10:20:14.983186Z","end":"2026-02-26T10:20:15.884897Z","steps":["trace[474941274] 'agreement among raft nodes before linearized reading' (duration: 901.629553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:15.884937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:14.983165Z","time spent":"901.764485ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T10:20:15.885113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.95745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T10:20:15.88515Z","caller":"traceutil/trace.go:171","msg":"trace[667466629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1055; }","duration":"902.021531ms","start":"2026-02-26T10:20:14.983121Z","end":"2026-02-26T10:20:15.885142Z","steps":["trace[667466629] 'agreement among raft nodes before linearized reading' (duration: 901.908929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:15.885175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:14.983108Z","time spent":"902.060423ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T10:20:15.884825Z","caller":"traceutil/trace.go:171","msg":"trace[903668697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1055; }","duration":"907.597484ms","start":"2026-02-26T10:20:14.977217Z","end":"2026-02-26T10:20:15.884814Z","steps":["trace[903668697] 'agreement among raft nodes before linearized reading' (duration: 907.208825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:15.885352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:14.977205Z","time spent":"908.133626ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T10:20:17.259484Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.229077638s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-26T10:20:17.261833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231255918s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T10:20:17.262165Z","caller":"traceutil/trace.go:171","msg":"trace[1523250934] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"1.37281972s","start":"2026-02-26T10:20:15.88931Z","end":"2026-02-26T10:20:17.262129Z","steps":["trace[1523250934] 'process raft request' (duration: 141.058749ms)","trace[1523250934] 'compare' (duration: 1.230873429s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:20:17.262271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:15.889299Z","time spent":"1.372922152s","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:20:17.263043Z","caller":"traceutil/trace.go:171","msg":"trace[1605190190] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"1.3733023s","start":"2026-02-26T10:20:15.889702Z","end":"2026-02-26T10:20:17.263005Z","steps":["trace[1605190190] 'process raft request' (duration: 1.372271025s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:17.263204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:15.889695Z","time spent":"1.373418543s","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:20:17.263447Z","caller":"traceutil/trace.go:171","msg":"trace[40619117] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"1.373484944s","start":"2026-02-26T10:20:15.889835Z","end":"2026-02-26T10:20:17.26332Z","steps":["trace[40619117] 'process raft request' (duration: 1.373091235s)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:20:17.263444Z","caller":"traceutil/trace.go:171","msg":"trace[1201098224] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"1.372184154s","start":"2026-02-26T10:20:15.891232Z","end":"2026-02-26T10:20:17.263416Z","steps":["trace[1201098224] 'process raft request' (duration: 1.371946328s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:17.26362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:15.88983Z","time spent":"1.373689809s","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:20:17.263618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:15.891217Z","time spent":"1.372330238s","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:20:17.263631Z","caller":"traceutil/trace.go:171","msg":"trace[56596087] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"1.372353918s","start":"2026-02-26T10:20:15.891257Z","end":"2026-02-26T10:20:17.263611Z","steps":["trace[56596087] 'process raft request' (duration: 1.372046391s)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:20:17.263667Z","caller":"traceutil/trace.go:171","msg":"trace[351123017] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"1.372338198s","start":"2026-02-26T10:20:15.891299Z","end":"2026-02-26T10:20:17.263637Z","steps":["trace[351123017] 'process raft request' (duration: 1.372144413s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:17.263727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:15.891248Z","time spent":"1.37242865s","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:20:17.263759Z","caller":"traceutil/trace.go:171","msg":"trace[1519982868] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"742.506418ms","start":"2026-02-26T10:20:16.521242Z","end":"2026-02-26T10:20:17.263748Z","steps":["trace[1519982868] 'process raft request' (duration: 742.312394ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:20:17.263776Z","caller":"traceutil/trace.go:171","msg":"trace[482706584] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1102; }","duration":"1.240740133s","start":"2026-02-26T10:20:16.023Z","end":"2026-02-26T10:20:17.26374Z","steps":["trace[482706584] 'read index received' (duration: 7.373725ms)","trace[482706584] 'applied index is now lower than readState.Index' (duration: 1.233364128s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:20:17.263812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:16.521216Z","time spent":"742.566929ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:20:17.263801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:15.891291Z","time spent":"1.37244037s","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:20:17.268208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.072281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:20:17.268268Z","caller":"traceutil/trace.go:171","msg":"trace[1793812097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1062; }","duration":"381.162323ms","start":"2026-02-26T10:20:16.887088Z","end":"2026-02-26T10:20:17.26825Z","steps":["trace[1793812097] 'agreement among raft nodes before linearized reading' (duration: 381.085921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:17.268302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:16.88707Z","time spent":"381.225114ms","remote":"127.0.0.1:52942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T10:20:17.268363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.245355842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T10:20:17.268447Z","caller":"traceutil/trace.go:171","msg":"trace[632141151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1062; }","duration":"1.245444774s","start":"2026-02-26T10:20:16.022948Z","end":"2026-02-26T10:20:17.268393Z","steps":["trace[632141151] 'agreement among raft nodes before linearized reading' (duration: 1.240871266s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:20:17.268486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:20:16.022928Z","time spent":"1.245551056s","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T10:20:21.057689Z","caller":"traceutil/trace.go:171","msg":"trace[928252052] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"162.489764ms","start":"2026-02-26T10:20:20.895182Z","end":"2026-02-26T10:20:21.057672Z","steps":["trace[928252052] 'process raft request' (duration: 162.382151ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:22:36.611632Z","caller":"traceutil/trace.go:171","msg":"trace[277594100] linearizableReadLoop","detail":"{readStateIndex:1703; appliedIndex:1701; }","duration":"161.596607ms","start":"2026-02-26T10:22:36.450011Z","end":"2026-02-26T10:22:36.611607Z","steps":["trace[277594100] 'read index received' (duration: 25.089282ms)","trace[277594100] 'applied index is now lower than readState.Index' (duration: 136.506415ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:22:36.611759Z","caller":"traceutil/trace.go:171","msg":"trace[2043718424] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"179.166943ms","start":"2026-02-26T10:22:36.432548Z","end":"2026-02-26T10:22:36.611715Z","steps":["trace[2043718424] 'process raft request' (duration: 106.64879ms)","trace[2043718424] 'compare' (duration: 72.303478ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:22:36.611882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.845524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-26T10:22:36.61192Z","caller":"traceutil/trace.go:171","msg":"trace[907655613] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1628; }","duration":"161.933045ms","start":"2026-02-26T10:22:36.449977Z","end":"2026-02-26T10:22:36.61191Z","steps":["trace[907655613] 'agreement among raft nodes before linearized reading' (duration: 161.774472ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:23:11.118154Z","caller":"traceutil/trace.go:171","msg":"trace[234219621] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"114.614094ms","start":"2026-02-26T10:23:11.003523Z","end":"2026-02-26T10:23:11.118137Z","steps":["trace[234219621] 'process raft request' (duration: 80.404261ms)","trace[234219621] 'compare' (duration: 33.996668ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:23:11.118427Z","caller":"traceutil/trace.go:171","msg":"trace[808270979] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"113.428747ms","start":"2026-02-26T10:23:11.004975Z","end":"2026-02-26T10:23:11.118404Z","steps":["trace[808270979] 'process raft request' (duration: 113.13178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:23:11.817066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.711277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:23:11.817158Z","caller":"traceutil/trace.go:171","msg":"trace[2064643247] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1792; }","duration":"143.866801ms","start":"2026-02-26T10:23:11.673265Z","end":"2026-02-26T10:23:11.817132Z","steps":["trace[2064643247] 'count revisions from in-memory index tree' (duration: 143.637765ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:23:11.817438Z","caller":"traceutil/trace.go:171","msg":"trace[443312284] linearizableReadLoop","detail":"{readStateIndex:1877; appliedIndex:1876; }","duration":"142.927808ms","start":"2026-02-26T10:23:11.674442Z","end":"2026-02-26T10:23:11.81737Z","steps":["trace[443312284] 'read index received' (duration: 142.031387ms)","trace[443312284] 'applied index is now lower than readState.Index' (duration: 893.921µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:23:11.817655Z","caller":"traceutil/trace.go:171","msg":"trace[1679884421] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"143.818329ms","start":"2026-02-26T10:23:11.673788Z","end":"2026-02-26T10:23:11.817606Z","steps":["trace[1679884421] 'process raft request' (duration: 142.623341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:23:11.817749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.289406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-02-26T10:23:11.817802Z","caller":"traceutil/trace.go:171","msg":"trace[868671495] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1793; }","duration":"143.373559ms","start":"2026-02-26T10:23:11.674414Z","end":"2026-02-26T10:23:11.817787Z","steps":["trace[868671495] 'agreement among raft nodes before linearized reading' (duration: 143.093762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:23:23.01904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.137792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-02-26T10:23:23.01915Z","caller":"traceutil/trace.go:171","msg":"trace[638299644] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1846; }","duration":"317.339566ms","start":"2026-02-26T10:23:22.701787Z","end":"2026-02-26T10:23:23.019126Z","steps":["trace[638299644] 'range keys from in-memory index tree' (duration: 316.917856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:23:23.019192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:23:22.701762Z","time spent":"317.422168ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-26T10:23:23.019313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.53634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-02-26T10:23:23.019323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.474231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:23:23.01941Z","caller":"traceutil/trace.go:171","msg":"trace[1215034582] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1846; }","duration":"211.683454ms","start":"2026-02-26T10:23:22.807705Z","end":"2026-02-26T10:23:23.019388Z","steps":["trace[1215034582] 'range keys from in-memory index tree' (duration: 211.310825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:23:23.019464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.512131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-26T10:23:23.019528Z","caller":"traceutil/trace.go:171","msg":"trace[8694026] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1846; }","duration":"299.623184ms","start":"2026-02-26T10:23:22.719887Z","end":"2026-02-26T10:23:23.01951Z","steps":["trace[8694026] 'range keys from in-memory index tree' (duration: 299.257166ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:23:23.019465Z","caller":"traceutil/trace.go:171","msg":"trace[347107342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1846; }","duration":"132.676525ms","start":"2026-02-26T10:23:22.886756Z","end":"2026-02-26T10:23:23.019433Z","steps":["trace[347107342] 'range keys from in-memory index tree' (duration: 132.317386ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:23:23.19461Z","caller":"traceutil/trace.go:171","msg":"trace[403760721] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"166.310544ms","start":"2026-02-26T10:23:23.028272Z","end":"2026-02-26T10:23:23.194583Z","steps":["trace[403760721] 'process raft request' (duration: 166.189091ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:23:23.201687Z","caller":"traceutil/trace.go:171","msg":"trace[23940127] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"172.981284ms","start":"2026-02-26T10:23:23.028699Z","end":"2026-02-26T10:23:23.20168Z","steps":["trace[23940127] 'process raft request' (duration: 172.941633ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:23:23.201809Z","caller":"traceutil/trace.go:171","msg":"trace[711813038] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"173.173818ms","start":"2026-02-26T10:23:23.028629Z","end":"2026-02-26T10:23:23.201803Z","steps":["trace[711813038] 'process raft request' (duration: 172.968493ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:23:23.202728Z","caller":"traceutil/trace.go:171","msg":"trace[1186237871] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"173.938946ms","start":"2026-02-26T10:23:23.028782Z","end":"2026-02-26T10:23:23.202721Z","steps":["trace[1186237871] 'process raft request' (duration: 173.105796ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:23:23.202834Z","caller":"traceutil/trace.go:171","msg":"trace[2107438317] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"174.15412ms","start":"2026-02-26T10:23:23.028675Z","end":"2026-02-26T10:23:23.202829Z","steps":["trace[2107438317] 'process raft request' (duration: 172.985493ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:23:24.230118Z","caller":"traceutil/trace.go:171","msg":"trace[503824364] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"176.470436ms","start":"2026-02-26T10:23:24.053629Z","end":"2026-02-26T10:23:24.230099Z","steps":["trace[503824364] 'process raft request' (duration: 176.357154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:23:24.380663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.571609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T10:23:24.380748Z","caller":"traceutil/trace.go:171","msg":"trace[897754200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1852; }","duration":"102.698322ms","start":"2026-02-26T10:23:24.27803Z","end":"2026-02-26T10:23:24.380728Z","steps":["trace[897754200] 'range keys from in-memory index tree' (duration: 102.404185ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:23:24.563058Z","caller":"traceutil/trace.go:171","msg":"trace[1938883527] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"138.610226ms","start":"2026-02-26T10:23:24.424418Z","end":"2026-02-26T10:23:24.563028Z","steps":["trace[1938883527] 'read index received' (duration: 138.367641ms)","trace[1938883527] 'applied index is now lower than readState.Index' (duration: 241.065µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:23:24.563113Z","caller":"traceutil/trace.go:171","msg":"trace[1838102444] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"178.388502ms","start":"2026-02-26T10:23:24.384691Z","end":"2026-02-26T10:23:24.563079Z","steps":["trace[1838102444] 'process raft request' (duration: 178.156287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:23:24.563437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.975035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T10:23:24.563553Z","caller":"traceutil/trace.go:171","msg":"trace[1529016203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1853; }","duration":"139.16812ms","start":"2026-02-26T10:23:24.424359Z","end":"2026-02-26T10:23:24.563527Z","steps":["trace[1529016203] 'agreement among raft nodes before linearized reading' (duration: 138.842102ms)"],"step_count":1}