{"level":"warn","ts":"2026-02-08T21:41:16.756809Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-08T21:41:16.756916Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-08T21:41:16.757061Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-08T21:41:16.757076Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-08T21:41:16.757178Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-08T21:41:16.767123Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-02-08T21:41:16.770967Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-08T21:41:16.778108Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.818507ms"} {"level":"info","ts":"2026-02-08T21:41:16.791247Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-02-08T21:41:16.791347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T21:41:16.791422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-02-08T21:41:16.791457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T21:41:16.791496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-02-08T21:41:16.791529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-02-08T21:41:16.820228Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T21:41:16.82714Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T21:41:16.832825Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-08T21:41:16.837833Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T21:41:16.839006Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-08T21:41:16.839039Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-08T21:41:16.839046Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-08T21:41:16.844939Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T21:41:16.84524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-02-08T21:41:16.845339Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-08T21:41:16.8467Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-08T21:41:16.846832Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-08T21:41:16.846853Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-08T21:41:16.855284Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T21:41:16.85531Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T21:41:17.196056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T21:41:17.196092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T21:41:17.196109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-02-08T21:41:17.19612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-02-08T21:41:17.196125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-08T21:41:17.196133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-02-08T21:41:17.196145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-08T21:41:17.231545Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:17.233531Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T21:41:17.233568Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T21:41:17.239447Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T21:41:17.251457Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-08T21:41:17.25249Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T21:41:17.252539Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T21:41:17.252698Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-02-08T21:41:17.309739Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:17.309947Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:17.309966Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-08T21:41:19.628087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.18991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-08T21:41:19.628198Z","caller":"traceutil/trace.go:171","msg":"trace[1572455845] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"393.764088ms","start":"2026-02-08T21:41:19.234416Z","end":"2026-02-08T21:41:19.62818Z","steps":["trace[1572455845] 'process raft request' (duration: 145.996795ms)","trace[1572455845] 'compare' (duration: 247.052507ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:19.628284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:19.234407Z","time spent":"393.832429ms","remote":"127.0.0.1:36756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T21:41:19.649816Z","caller":"traceutil/trace.go:171","msg":"trace[206196497] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"415.269332ms","start":"2026-02-08T21:41:19.234529Z","end":"2026-02-08T21:41:19.649799Z","steps":["trace[206196497] 'process raft request' (duration: 415.118348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:19.650601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:19.234523Z","time spent":"415.370034ms","remote":"127.0.0.1:36756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1058,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:41:25.446657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.134466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-08T21:41:25.446689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.907263ms","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-08T21:41:25.446791Z","caller":"traceutil/trace.go:171","msg":"trace[32121974] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:331; }","duration":"562.058137ms","start":"2026-02-08T21:41:24.88471Z","end":"2026-02-08T21:41:25.446768Z","steps":["trace[32121974] 'range keys from in-memory index tree' (duration: 561.844011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:25.446841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:24.884697Z","time spent":"562.130798ms","remote":"127.0.0.1:36548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" "} {"level":"info","ts":"2026-02-08T21:41:25.446751Z","caller":"traceutil/trace.go:171","msg":"trace[1913150957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:331; }","duration":"246.29162ms","start":"2026-02-08T21:41:25.200442Z","end":"2026-02-08T21:41:25.446733Z","steps":["trace[1913150957] 'range keys from in-memory index tree' (duration: 246.009954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:25.447182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.441886ms","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-08T21:41:25.447217Z","caller":"traceutil/trace.go:171","msg":"trace[1415397024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:331; }","duration":"247.528869ms","start":"2026-02-08T21:41:25.199677Z","end":"2026-02-08T21:41:25.447205Z","steps":["trace[1415397024] 'range keys from in-memory index tree' (duration: 247.332954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:26.115612Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021238627858920,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:41:26.61611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021238627858920,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:41:26.623998Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.172244299s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T21:41:26.624516Z","caller":"traceutil/trace.go:171","msg":"trace[836118764] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"1.172849583s","start":"2026-02-08T21:41:25.451649Z","end":"2026-02-08T21:41:26.624499Z","steps":["trace[836118764] 'process raft request' (duration: 1.17273246s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:26.62461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:25.451635Z","time spent":"1.172916155s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:26.649741Z","caller":"traceutil/trace.go:171","msg":"trace[1326631940] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:338; }","duration":"1.035169289s","start":"2026-02-08T21:41:25.614557Z","end":"2026-02-08T21:41:26.649727Z","steps":["trace[1326631940] 'read index received' (duration: 1.009760825s)","trace[1326631940] 'applied index is now lower than readState.Index' (duration: 25.407934ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:41:26.64991Z","caller":"traceutil/trace.go:171","msg":"trace[156597376] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"1.195574416s","start":"2026-02-08T21:41:25.454327Z","end":"2026-02-08T21:41:26.649901Z","steps":["trace[156597376] 'process raft request' (duration: 1.195308939s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:26.649967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:25.454311Z","time spent":"1.195612586s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:26.650087Z","caller":"traceutil/trace.go:171","msg":"trace[2140135993] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"1.195708239s","start":"2026-02-08T21:41:25.454374Z","end":"2026-02-08T21:41:26.650082Z","steps":["trace[2140135993] 'process raft request' (duration: 1.19532878s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:26.650109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:25.454332Z","time spent":"1.195765349s","remote":"127.0.0.1:36548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-08T21:41:26.650175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03561849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T21:41:26.650192Z","caller":"traceutil/trace.go:171","msg":"trace[1501388978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:334; }","duration":"1.035635s","start":"2026-02-08T21:41:25.614551Z","end":"2026-02-08T21:41:26.650186Z","steps":["trace[1501388978] 'agreement among raft nodes before linearized reading' (duration: 1.035602359s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:26.650205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:25.614501Z","time spent":"1.035701751s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:41:26.650298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.812152ms","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-08T21:41:26.650374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.79737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:41:26.650363Z","caller":"traceutil/trace.go:171","msg":"trace[430966550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:334; }","duration":"840.924875ms","start":"2026-02-08T21:41:25.809421Z","end":"2026-02-08T21:41:26.650346Z","steps":["trace[430966550] 'agreement among raft nodes before linearized reading' (duration: 840.789022ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:26.650393Z","caller":"traceutil/trace.go:171","msg":"trace[1691679718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:334; }","duration":"410.844902ms","start":"2026-02-08T21:41:26.239542Z","end":"2026-02-08T21:41:26.650387Z","steps":["trace[1691679718] 'agreement among raft nodes before linearized reading' (duration: 410.818741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:26.650425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:26.239523Z","time spent":"410.899212ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:41:26.650418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:25.80936Z","time spent":"841.040578ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-08T21:41:27.778196Z","caller":"traceutil/trace.go:171","msg":"trace[153647085] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"113.859536ms","start":"2026-02-08T21:41:27.66432Z","end":"2026-02-08T21:41:27.77818Z","steps":["trace[153647085] 'process raft request' (duration: 113.759784ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:50.188002Z","caller":"traceutil/trace.go:171","msg":"trace[402482343] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"175.311689ms","start":"2026-02-08T21:41:50.012673Z","end":"2026-02-08T21:41:50.187985Z","steps":["trace[402482343] 'process raft request' (duration: 175.196327ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:51.026895Z","caller":"traceutil/trace.go:171","msg":"trace[134633210] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"213.518617ms","start":"2026-02-08T21:41:50.813363Z","end":"2026-02-08T21:41:51.026882Z","steps":["trace[134633210] 'read index received' (duration: 213.436915ms)","trace[134633210] 'applied index is now lower than readState.Index' (duration: 81.122µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:51.026959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:50.604602Z","time spent":"422.353826ms","remote":"127.0.0.1:36366","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-08T21:41:51.02713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.756732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:41:51.027147Z","caller":"traceutil/trace.go:171","msg":"trace[401933609] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:523; }","duration":"213.781933ms","start":"2026-02-08T21:41:50.81336Z","end":"2026-02-08T21:41:51.027142Z","steps":["trace[401933609] 'agreement among raft nodes before linearized reading' (duration: 213.702581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:51.027232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.052177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:41:51.027244Z","caller":"traceutil/trace.go:171","msg":"trace[638600731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"203.073697ms","start":"2026-02-08T21:41:50.824167Z","end":"2026-02-08T21:41:51.027241Z","steps":["trace[638600731] 'agreement among raft nodes before linearized reading' (duration: 203.039236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:51.027357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.04498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T21:41:51.027369Z","caller":"traceutil/trace.go:171","msg":"trace[693261171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"171.067232ms","start":"2026-02-08T21:41:50.856299Z","end":"2026-02-08T21:41:51.027366Z","steps":["trace[693261171] 'agreement among raft nodes before linearized reading' (duration: 171.029221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:51.027463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.238992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T21:41:51.027476Z","caller":"traceutil/trace.go:171","msg":"trace[2041915243] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"180.263312ms","start":"2026-02-08T21:41:50.847209Z","end":"2026-02-08T21:41:51.027472Z","steps":["trace[2041915243] 'agreement among raft nodes before linearized reading' (duration: 180.209851ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:53.70515Z","caller":"traceutil/trace.go:171","msg":"trace[267324046] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"638.563345ms","start":"2026-02-08T21:41:53.066559Z","end":"2026-02-08T21:41:53.705122Z","steps":["trace[267324046] 'process raft request' (duration: 638.427742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:53.705315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:53.066548Z","time spent":"638.680067ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:53.796497Z","caller":"traceutil/trace.go:171","msg":"trace[1929106470] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"729.259129ms","start":"2026-02-08T21:41:53.06722Z","end":"2026-02-08T21:41:53.796479Z","steps":["trace[1929106470] 'process raft request' (duration: 729.052945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:53.796605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:53.067213Z","time spent":"729.331611ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:53.796652Z","caller":"traceutil/trace.go:171","msg":"trace[1763011275] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"727.616171ms","start":"2026-02-08T21:41:53.069022Z","end":"2026-02-08T21:41:53.796638Z","steps":["trace[1763011275] 'process raft request' (duration: 727.433107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:53.7967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:53.069014Z","time spent":"727.660342ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:53.797088Z","caller":"traceutil/trace.go:171","msg":"trace[316700804] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"727.414266ms","start":"2026-02-08T21:41:53.069461Z","end":"2026-02-08T21:41:53.796875Z","steps":["trace[316700804] 'process raft request' (duration: 727.14682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:53.79716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:53.069455Z","time spent":"727.663182ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:53.797286Z","caller":"traceutil/trace.go:171","msg":"trace[634940253] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:542; }","duration":"326.324069ms","start":"2026-02-08T21:41:53.470941Z","end":"2026-02-08T21:41:53.797265Z","steps":["trace[634940253] 'read index received' (duration: 234.296635ms)","trace[634940253] 'applied index is now lower than readState.Index' (duration: 92.025994ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:53.797448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.503853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:41:53.797477Z","caller":"traceutil/trace.go:171","msg":"trace[736936488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"326.528424ms","start":"2026-02-08T21:41:53.470936Z","end":"2026-02-08T21:41:53.797464Z","steps":["trace[736936488] 'agreement among raft nodes before linearized reading' (duration: 326.376711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:53.797503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:53.470892Z","time spent":"326.605376ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T21:41:53.797679Z","caller":"traceutil/trace.go:171","msg":"trace[687720299] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"475.247522ms","start":"2026-02-08T21:41:53.322416Z","end":"2026-02-08T21:41:53.797663Z","steps":["trace[687720299] 'process raft request' (duration: 474.435303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:53.797729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:53.322398Z","time spent":"475.301563ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:56.213573Z","caller":"traceutil/trace.go:171","msg":"trace[1787806249] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"500.178114ms","start":"2026-02-08T21:41:55.713378Z","end":"2026-02-08T21:41:56.213556Z","steps":["trace[1787806249] 'process raft request' (duration: 500.030581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:56.213684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:55.713363Z","time spent":"500.258346ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:56.214052Z","caller":"traceutil/trace.go:171","msg":"trace[575691321] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:547; }","duration":"412.939349ms","start":"2026-02-08T21:41:55.801104Z","end":"2026-02-08T21:41:56.214044Z","steps":["trace[575691321] 'read index received' (duration: 412.936019ms)","trace[575691321] 'applied index is now lower than readState.Index' (duration: 2.61µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:56.21415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.042121ms","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-08T21:41:56.214169Z","caller":"traceutil/trace.go:171","msg":"trace[1454322114] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"413.099293ms","start":"2026-02-08T21:41:55.801064Z","end":"2026-02-08T21:41:56.214163Z","steps":["trace[1454322114] 'agreement among raft nodes before linearized reading' (duration: 413.022811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:56.214188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:55.801045Z","time spent":"413.138423ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:41:56.225206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.734604ms","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-08T21:41:56.225257Z","caller":"traceutil/trace.go:171","msg":"trace[1558855797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"422.813207ms","start":"2026-02-08T21:41:55.802431Z","end":"2026-02-08T21:41:56.225244Z","steps":["trace[1558855797] 'agreement among raft nodes before linearized reading' (duration: 422.646273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:56.225284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:55.80242Z","time spent":"422.859138ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T21:41:56.22546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.050698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:41:56.225493Z","caller":"traceutil/trace.go:171","msg":"trace[1276075684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"422.10476ms","start":"2026-02-08T21:41:55.80338Z","end":"2026-02-08T21:41:56.225485Z","steps":["trace[1276075684] 'agreement among raft nodes before linearized reading' (duration: 421.983927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:56.225524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:55.80337Z","time spent":"422.149091ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-08T21:41:58.461865Z","caller":"traceutil/trace.go:171","msg":"trace[434375633] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"243.734831ms","start":"2026-02-08T21:41:58.218119Z","end":"2026-02-08T21:41:58.461854Z","steps":["trace[434375633] 'process raft request' (duration: 243.424854ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:58.461703Z","caller":"traceutil/trace.go:171","msg":"trace[1256978786] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"234.598551ms","start":"2026-02-08T21:41:58.227084Z","end":"2026-02-08T21:41:58.461682Z","steps":["trace[1256978786] 'read index received' (duration: 234.376986ms)","trace[1256978786] 'applied index is now lower than readState.Index' (duration: 220.675µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:58.462288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.186405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:41:58.46232Z","caller":"traceutil/trace.go:171","msg":"trace[436207478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:538; }","duration":"235.239606ms","start":"2026-02-08T21:41:58.22707Z","end":"2026-02-08T21:41:58.46231Z","steps":["trace[436207478] 'agreement among raft nodes before linearized reading' (duration: 235.121613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:58.462628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.492859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:41:58.462658Z","caller":"traceutil/trace.go:171","msg":"trace[1563768604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"214.53673ms","start":"2026-02-08T21:41:58.248113Z","end":"2026-02-08T21:41:58.46265Z","steps":["trace[1563768604] 'agreement among raft nodes before linearized reading' (duration: 214.462208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:58.462873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.664144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T21:41:58.462933Z","caller":"traceutil/trace.go:171","msg":"trace[1189228760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"221.723435ms","start":"2026-02-08T21:41:58.241201Z","end":"2026-02-08T21:41:58.462924Z","steps":["trace[1189228760] 'agreement among raft nodes before linearized reading' (duration: 221.326206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:59.344152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.675208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:41:59.344346Z","caller":"traceutil/trace.go:171","msg":"trace[63462509] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:552; }","duration":"873.217476ms","start":"2026-02-08T21:41:58.471114Z","end":"2026-02-08T21:41:59.344331Z","steps":["trace[63462509] 'read index received' (duration: 455.313493ms)","trace[63462509] 'applied index is now lower than readState.Index' (duration: 417.903273ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:59.344409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.290438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:41:59.344425Z","caller":"traceutil/trace.go:171","msg":"trace[1861623509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"873.323179ms","start":"2026-02-08T21:41:58.471096Z","end":"2026-02-08T21:41:59.344419Z","steps":["trace[1861623509] 'agreement among raft nodes before linearized reading' (duration: 873.273257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:59.344493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:58.471089Z","time spent":"873.39743ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T21:41:59.344686Z","caller":"traceutil/trace.go:171","msg":"trace[386685565] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"874.878594ms","start":"2026-02-08T21:41:58.469798Z","end":"2026-02-08T21:41:59.344677Z","steps":["trace[386685565] 'process raft request' (duration: 456.619753ms)","trace[386685565] 'compare' (duration: 417.416522ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:59.344725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:58.469784Z","time spent":"874.920145ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:59.344802Z","caller":"traceutil/trace.go:171","msg":"trace[1170759651] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"874.870454ms","start":"2026-02-08T21:41:58.469926Z","end":"2026-02-08T21:41:59.344796Z","steps":["trace[1170759651] 'process raft request' (duration: 874.302321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:59.344837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:58.46992Z","time spent":"874.895555ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:59.34491Z","caller":"traceutil/trace.go:171","msg":"trace[171606634] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"874.945286ms","start":"2026-02-08T21:41:58.469959Z","end":"2026-02-08T21:41:59.344904Z","steps":["trace[171606634] 'process raft request' (duration: 874.344102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:59.344938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:58.469955Z","time spent":"874.967596ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:05.451827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.779221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:05.451914Z","caller":"traceutil/trace.go:171","msg":"trace[1462921144] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"384.819578ms","start":"2026-02-08T21:42:05.067079Z","end":"2026-02-08T21:42:05.451898Z","steps":["trace[1462921144] 'process raft request' (duration: 128.916404ms)","trace[1462921144] 'compare' (duration: 255.160517ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:05.451963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:05.067061Z","time spent":"384.881319ms","remote":"127.0.0.1:36370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T21:42:05.452284Z","caller":"traceutil/trace.go:171","msg":"trace[449579606] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"221.037504ms","start":"2026-02-08T21:42:05.231235Z","end":"2026-02-08T21:42:05.452273Z","steps":["trace[449579606] 'read index received' (duration: 220.940432ms)","trace[449579606] 'applied index is now lower than readState.Index' (duration: 96.282µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:05.452341Z","caller":"traceutil/trace.go:171","msg":"trace[1991187692] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"365.970546ms","start":"2026-02-08T21:42:05.086363Z","end":"2026-02-08T21:42:05.452334Z","steps":["trace[1991187692] 'process raft request' (duration: 365.850483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:05.452382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:05.086349Z","time spent":"366.010536ms","remote":"127.0.0.1:36404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-08T21:42:05.452878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.636848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-08T21:42:05.452912Z","caller":"traceutil/trace.go:171","msg":"trace[2029811712] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:612; }","duration":"221.71116ms","start":"2026-02-08T21:42:05.23119Z","end":"2026-02-08T21:42:05.452901Z","steps":["trace[2029811712] 'agreement among raft nodes before linearized reading' (duration: 221.616808ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:09.809903Z","caller":"traceutil/trace.go:171","msg":"trace[2032559291] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"215.818634ms","start":"2026-02-08T21:42:09.594041Z","end":"2026-02-08T21:42:09.80986Z","steps":["trace[2032559291] 'process raft request' (duration: 215.784083ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:09.810396Z","caller":"traceutil/trace.go:171","msg":"trace[354989270] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"218.443504ms","start":"2026-02-08T21:42:09.591944Z","end":"2026-02-08T21:42:09.810387Z","steps":["trace[354989270] 'process raft request' (duration: 154.928239ms)","trace[354989270] 'compare' (duration: 62.727987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:11.969502Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021238627859573,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:42:12.208791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.132148148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-fqn8x.1892636e1bd3ddf5\" ","response":"range_response_count:1 size:795"} {"level":"info","ts":"2026-02-08T21:42:12.208838Z","caller":"traceutil/trace.go:171","msg":"trace[553995895] range","detail":"{range_begin:/registry/events/kube-system/cilium-fqn8x.1892636e1bd3ddf5; range_end:; response_count:1; response_revision:656; }","duration":"2.13223208s","start":"2026-02-08T21:42:10.076595Z","end":"2026-02-08T21:42:12.208828Z","steps":["trace[553995895] 'range keys from in-memory index tree' (duration: 2.132035335s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:12.20886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:10.076581Z","time spent":"2.13227366s","remote":"127.0.0.1:36404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":818,"request content":"key:\"/registry/events/kube-system/cilium-fqn8x.1892636e1bd3ddf5\" "} {"level":"warn","ts":"2026-02-08T21:42:12.208982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.832517161s","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-08T21:42:12.209004Z","caller":"traceutil/trace.go:171","msg":"trace[988846011] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:656; }","duration":"1.832530522s","start":"2026-02-08T21:42:10.37646Z","end":"2026-02-08T21:42:12.208991Z","steps":["trace[988846011] 'range keys from in-memory index tree' (duration: 1.83243414s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:12.209016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:10.376406Z","time spent":"1.832606984s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:12.2092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.682680488s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T21:42:12.209214Z","caller":"traceutil/trace.go:171","msg":"trace[1739085997] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:656; }","duration":"1.68272831s","start":"2026-02-08T21:42:10.526481Z","end":"2026-02-08T21:42:12.209209Z","steps":["trace[1739085997] 'range keys from in-memory index tree' (duration: 1.682604017s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:12.209225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:10.526452Z","time spent":"1.682770811s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:42:12.209332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600938505s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T21:42:12.209345Z","caller":"traceutil/trace.go:171","msg":"trace[1262644462] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:656; }","duration":"1.600974246s","start":"2026-02-08T21:42:10.608367Z","end":"2026-02-08T21:42:12.209342Z","steps":["trace[1262644462] 'range keys from in-memory index tree' (duration: 1.600886494s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:12.209358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:10.608351Z","time spent":"1.601003927s","remote":"127.0.0.1:36366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.217\" "} {"level":"warn","ts":"2026-02-08T21:42:12.439441Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.815164784s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T21:42:12.439822Z","caller":"traceutil/trace.go:171","msg":"trace[1159133923] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.815612435s","start":"2026-02-08T21:42:10.624196Z","end":"2026-02-08T21:42:12.439808Z","steps":["trace[1159133923] 'process raft request' (duration: 1.815527193s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:12.439911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:10.624177Z","time spent":"1.815688496s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:13.430311Z","caller":"traceutil/trace.go:171","msg":"trace[305970073] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"1.962154572s","start":"2026-02-08T21:42:11.468142Z","end":"2026-02-08T21:42:13.430296Z","steps":["trace[305970073] 'read index received' (duration: 971.993673ms)","trace[305970073] 'applied index is now lower than readState.Index' (duration: 990.160399ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:13.430388Z","caller":"traceutil/trace.go:171","msg":"trace[1538830456] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.215957223s","start":"2026-02-08T21:42:12.214426Z","end":"2026-02-08T21:42:13.430383Z","steps":["trace[1538830456] 'process raft request' (duration: 1.215795019s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:13.430453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:12.214418Z","time spent":"1.215985483s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:13.430529Z","caller":"traceutil/trace.go:171","msg":"trace[1047914718] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.216070925s","start":"2026-02-08T21:42:12.214454Z","end":"2026-02-08T21:42:13.430525Z","steps":["trace[1047914718] 'process raft request' (duration: 1.215803619s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:13.430551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:12.214451Z","time spent":"1.216086895s","remote":"127.0.0.1:36404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:13.430653Z","caller":"traceutil/trace.go:171","msg":"trace[950367666] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"2.708167726s","start":"2026-02-08T21:42:10.722462Z","end":"2026-02-08T21:42:13.43063Z","steps":["trace[950367666] 'process raft request' (duration: 2.707618984s)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:13.430696Z","caller":"traceutil/trace.go:171","msg":"trace[572333429] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.216154717s","start":"2026-02-08T21:42:12.214536Z","end":"2026-02-08T21:42:13.430691Z","steps":["trace[572333429] 'process raft request' (duration: 1.215741257s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:13.430722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:12.214532Z","time spent":"1.216178007s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:13.43076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:10.722445Z","time spent":"2.708263768s","remote":"127.0.0.1:56676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1238,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-08T21:42:13.430803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.962661893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:13.43082Z","caller":"traceutil/trace.go:171","msg":"trace[418013287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"1.962694894s","start":"2026-02-08T21:42:11.46812Z","end":"2026-02-08T21:42:13.430815Z","steps":["trace[418013287] 'agreement among raft nodes before linearized reading' (duration: 1.962665903s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:13.430832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:11.468075Z","time spent":"1.962754335s","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:42:13.430923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:12.214239Z","time spent":"1.216683249s","remote":"127.0.0.1:36366","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-08T21:42:13.430951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.915947573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:42:13.430965Z","caller":"traceutil/trace.go:171","msg":"trace[1123677535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"1.915987223s","start":"2026-02-08T21:42:11.514974Z","end":"2026-02-08T21:42:13.430961Z","steps":["trace[1123677535] 'agreement among raft nodes before linearized reading' (duration: 1.915931492s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:13.430977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:11.514962Z","time spent":"1.916012614s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:42:13.431141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.354807804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fqn8x\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-08T21:42:13.431153Z","caller":"traceutil/trace.go:171","msg":"trace[1843915033] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fqn8x; range_end:; response_count:1; response_revision:661; }","duration":"1.354837824s","start":"2026-02-08T21:42:12.076312Z","end":"2026-02-08T21:42:13.431149Z","steps":["trace[1843915033] 'agreement among raft nodes before linearized reading' (duration: 1.354793273s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:13.431165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:12.076297Z","time spent":"1.354865376s","remote":"127.0.0.1:36538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18273,"request content":"key:\"/registry/pods/kube-system/cilium-fqn8x\" "} {"level":"warn","ts":"2026-02-08T21:42:13.431608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.617995705s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:42:13.431636Z","caller":"traceutil/trace.go:171","msg":"trace[1214699348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"1.618030985s","start":"2026-02-08T21:42:11.813598Z","end":"2026-02-08T21:42:13.431629Z","steps":["trace[1214699348] 'agreement among raft nodes before linearized reading' (duration: 1.617973104s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:13.431651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:11.813595Z","time spent":"1.618051927s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:13.431854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.61819076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T21:42:13.431907Z","caller":"traceutil/trace.go:171","msg":"trace[1648428526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"1.618326992s","start":"2026-02-08T21:42:11.813564Z","end":"2026-02-08T21:42:13.431891Z","steps":["trace[1648428526] 'agreement among raft nodes before linearized reading' (duration: 1.618110067s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:13.431959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:11.813551Z","time spent":"1.618396505s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:13.940953Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021238627859582,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:42:14.358699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.296528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:14.35879Z","caller":"traceutil/trace.go:171","msg":"trace[76159406] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"919.503551ms","start":"2026-02-08T21:42:13.43927Z","end":"2026-02-08T21:42:14.358773Z","steps":["trace[76159406] 'process raft request' (duration: 572.045269ms)","trace[76159406] 'compare' (duration: 346.871848ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:14.358847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:13.439256Z","time spent":"919.564082ms","remote":"127.0.0.1:36366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:14.402462Z","caller":"traceutil/trace.go:171","msg":"trace[502240596] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:681; }","duration":"962.508365ms","start":"2026-02-08T21:42:13.439943Z","end":"2026-02-08T21:42:14.402452Z","steps":["trace[502240596] 'read index received' (duration: 571.378593ms)","trace[502240596] 'applied index is now lower than readState.Index' (duration: 391.129322ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:14.402927Z","caller":"traceutil/trace.go:171","msg":"trace[1333254838] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"962.836483ms","start":"2026-02-08T21:42:13.440083Z","end":"2026-02-08T21:42:14.40292Z","steps":["trace[1333254838] 'process raft request' (duration: 962.197429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:14.40299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:13.440077Z","time spent":"962.866374ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:14.403127Z","caller":"traceutil/trace.go:171","msg":"trace[599213604] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"954.071333ms","start":"2026-02-08T21:42:13.44905Z","end":"2026-02-08T21:42:14.403122Z","steps":["trace[599213604] 'process raft request' (duration: 953.292495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:14.403155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:13.44904Z","time spent":"954.100093ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:14.403273Z","caller":"traceutil/trace.go:171","msg":"trace[1259405981] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"954.145384ms","start":"2026-02-08T21:42:13.449122Z","end":"2026-02-08T21:42:14.403268Z","steps":["trace[1259405981] 'process raft request' (duration: 953.241833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:14.4033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:13.449114Z","time spent":"954.171955ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:14.40361Z","caller":"traceutil/trace.go:171","msg":"trace[323555086] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"951.611296ms","start":"2026-02-08T21:42:13.451993Z","end":"2026-02-08T21:42:14.403604Z","steps":["trace[323555086] 'process raft request' (duration: 950.389508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:14.403641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:13.451987Z","time spent":"951.637476ms","remote":"127.0.0.1:36538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:14.405268Z","caller":"traceutil/trace.go:171","msg":"trace[79638442] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"929.378686ms","start":"2026-02-08T21:42:13.475882Z","end":"2026-02-08T21:42:14.40526Z","steps":["trace[79638442] 'process raft request' (duration: 926.52418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:14.405306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:13.475865Z","time spent":"929.418257ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T21:42:14.405374Z","caller":"traceutil/trace.go:171","msg":"trace[1605026495] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"929.365956ms","start":"2026-02-08T21:42:13.476003Z","end":"2026-02-08T21:42:14.405369Z","steps":["trace[1605026495] 'process raft request' (duration: 926.421549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:14.405393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:13.475999Z","time spent":"929.384787ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T21:42:14.405443Z","caller":"traceutil/trace.go:171","msg":"trace[1807217380] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"915.55053ms","start":"2026-02-08T21:42:13.489889Z","end":"2026-02-08T21:42:14.40544Z","steps":["trace[1807217380] 'process raft request' (duration: 912.547651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:14.405461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:13.489881Z","time spent":"915.57127ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-08T21:42:14.405523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.578636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:14.405537Z","caller":"traceutil/trace.go:171","msg":"trace[366943206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:669; }","duration":"965.602017ms","start":"2026-02-08T21:42:13.439931Z","end":"2026-02-08T21:42:14.405533Z","steps":["trace[366943206] 'agreement among raft nodes before linearized reading' (duration: 965.576787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:14.405551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:13.439923Z","time spent":"965.624528ms","remote":"127.0.0.1:36348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T21:42:14.432517Z","caller":"traceutil/trace.go:171","msg":"trace[24811671] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"377.778497ms","start":"2026-02-08T21:42:14.054708Z","end":"2026-02-08T21:42:14.432487Z","steps":["trace[24811671] 'process raft request' (duration: 377.660884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:14.432759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:14.054692Z","time spent":"377.93831ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:15.655563Z","caller":"traceutil/trace.go:171","msg":"trace[901606491] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"211.867974ms","start":"2026-02-08T21:42:15.443646Z","end":"2026-02-08T21:42:15.655514Z","steps":["trace[901606491] 'read index received' (duration: 211.485866ms)","trace[901606491] 'applied index is now lower than readState.Index' (duration: 380.528µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:15.655692Z","caller":"traceutil/trace.go:171","msg":"trace[2048385519] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"213.727907ms","start":"2026-02-08T21:42:15.441953Z","end":"2026-02-08T21:42:15.655681Z","steps":["trace[2048385519] 'process raft request' (duration: 213.311238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:15.656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.333976ms","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-08T21:42:15.656054Z","caller":"traceutil/trace.go:171","msg":"trace[1873271448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:692; }","duration":"212.402297ms","start":"2026-02-08T21:42:15.443642Z","end":"2026-02-08T21:42:15.656044Z","steps":["trace[1873271448] 'agreement among raft nodes before linearized reading' (duration: 212.209773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:15.656155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.632501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:15.656222Z","caller":"traceutil/trace.go:171","msg":"trace[994845621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"184.722903ms","start":"2026-02-08T21:42:15.47148Z","end":"2026-02-08T21:42:15.656203Z","steps":["trace[994845621] 'agreement among raft nodes before linearized reading' (duration: 184.621991ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:17.795701Z","caller":"traceutil/trace.go:171","msg":"trace[1103736711] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"127.628464ms","start":"2026-02-08T21:42:17.668054Z","end":"2026-02-08T21:42:17.795682Z","steps":["trace[1103736711] 'read index received' (duration: 127.511481ms)","trace[1103736711] 'applied index is now lower than readState.Index' (duration: 116.253µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:17.795864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.798559ms","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-08T21:42:17.795885Z","caller":"traceutil/trace.go:171","msg":"trace[2035219079] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:725; }","duration":"127.87536ms","start":"2026-02-08T21:42:17.668002Z","end":"2026-02-08T21:42:17.795878Z","steps":["trace[2035219079] 'agreement among raft nodes before linearized reading' (duration: 127.775218ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:17.795867Z","caller":"traceutil/trace.go:171","msg":"trace[1858662224] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"128.639138ms","start":"2026-02-08T21:42:17.667206Z","end":"2026-02-08T21:42:17.795845Z","steps":["trace[1858662224] 'process raft request' (duration: 128.372822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:18.034822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.92777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:18.034883Z","caller":"traceutil/trace.go:171","msg":"trace[940837800] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"235.329733ms","start":"2026-02-08T21:42:17.799541Z","end":"2026-02-08T21:42:18.034871Z","steps":["trace[940837800] 'process raft request' (duration: 87.300481ms)","trace[940837800] 'compare' (duration: 147.830458ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:18.828828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.457656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:18.828904Z","caller":"traceutil/trace.go:171","msg":"trace[388420078] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"846.232721ms","start":"2026-02-08T21:42:17.98266Z","end":"2026-02-08T21:42:18.828892Z","steps":["trace[388420078] 'read index received' (duration: 325.612401ms)","trace[388420078] 'applied index is now lower than readState.Index' (duration: 520.6195ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:18.828931Z","caller":"traceutil/trace.go:171","msg":"trace[1223907255] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"846.503387ms","start":"2026-02-08T21:42:17.9824Z","end":"2026-02-08T21:42:18.828903Z","steps":["trace[1223907255] 'process raft request' (duration: 325.922278ms)","trace[1223907255] 'compare' (duration: 520.375375ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:18.829025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:17.982381Z","time spent":"846.59449ms","remote":"127.0.0.1:36404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-08T21:42:18.829042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.377404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-2l58z\" ","response":"range_response_count:1 size:4821"} {"level":"info","ts":"2026-02-08T21:42:18.829063Z","caller":"traceutil/trace.go:171","msg":"trace[1593720217] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-2l58z; range_end:; response_count:1; response_revision:727; }","duration":"846.416866ms","start":"2026-02-08T21:42:17.982638Z","end":"2026-02-08T21:42:18.829055Z","steps":["trace[1593720217] 'agreement among raft nodes before linearized reading' (duration: 846.308043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:18.829085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:17.982632Z","time spent":"846.447726ms","remote":"127.0.0.1:36538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4844,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-2l58z\" "} {"level":"warn","ts":"2026-02-08T21:42:18.829256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.257013ms","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-08T21:42:18.82928Z","caller":"traceutil/trace.go:171","msg":"trace[801983365] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:727; }","duration":"428.312785ms","start":"2026-02-08T21:42:18.400958Z","end":"2026-02-08T21:42:18.829271Z","steps":["trace[801983365] 'agreement among raft nodes before linearized reading' (duration: 428.259173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:18.829305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:18.400944Z","time spent":"428.351985ms","remote":"127.0.0.1:36810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:42:18.829375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.685485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:42:18.829399Z","caller":"traceutil/trace.go:171","msg":"trace[1006678748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:727; }","duration":"401.722035ms","start":"2026-02-08T21:42:18.42767Z","end":"2026-02-08T21:42:18.829392Z","steps":["trace[1006678748] 'agreement among raft nodes before linearized reading' (duration: 401.654824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:18.829422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:18.427664Z","time spent":"401.753056ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:18.829476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.877677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T21:42:18.829497Z","caller":"traceutil/trace.go:171","msg":"trace[993192798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:727; }","duration":"404.911589ms","start":"2026-02-08T21:42:18.42458Z","end":"2026-02-08T21:42:18.829491Z","steps":["trace[993192798] 'agreement among raft nodes before linearized reading' (duration: 404.818637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:18.829513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:18.424573Z","time spent":"404.936369ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:18.830719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.000357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:18.830743Z","caller":"traceutil/trace.go:171","msg":"trace[1007028862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"359.058187ms","start":"2026-02-08T21:42:18.471678Z","end":"2026-02-08T21:42:18.830737Z","steps":["trace[1007028862] 'agreement among raft nodes before linearized reading' (duration: 359.016086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:18.830741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.097936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:42:18.830785Z","caller":"traceutil/trace.go:171","msg":"trace[175484995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:727; }","duration":"413.168607ms","start":"2026-02-08T21:42:18.417609Z","end":"2026-02-08T21:42:18.830778Z","steps":["trace[175484995] 'agreement among raft nodes before linearized reading' (duration: 411.943009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:18.830806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:18.417598Z","time spent":"413.203179ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:42:18.830765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:18.471661Z","time spent":"359.097179ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T21:42:19.022072Z","caller":"traceutil/trace.go:171","msg":"trace[1338303109] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"184.18278ms","start":"2026-02-08T21:42:18.837873Z","end":"2026-02-08T21:42:19.022056Z","steps":["trace[1338303109] 'process raft request' (duration: 138.449853ms)","trace[1338303109] 'compare' (duration: 44.928129ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:19.022355Z","caller":"traceutil/trace.go:171","msg":"trace[964429165] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"184.16001ms","start":"2026-02-08T21:42:18.837876Z","end":"2026-02-08T21:42:19.022036Z","steps":["trace[964429165] 'process raft request' (duration: 183.496505ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:19.026155Z","caller":"traceutil/trace.go:171","msg":"trace[1646480379] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"187.031006ms","start":"2026-02-08T21:42:18.839106Z","end":"2026-02-08T21:42:19.026137Z","steps":["trace[1646480379] 'process raft request' (duration: 186.933084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:23.545438Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021238627859709,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T21:42:23.571001Z","caller":"traceutil/trace.go:171","msg":"trace[1256908142] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"527.431055ms","start":"2026-02-08T21:42:23.043553Z","end":"2026-02-08T21:42:23.570984Z","steps":["trace[1256908142] 'process raft request' (duration: 527.313932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:23.571294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:23.043406Z","time spent":"527.637101ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:24.700822Z","caller":"traceutil/trace.go:171","msg":"trace[120495570] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:762; }","duration":"1.655621776s","start":"2026-02-08T21:42:23.045185Z","end":"2026-02-08T21:42:24.700806Z","steps":["trace[120495570] 'read index received' (duration: 525.88544ms)","trace[120495570] 'applied index is now lower than readState.Index' (duration: 1.129735816s)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:24.701044Z","caller":"traceutil/trace.go:171","msg":"trace[1398979448] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"1.654626953s","start":"2026-02-08T21:42:23.04641Z","end":"2026-02-08T21:42:24.701037Z","steps":["trace[1398979448] 'process raft request' (duration: 1.654273155s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:24.701123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:23.0464Z","time spent":"1.654661174s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:24.701289Z","caller":"traceutil/trace.go:171","msg":"trace[989888340] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"1.274090464s","start":"2026-02-08T21:42:23.427193Z","end":"2026-02-08T21:42:24.701284Z","steps":["trace[989888340] 'process raft request' (duration: 1.273589693s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:24.701338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:23.427178Z","time spent":"1.274129665s","remote":"127.0.0.1:36524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4726,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:24.701463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.656277511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:42:24.701484Z","caller":"traceutil/trace.go:171","msg":"trace[929419223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:742; }","duration":"1.656311441s","start":"2026-02-08T21:42:23.045167Z","end":"2026-02-08T21:42:24.701479Z","steps":["trace[929419223] 'agreement among raft nodes before linearized reading' (duration: 1.65623955s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:24.701502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:23.045161Z","time spent":"1.656338632s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:24.701573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233692649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:24.70159Z","caller":"traceutil/trace.go:171","msg":"trace[1831314430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:742; }","duration":"1.233729929s","start":"2026-02-08T21:42:23.467856Z","end":"2026-02-08T21:42:24.701586Z","steps":["trace[1831314430] 'agreement among raft nodes before linearized reading' (duration: 1.233704658s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:24.701606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:23.46784Z","time spent":"1.23376305s","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:42:24.701741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.762614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:24.701758Z","caller":"traceutil/trace.go:171","msg":"trace[1871140672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:742; }","duration":"198.808885ms","start":"2026-02-08T21:42:24.502945Z","end":"2026-02-08T21:42:24.701754Z","steps":["trace[1871140672] 'agreement among raft nodes before linearized reading' (duration: 198.785034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:24.701838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.612376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-08T21:42:24.701854Z","caller":"traceutil/trace.go:171","msg":"trace[1797447593] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:742; }","duration":"639.630506ms","start":"2026-02-08T21:42:24.062221Z","end":"2026-02-08T21:42:24.701851Z","steps":["trace[1797447593] 'agreement among raft nodes before linearized reading' (duration: 639.588945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:24.701869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:24.062174Z","time spent":"639.692817ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:24.701956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.001819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T21:42:24.701973Z","caller":"traceutil/trace.go:171","msg":"trace[1574337612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:742; }","duration":"875.04266ms","start":"2026-02-08T21:42:23.826925Z","end":"2026-02-08T21:42:24.701968Z","steps":["trace[1574337612] 'agreement among raft nodes before linearized reading' (duration: 874.994759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:24.701988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:23.826911Z","time spent":"875.07417ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:42:24.702051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.049944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:24.702069Z","caller":"traceutil/trace.go:171","msg":"trace[1511581113] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:742; }","duration":"966.102906ms","start":"2026-02-08T21:42:23.735961Z","end":"2026-02-08T21:42:24.702064Z","steps":["trace[1511581113] 'agreement among raft nodes before linearized reading' (duration: 966.079145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:24.702083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:23.735943Z","time spent":"966.135376ms","remote":"127.0.0.1:36570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:42:25.089126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.901299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:25.089375Z","caller":"traceutil/trace.go:171","msg":"trace[1212347080] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"379.607598ms","start":"2026-02-08T21:42:24.70975Z","end":"2026-02-08T21:42:25.089358Z","steps":["trace[1212347080] 'process raft request' (duration: 379.572167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:25.089443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:24.709747Z","time spent":"379.665189ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:25.089631Z","caller":"traceutil/trace.go:171","msg":"trace[489285466] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"380.14616ms","start":"2026-02-08T21:42:24.709434Z","end":"2026-02-08T21:42:25.089581Z","steps":["trace[489285466] 'process raft request' (duration: 159.75466ms)","trace[489285466] 'compare' (duration: 219.695684ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:25.089728Z","caller":"traceutil/trace.go:171","msg":"trace[13117486] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"380.028887ms","start":"2026-02-08T21:42:24.709689Z","end":"2026-02-08T21:42:25.089718Z","steps":["trace[13117486] 'process raft request' (duration: 379.551496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:25.089772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:24.70942Z","time spent":"380.273332ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:25.08984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:24.709685Z","time spent":"380.069398ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:25.089915Z","caller":"traceutil/trace.go:171","msg":"trace[566047560] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"380.18923ms","start":"2026-02-08T21:42:24.709719Z","end":"2026-02-08T21:42:25.089908Z","steps":["trace[566047560] 'process raft request' (duration: 379.571076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:25.089958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:24.709714Z","time spent":"380.219332ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:25.089986Z","caller":"traceutil/trace.go:171","msg":"trace[421238447] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"380.354325ms","start":"2026-02-08T21:42:24.70962Z","end":"2026-02-08T21:42:25.089974Z","steps":["trace[421238447] 'read index received' (duration: 159.579836ms)","trace[421238447] 'applied index is now lower than readState.Index' (duration: 220.773579ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:25.090049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.420916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:25.090078Z","caller":"traceutil/trace.go:171","msg":"trace[687802324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"380.466027ms","start":"2026-02-08T21:42:24.709603Z","end":"2026-02-08T21:42:25.090069Z","steps":["trace[687802324] 'agreement among raft nodes before linearized reading' (duration: 380.412116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:25.090105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:24.709595Z","time spent":"380.504988ms","remote":"127.0.0.1:36348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T21:42:25.704895Z","caller":"traceutil/trace.go:171","msg":"trace[1512932514] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"123.557962ms","start":"2026-02-08T21:42:25.581321Z","end":"2026-02-08T21:42:25.704879Z","steps":["trace[1512932514] 'process raft request' (duration: 123.422479ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:27.190932Z","caller":"traceutil/trace.go:171","msg":"trace[1376698198] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"177.62247ms","start":"2026-02-08T21:42:27.013293Z","end":"2026-02-08T21:42:27.190916Z","steps":["trace[1376698198] 'process raft request' (duration: 177.323553ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:27.332211Z","caller":"traceutil/trace.go:171","msg":"trace[2069115442] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"133.998791ms","start":"2026-02-08T21:42:27.198191Z","end":"2026-02-08T21:42:27.33219Z","steps":["trace[2069115442] 'process raft request' (duration: 82.326536ms)","trace[2069115442] 'compare' (duration: 51.538771ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:27.33236Z","caller":"traceutil/trace.go:171","msg":"trace[1198972378] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"132.278551ms","start":"2026-02-08T21:42:27.200074Z","end":"2026-02-08T21:42:27.332353Z","steps":["trace[1198972378] 'process raft request' (duration: 132.072497ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:27.332399Z","caller":"traceutil/trace.go:171","msg":"trace[255476265] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"130.415028ms","start":"2026-02-08T21:42:27.201981Z","end":"2026-02-08T21:42:27.332396Z","steps":["trace[255476265] 'process raft request' (duration: 130.186003ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:38.037919Z","caller":"traceutil/trace.go:171","msg":"trace[1578460893] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"282.906594ms","start":"2026-02-08T21:42:37.754993Z","end":"2026-02-08T21:42:38.037899Z","steps":["trace[1578460893] 'process raft request' (duration: 282.807001ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:41.533243Z","caller":"traceutil/trace.go:171","msg":"trace[475971562] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"647.454167ms","start":"2026-02-08T21:42:40.885769Z","end":"2026-02-08T21:42:41.533223Z","steps":["trace[475971562] 'process raft request' (duration: 647.111519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:41.533564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:40.885733Z","time spent":"647.752264ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:42.413921Z","caller":"traceutil/trace.go:171","msg":"trace[2108025651] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"875.042842ms","start":"2026-02-08T21:42:41.538857Z","end":"2026-02-08T21:42:42.413899Z","steps":["trace[2108025651] 'process raft request' (duration: 874.894329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:42.41406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:41.538836Z","time spent":"875.147024ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:42.437042Z","caller":"traceutil/trace.go:171","msg":"trace[1908639691] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:820; }","duration":"389.987817ms","start":"2026-02-08T21:42:42.047033Z","end":"2026-02-08T21:42:42.437021Z","steps":["trace[1908639691] 'read index received' (duration: 367.191635ms)","trace[1908639691] 'applied index is now lower than readState.Index' (duration: 22.795382ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:42.437223Z","caller":"traceutil/trace.go:171","msg":"trace[4020097] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"897.64067ms","start":"2026-02-08T21:42:41.539573Z","end":"2026-02-08T21:42:42.437214Z","steps":["trace[4020097] 'process raft request' (duration: 897.306922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:42.437248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.200142ms","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-08T21:42:42.437281Z","caller":"traceutil/trace.go:171","msg":"trace[1436570054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:795; }","duration":"390.287794ms","start":"2026-02-08T21:42:42.046983Z","end":"2026-02-08T21:42:42.437271Z","steps":["trace[1436570054] 'agreement among raft nodes before linearized reading' (duration: 390.11752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:42.437289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:41.539567Z","time spent":"897.681702ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:42.437309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:42.046961Z","time spent":"390.341826ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-08T21:42:42.437385Z","caller":"traceutil/trace.go:171","msg":"trace[1756277538] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"896.588786ms","start":"2026-02-08T21:42:41.540788Z","end":"2026-02-08T21:42:42.437377Z","steps":["trace[1756277538] 'process raft request' (duration: 896.193836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:42.437433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:41.540774Z","time spent":"896.629027ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:42.614014Z","caller":"traceutil/trace.go:171","msg":"trace[1146609654] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"173.491865ms","start":"2026-02-08T21:42:42.440499Z","end":"2026-02-08T21:42:42.613991Z","steps":["trace[1146609654] 'process raft request' (duration: 172.046732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:43.980156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.823854ms","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-08T21:42:43.980277Z","caller":"traceutil/trace.go:171","msg":"trace[841130043] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:796; }","duration":"996.954947ms","start":"2026-02-08T21:42:42.983305Z","end":"2026-02-08T21:42:43.98026Z","steps":["trace[841130043] 'range keys from in-memory index tree' (duration: 996.808132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:44.001551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.439519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:44.001749Z","caller":"traceutil/trace.go:171","msg":"trace[46344889] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"532.721148ms","start":"2026-02-08T21:42:43.468995Z","end":"2026-02-08T21:42:44.001716Z","steps":["trace[46344889] 'read index received' (duration: 96.987542ms)","trace[46344889] 'applied index is now lower than readState.Index' (duration: 435.732266ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:44.001988Z","caller":"traceutil/trace.go:171","msg":"trace[1772781048] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"993.537958ms","start":"2026-02-08T21:42:43.008435Z","end":"2026-02-08T21:42:44.001973Z","steps":["trace[1772781048] 'process raft request' (duration: 557.626438ms)","trace[1772781048] 'compare' (duration: 414.705204ms)","trace[1772781048] 'store kv pair into bolt db' {req_type:put; key:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; req_size:669; } (duration: 15.666489ms)"],"step_count":3} {"level":"warn","ts":"2026-02-08T21:42:44.002575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:43.00841Z","time spent":"994.136482ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:44.002743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.744362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:44.002793Z","caller":"traceutil/trace.go:171","msg":"trace[1496245085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"533.823074ms","start":"2026-02-08T21:42:43.468962Z","end":"2026-02-08T21:42:44.002785Z","steps":["trace[1496245085] 'agreement among raft nodes before linearized reading' (duration: 533.756212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:44.002834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:43.468948Z","time spent":"533.879105ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:42:44.003015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.529039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:42:44.003066Z","caller":"traceutil/trace.go:171","msg":"trace[1786826700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:797; }","duration":"465.613241ms","start":"2026-02-08T21:42:43.537445Z","end":"2026-02-08T21:42:44.003059Z","steps":["trace[1786826700] 'agreement among raft nodes before linearized reading' (duration: 465.506519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:44.003104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:43.537429Z","time spent":"465.667282ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:45.042442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.383661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:45.042735Z","caller":"traceutil/trace.go:171","msg":"trace[1245497616] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"624.017821ms","start":"2026-02-08T21:42:44.418512Z","end":"2026-02-08T21:42:45.04253Z","steps":["trace[1245497616] 'read index received' (duration: 286.409295ms)","trace[1245497616] 'applied index is now lower than readState.Index' (duration: 337.607316ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:45.043212Z","caller":"traceutil/trace.go:171","msg":"trace[176230884] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"1.03553433s","start":"2026-02-08T21:42:44.007657Z","end":"2026-02-08T21:42:45.043191Z","steps":["trace[176230884] 'process raft request' (duration: 697.32018ms)","trace[176230884] 'compare' (duration: 336.393719ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:45.043412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:44.007643Z","time spent":"1.035611642s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:45.043869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.377492ms","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-08T21:42:45.043912Z","caller":"traceutil/trace.go:171","msg":"trace[1532937002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"625.461194ms","start":"2026-02-08T21:42:44.418439Z","end":"2026-02-08T21:42:45.0439Z","steps":["trace[1532937002] 'agreement among raft nodes before linearized reading' (duration: 625.31748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.044177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:44.418417Z","time spent":"625.684059ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:45.044596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.066398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T21:42:45.044741Z","caller":"traceutil/trace.go:171","msg":"trace[1451095491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"602.171231ms","start":"2026-02-08T21:42:44.442465Z","end":"2026-02-08T21:42:45.044636Z","steps":["trace[1451095491] 'agreement among raft nodes before linearized reading' (duration: 602.048208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.044956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:44.44245Z","time spent":"602.488217ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:42:45.045567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.914497ms","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-08T21:42:45.045684Z","caller":"traceutil/trace.go:171","msg":"trace[1679734706] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"421.08351ms","start":"2026-02-08T21:42:44.624524Z","end":"2026-02-08T21:42:45.045607Z","steps":["trace[1679734706] 'agreement among raft nodes before linearized reading' (duration: 420.928216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.045784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:44.624503Z","time spent":"421.266185ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:42:45.047099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.401651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:45.047129Z","caller":"traceutil/trace.go:171","msg":"trace[1673353103] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:798; }","duration":"460.469513ms","start":"2026-02-08T21:42:44.586653Z","end":"2026-02-08T21:42:45.047122Z","steps":["trace[1673353103] 'agreement among raft nodes before linearized reading' (duration: 460.411341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.047211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:44.58661Z","time spent":"460.592426ms","remote":"127.0.0.1:36476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:42:45.04764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.490852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:45.047802Z","caller":"traceutil/trace.go:171","msg":"trace[1327714931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"576.689647ms","start":"2026-02-08T21:42:44.471096Z","end":"2026-02-08T21:42:45.047786Z","steps":["trace[1327714931] 'agreement among raft nodes before linearized reading' (duration: 576.464782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.047896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:44.471086Z","time spent":"576.799599ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:42:45.04807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.380471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T21:42:45.04812Z","caller":"traceutil/trace.go:171","msg":"trace[807738772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"604.455393ms","start":"2026-02-08T21:42:44.443645Z","end":"2026-02-08T21:42:45.0481Z","steps":["trace[807738772] 'agreement among raft nodes before linearized reading' (duration: 604.182717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.048198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:44.443626Z","time spent":"604.534915ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:45.400988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.675018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:45.401085Z","caller":"traceutil/trace.go:171","msg":"trace[626590943] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"345.565297ms","start":"2026-02-08T21:42:45.055502Z","end":"2026-02-08T21:42:45.401067Z","steps":["trace[626590943] 'process raft request' (duration: 243.718155ms)","trace[626590943] 'compare' (duration: 101.533775ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:45.401146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:45.055486Z","time spent":"345.630769ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:45.402723Z","caller":"traceutil/trace.go:171","msg":"trace[1615045742] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"345.292862ms","start":"2026-02-08T21:42:45.057409Z","end":"2026-02-08T21:42:45.402702Z","steps":["trace[1615045742] 'process raft request' (duration: 345.258581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.402896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:45.057402Z","time spent":"345.391424ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:45.403016Z","caller":"traceutil/trace.go:171","msg":"trace[1239818846] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"346.853688ms","start":"2026-02-08T21:42:45.056152Z","end":"2026-02-08T21:42:45.403006Z","steps":["trace[1239818846] 'process raft request' (duration: 346.482679ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:45.402748Z","caller":"traceutil/trace.go:171","msg":"trace[2126131888] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"346.628662ms","start":"2026-02-08T21:42:45.0561Z","end":"2026-02-08T21:42:45.402729Z","steps":["trace[2126131888] 'process raft request' (duration: 346.438398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:45.403162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:45.056088Z","time spent":"347.046752ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:45.407666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:45.056148Z","time spent":"346.887498ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:55.64488Z","caller":"traceutil/trace.go:171","msg":"trace[868114930] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:908; }","duration":"167.97238ms","start":"2026-02-08T21:42:55.476891Z","end":"2026-02-08T21:42:55.644864Z","steps":["trace[868114930] 'read index received' (duration: 167.836227ms)","trace[868114930] 'applied index is now lower than readState.Index' (duration: 135.663µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:55.645102Z","caller":"traceutil/trace.go:171","msg":"trace[1090095916] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"181.586281ms","start":"2026-02-08T21:42:55.463509Z","end":"2026-02-08T21:42:55.645095Z","steps":["trace[1090095916] 'process raft request' (duration: 181.263264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:55.645271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.366799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:42:55.645295Z","caller":"traceutil/trace.go:171","msg":"trace[771016904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:878; }","duration":"168.436689ms","start":"2026-02-08T21:42:55.476852Z","end":"2026-02-08T21:42:55.645289Z","steps":["trace[771016904] 'agreement among raft nodes before linearized reading' (duration: 168.349357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:55.645395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.536291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T21:42:55.645413Z","caller":"traceutil/trace.go:171","msg":"trace[523127487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:878; }","duration":"164.563221ms","start":"2026-02-08T21:42:55.480845Z","end":"2026-02-08T21:42:55.645408Z","steps":["trace[523127487] 'agreement among raft nodes before linearized reading' (duration: 164.52254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:55.64564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.378029ms","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-08T21:42:55.645659Z","caller":"traceutil/trace.go:171","msg":"trace[1165120614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:878; }","duration":"110.435651ms","start":"2026-02-08T21:42:55.535219Z","end":"2026-02-08T21:42:55.645655Z","steps":["trace[1165120614] 'agreement among raft nodes before linearized reading' (duration: 110.38642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:56.465377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.090241ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-08T21:42:57.676753Z","caller":"traceutil/trace.go:171","msg":"trace[1084130627] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"571.194669ms","start":"2026-02-08T21:42:57.105542Z","end":"2026-02-08T21:42:57.676736Z","steps":["trace[1084130627] 'process raft request' (duration: 571.028735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:57.676855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:57.10553Z","time spent":"571.278611ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:58.308272Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021238627860069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:42:58.942524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.68289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:58.942632Z","caller":"traceutil/trace.go:171","msg":"trace[1073040509] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"1.262383236s","start":"2026-02-08T21:42:57.680227Z","end":"2026-02-08T21:42:58.94261Z","steps":["trace[1073040509] 'process raft request' (duration: 961.680366ms)","trace[1073040509] 'compare' (duration: 300.282301ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:58.942693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:57.680214Z","time spent":"1.262451479s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:59.910847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.10941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:59.911023Z","caller":"traceutil/trace.go:171","msg":"trace[1312328061] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:915; }","duration":"2.102994678s","start":"2026-02-08T21:42:57.808014Z","end":"2026-02-08T21:42:59.911008Z","steps":["trace[1312328061] 'read index received' (duration: 833.905388ms)","trace[1312328061] 'applied index is now lower than readState.Index' (duration: 1.26908846s)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:59.911106Z","caller":"traceutil/trace.go:171","msg":"trace[806307857] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"2.220799818s","start":"2026-02-08T21:42:57.690291Z","end":"2026-02-08T21:42:59.911091Z","steps":["trace[806307857] 'process raft request' (duration: 1.596377171s)","trace[806307857] 'compare' (duration: 623.361873ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:59.911159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:57.690274Z","time spent":"2.220856259s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:59.911211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.103180512s","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-08T21:42:59.911262Z","caller":"traceutil/trace.go:171","msg":"trace[1776714196] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:887; }","duration":"2.103266115s","start":"2026-02-08T21:42:57.807982Z","end":"2026-02-08T21:42:59.911248Z","steps":["trace[1776714196] 'agreement among raft nodes before linearized reading' (duration: 2.103146762s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:59.911289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:57.807968Z","time spent":"2.103313855s","remote":"127.0.0.1:36404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":109,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:42:59.91135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441320166s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:59.911379Z","caller":"traceutil/trace.go:171","msg":"trace[983851988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:887; }","duration":"1.441373428s","start":"2026-02-08T21:42:58.469997Z","end":"2026-02-08T21:42:59.91137Z","steps":["trace[983851988] 'agreement among raft nodes before linearized reading' (duration: 1.441327796s)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:59.911416Z","caller":"traceutil/trace.go:171","msg":"trace[669455842] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"2.220343177s","start":"2026-02-08T21:42:57.691066Z","end":"2026-02-08T21:42:59.911409Z","steps":["trace[669455842] 'process raft request' (duration: 2.219859376s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:59.91141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:58.469982Z","time spent":"1.441418728s","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:42:59.911492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:57.691059Z","time spent":"2.220395098s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:59.911567Z","caller":"traceutil/trace.go:171","msg":"trace[1288673086] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"2.218887404s","start":"2026-02-08T21:42:57.692675Z","end":"2026-02-08T21:42:59.911562Z","steps":["trace[1288673086] 'process raft request' (duration: 2.21830047s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:59.911591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:57.692669Z","time spent":"2.218908054s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:59.911798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.376563ms","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-08T21:42:59.911813Z","caller":"traceutil/trace.go:171","msg":"trace[1468428318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:887; }","duration":"218.413924ms","start":"2026-02-08T21:42:59.693395Z","end":"2026-02-08T21:42:59.911808Z","steps":["trace[1468428318] 'agreement among raft nodes before linearized reading' (duration: 218.346792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:59.911858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.531595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:42:59.91187Z","caller":"traceutil/trace.go:171","msg":"trace[570508530] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:887; }","duration":"406.572976ms","start":"2026-02-08T21:42:59.505293Z","end":"2026-02-08T21:42:59.911866Z","steps":["trace[570508530] 'agreement among raft nodes before linearized reading' (duration: 406.551525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:59.91189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:59.505275Z","time spent":"406.602666ms","remote":"127.0.0.1:36612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:42:59.911964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.89809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:59.911979Z","caller":"traceutil/trace.go:171","msg":"trace[380880172] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:887; }","duration":"573.93809ms","start":"2026-02-08T21:42:59.338035Z","end":"2026-02-08T21:42:59.911973Z","steps":["trace[380880172] 'agreement among raft nodes before linearized reading' (duration: 573.91789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:59.911993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:59.338019Z","time spent":"573.970242ms","remote":"127.0.0.1:36732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:00.560913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.031361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:00.561029Z","caller":"traceutil/trace.go:171","msg":"trace[1159531081] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:919; }","duration":"426.542684ms","start":"2026-02-08T21:43:00.134467Z","end":"2026-02-08T21:43:00.561009Z","steps":["trace[1159531081] 'read index received' (duration: 258.249958ms)","trace[1159531081] 'applied index is now lower than readState.Index' (duration: 168.289056ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:00.561275Z","caller":"traceutil/trace.go:171","msg":"trace[897310615] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"636.487314ms","start":"2026-02-08T21:42:59.924768Z","end":"2026-02-08T21:43:00.561255Z","steps":["trace[897310615] 'process raft request' (duration: 468.025795ms)","trace[897310615] 'compare' (duration: 167.927167ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:00.56135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:59.924758Z","time spent":"636.551107ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:00.56157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.099366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:43:00.561608Z","caller":"traceutil/trace.go:171","msg":"trace[922151701] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:888; }","duration":"427.173668ms","start":"2026-02-08T21:43:00.134421Z","end":"2026-02-08T21:43:00.561595Z","steps":["trace[922151701] 'agreement among raft nodes before linearized reading' (duration: 427.109017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:00.561645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:00.134408Z","time spent":"427.227519ms","remote":"127.0.0.1:36724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:00.561841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.491257ms","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-08T21:43:00.561881Z","caller":"traceutil/trace.go:171","msg":"trace[299813510] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:888; }","duration":"297.559299ms","start":"2026-02-08T21:43:00.26431Z","end":"2026-02-08T21:43:00.561869Z","steps":["trace[299813510] 'agreement among raft nodes before linearized reading' (duration: 297.498427ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:00.745423Z","caller":"traceutil/trace.go:171","msg":"trace[967746057] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"115.712291ms","start":"2026-02-08T21:43:00.629689Z","end":"2026-02-08T21:43:00.745401Z","steps":["trace[967746057] 'process raft request' (duration: 89.357697ms)","trace[967746057] 'compare' (duration: 26.248172ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:01.29917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.496538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:43:01.299222Z","caller":"traceutil/trace.go:171","msg":"trace[2065170401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:889; }","duration":"349.584911ms","start":"2026-02-08T21:43:00.949625Z","end":"2026-02-08T21:43:01.29921Z","steps":["trace[2065170401] 'range keys from in-memory index tree' (duration: 349.367585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:01.299245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:00.949611Z","time spent":"349.630321ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-08T21:43:01.421543Z","caller":"traceutil/trace.go:171","msg":"trace[1835656846] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"119.853697ms","start":"2026-02-08T21:43:01.301669Z","end":"2026-02-08T21:43:01.421523Z","steps":["trace[1835656846] 'process raft request' (duration: 119.745374ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:02.058445Z","caller":"traceutil/trace.go:171","msg":"trace[2124450392] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"137.355357ms","start":"2026-02-08T21:43:01.921072Z","end":"2026-02-08T21:43:02.058427Z","steps":["trace[2124450392] 'read index received' (duration: 137.219674ms)","trace[2124450392] 'applied index is now lower than readState.Index' (duration: 135.083µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:02.058623Z","caller":"traceutil/trace.go:171","msg":"trace[597411706] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"138.660728ms","start":"2026-02-08T21:43:01.919955Z","end":"2026-02-08T21:43:02.058616Z","steps":["trace[597411706] 'process raft request' (duration: 138.383841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:02.058789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.705305ms","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-08T21:43:02.058808Z","caller":"traceutil/trace.go:171","msg":"trace[811032673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:891; }","duration":"137.733146ms","start":"2026-02-08T21:43:01.921068Z","end":"2026-02-08T21:43:02.058801Z","steps":["trace[811032673] 'agreement among raft nodes before linearized reading' (duration: 137.649744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:02.058893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.758921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T21:43:02.058905Z","caller":"traceutil/trace.go:171","msg":"trace[1996021092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:891; }","duration":"135.785711ms","start":"2026-02-08T21:43:01.923116Z","end":"2026-02-08T21:43:02.058901Z","steps":["trace[1996021092] 'agreement among raft nodes before linearized reading' (duration: 135.74997ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:02.321521Z","caller":"traceutil/trace.go:171","msg":"trace[648461508] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"257.723556ms","start":"2026-02-08T21:43:02.063782Z","end":"2026-02-08T21:43:02.321506Z","steps":["trace[648461508] 'process raft request' (duration: 257.629414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:08.968906Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021238627860141,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:09.469936Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021238627860141,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:09.697914Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.29696276s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T21:43:09.698293Z","caller":"traceutil/trace.go:171","msg":"trace[1515170905] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"1.229853102s","start":"2026-02-08T21:43:08.468425Z","end":"2026-02-08T21:43:09.698278Z","steps":["trace[1515170905] 'read index received' (duration: 1.229567995s)","trace[1515170905] 'applied index is now lower than readState.Index' (duration: 284.617µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:09.698343Z","caller":"traceutil/trace.go:171","msg":"trace[1722284465] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"1.297442001s","start":"2026-02-08T21:43:08.400894Z","end":"2026-02-08T21:43:09.698336Z","steps":["trace[1722284465] 'process raft request' (duration: 1.297146664s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:09.698406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:08.400884Z","time spent":"1.297474281s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:09.698567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230151139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:09.698586Z","caller":"traceutil/trace.go:171","msg":"trace[172418319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:910; }","duration":"1.230197s","start":"2026-02-08T21:43:08.468384Z","end":"2026-02-08T21:43:09.698581Z","steps":["trace[172418319] 'agreement among raft nodes before linearized reading' (duration: 1.230165669s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:09.6986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:08.468368Z","time spent":"1.230229331s","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:09.698785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086443106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:43:09.698799Z","caller":"traceutil/trace.go:171","msg":"trace[212500704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:910; }","duration":"1.086479457s","start":"2026-02-08T21:43:08.612316Z","end":"2026-02-08T21:43:09.698795Z","steps":["trace[212500704] 'agreement among raft nodes before linearized reading' (duration: 1.086407125s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:09.698811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:08.612302Z","time spent":"1.086506847s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:09.698888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.931104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:43:09.698902Z","caller":"traceutil/trace.go:171","msg":"trace[1682149790] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:910; }","duration":"258.964815ms","start":"2026-02-08T21:43:09.439933Z","end":"2026-02-08T21:43:09.698898Z","steps":["trace[1682149790] 'agreement among raft nodes before linearized reading' (duration: 258.929874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:09.698964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.652769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:43:09.698975Z","caller":"traceutil/trace.go:171","msg":"trace[2106842925] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:910; }","duration":"818.686211ms","start":"2026-02-08T21:43:08.880286Z","end":"2026-02-08T21:43:09.698972Z","steps":["trace[2106842925] 'agreement among raft nodes before linearized reading' (duration: 818.66045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:09.698987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:08.880272Z","time spent":"818.711721ms","remote":"127.0.0.1:36652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:10.630488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021238627860149,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T21:43:10.683469Z","caller":"traceutil/trace.go:171","msg":"trace[1360522866] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"553.344129ms","start":"2026-02-08T21:43:10.130109Z","end":"2026-02-08T21:43:10.683454Z","steps":["trace[1360522866] 'read index received' (duration: 553.202715ms)","trace[1360522866] 'applied index is now lower than readState.Index' (duration: 140.904µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:10.683683Z","caller":"traceutil/trace.go:171","msg":"trace[7778754] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"600.97699ms","start":"2026-02-08T21:43:10.082697Z","end":"2026-02-08T21:43:10.683674Z","steps":["trace[7778754] 'process raft request' (duration: 600.655772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:10.683748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:10.082687Z","time spent":"601.012741ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:10.68392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.813259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:43:10.683948Z","caller":"traceutil/trace.go:171","msg":"trace[55437023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:913; }","duration":"553.870501ms","start":"2026-02-08T21:43:10.130072Z","end":"2026-02-08T21:43:10.683943Z","steps":["trace[55437023] 'agreement among raft nodes before linearized reading' (duration: 553.788699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:10.683963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:10.130057Z","time spent":"553.902671ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:10.684037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.393334ms","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-08T21:43:10.684049Z","caller":"traceutil/trace.go:171","msg":"trace[1154719227] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:913; }","duration":"551.424854ms","start":"2026-02-08T21:43:10.132621Z","end":"2026-02-08T21:43:10.684045Z","steps":["trace[1154719227] 'agreement among raft nodes before linearized reading' (duration: 551.401694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:10.68406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:10.132615Z","time spent":"551.442495ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-02-08T21:43:11.973628Z","caller":"traceutil/trace.go:171","msg":"trace[509184763] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"206.790169ms","start":"2026-02-08T21:43:11.766813Z","end":"2026-02-08T21:43:11.973603Z","steps":["trace[509184763] 'process raft request' (duration: 206.623605ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:11.974408Z","caller":"traceutil/trace.go:171","msg":"trace[1686896310] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"207.045435ms","start":"2026-02-08T21:43:11.767345Z","end":"2026-02-08T21:43:11.974391Z","steps":["trace[1686896310] 'process raft request' (duration: 206.974423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:13.233922Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021238627860172,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T21:43:13.32539Z","caller":"traceutil/trace.go:171","msg":"trace[907785245] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"592.373174ms","start":"2026-02-08T21:43:12.733002Z","end":"2026-02-08T21:43:13.325375Z","steps":["trace[907785245] 'read index received' (duration: 592.221801ms)","trace[907785245] 'applied index is now lower than readState.Index' (duration: 150.883µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:13.325566Z","caller":"traceutil/trace.go:171","msg":"trace[172248132] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"635.704698ms","start":"2026-02-08T21:43:12.689855Z","end":"2026-02-08T21:43:13.325559Z","steps":["trace[172248132] 'process raft request' (duration: 635.411151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:13.325623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:12.689843Z","time spent":"635.737018ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:13.325772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.768733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T21:43:13.325787Z","caller":"traceutil/trace.go:171","msg":"trace[21232463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:919; }","duration":"592.807565ms","start":"2026-02-08T21:43:12.732975Z","end":"2026-02-08T21:43:13.325783Z","steps":["trace[21232463] 'agreement among raft nodes before linearized reading' (duration: 592.724093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:13.325801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:12.73296Z","time spent":"592.837665ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:13.325868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.686105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:13.325882Z","caller":"traceutil/trace.go:171","msg":"trace[889152515] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:919; }","duration":"418.727026ms","start":"2026-02-08T21:43:12.90715Z","end":"2026-02-08T21:43:13.325877Z","steps":["trace[889152515] 'agreement among raft nodes before linearized reading' (duration: 418.702895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:13.325894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:12.907138Z","time spent":"418.753296ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:13.969003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021238627860175,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:14.182258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.507142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:14.182356Z","caller":"traceutil/trace.go:171","msg":"trace[2038808038] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"713.98473ms","start":"2026-02-08T21:43:13.468355Z","end":"2026-02-08T21:43:14.182339Z","steps":["trace[2038808038] 'read index received' (duration: 534.273163ms)","trace[2038808038] 'applied index is now lower than readState.Index' (duration: 179.709887ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:14.182431Z","caller":"traceutil/trace.go:171","msg":"trace[573353760] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"853.142529ms","start":"2026-02-08T21:43:13.329262Z","end":"2026-02-08T21:43:14.182405Z","steps":["trace[573353760] 'process raft request' (duration: 673.406382ms)","trace[573353760] 'compare' (duration: 179.051472ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:14.182455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.099492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:14.182509Z","caller":"traceutil/trace.go:171","msg":"trace[1118731525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"714.185474ms","start":"2026-02-08T21:43:13.468313Z","end":"2026-02-08T21:43:14.182498Z","steps":["trace[1118731525] 'agreement among raft nodes before linearized reading' (duration: 714.106863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:14.182541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:13.4683Z","time spent":"714.234546ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:14.182616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:13.329247Z","time spent":"853.266382ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:14.182816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.698031ms","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-08T21:43:14.182845Z","caller":"traceutil/trace.go:171","msg":"trace[28666623] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:920; }","duration":"417.768992ms","start":"2026-02-08T21:43:13.765067Z","end":"2026-02-08T21:43:14.182836Z","steps":["trace[28666623] 'agreement among raft nodes before linearized reading' (duration: 417.642549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:14.182871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:13.765051Z","time spent":"417.814103ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:14.18307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.473415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:43:14.183096Z","caller":"traceutil/trace.go:171","msg":"trace[1677373127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:920; }","duration":"187.518596ms","start":"2026-02-08T21:43:13.995569Z","end":"2026-02-08T21:43:14.183088Z","steps":["trace[1677373127] 'agreement among raft nodes before linearized reading' (duration: 187.419884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:14.183106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.700283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:43:14.183145Z","caller":"traceutil/trace.go:171","msg":"trace[1299712333] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:920; }","duration":"205.770364ms","start":"2026-02-08T21:43:13.977362Z","end":"2026-02-08T21:43:14.183132Z","steps":["trace[1299712333] 'agreement among raft nodes before linearized reading' (duration: 205.638541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:14.764332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.79813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:14.764441Z","caller":"traceutil/trace.go:171","msg":"trace[1978044179] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"575.011006ms","start":"2026-02-08T21:43:14.189408Z","end":"2026-02-08T21:43:14.764419Z","steps":["trace[1978044179] 'process raft request' (duration: 107.031072ms)","trace[1978044179] 'compare' (duration: 467.35797ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:14.764512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:14.189394Z","time spent":"575.083298ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:14.969602Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021238627860179,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T21:43:15.166236Z","caller":"traceutil/trace.go:171","msg":"trace[1197666547] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"976.566968ms","start":"2026-02-08T21:43:14.189647Z","end":"2026-02-08T21:43:15.166214Z","steps":["trace[1197666547] 'process raft request' (duration: 976.514857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:15.166374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:14.189641Z","time spent":"976.66299ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:15.166487Z","caller":"traceutil/trace.go:171","msg":"trace[1913813099] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"976.880765ms","start":"2026-02-08T21:43:14.189597Z","end":"2026-02-08T21:43:15.166478Z","steps":["trace[1913813099] 'process raft request' (duration: 976.191929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:15.166534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:14.189589Z","time spent":"976.916996ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:15.166678Z","caller":"traceutil/trace.go:171","msg":"trace[1259152836] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:956; }","duration":"697.435831ms","start":"2026-02-08T21:43:14.469233Z","end":"2026-02-08T21:43:15.166669Z","steps":["trace[1259152836] 'read index received' (duration: 696.50038ms)","trace[1259152836] 'applied index is now lower than readState.Index' (duration: 934.721µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:15.166831Z","caller":"traceutil/trace.go:171","msg":"trace[254656746] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"549.509252ms","start":"2026-02-08T21:43:14.617312Z","end":"2026-02-08T21:43:15.166821Z","steps":["trace[254656746] 'process raft request' (duration: 549.312447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:15.16688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:14.617286Z","time spent":"549.566453ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:15.166962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.569892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:15.167035Z","caller":"traceutil/trace.go:171","msg":"trace[744930031] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:924; }","duration":"408.666264ms","start":"2026-02-08T21:43:14.758352Z","end":"2026-02-08T21:43:15.167018Z","steps":["trace[744930031] 'agreement among raft nodes before linearized reading' (duration: 408.537481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:15.167082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:14.758297Z","time spent":"408.773237ms","remote":"127.0.0.1:36418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:15.167164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.935854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:15.167198Z","caller":"traceutil/trace.go:171","msg":"trace[1949155590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"698.011775ms","start":"2026-02-08T21:43:14.469177Z","end":"2026-02-08T21:43:15.167188Z","steps":["trace[1949155590] 'agreement among raft nodes before linearized reading' (duration: 697.959684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:15.167224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:14.469135Z","time spent":"698.082787ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:15.167285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.997432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:15.167317Z","caller":"traceutil/trace.go:171","msg":"trace[1705586453] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:924; }","duration":"107.032743ms","start":"2026-02-08T21:43:15.060274Z","end":"2026-02-08T21:43:15.167307Z","steps":["trace[1705586453] 'agreement among raft nodes before linearized reading' (duration: 106.978852ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:16.422194Z","caller":"traceutil/trace.go:171","msg":"trace[120051085] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"419.641676ms","start":"2026-02-08T21:43:16.002536Z","end":"2026-02-08T21:43:16.422177Z","steps":["trace[120051085] 'process raft request' (duration: 419.35225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:16.422288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:16.002521Z","time spent":"419.717418ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:16.422484Z","caller":"traceutil/trace.go:171","msg":"trace[1649530823] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"234.32784ms","start":"2026-02-08T21:43:16.187679Z","end":"2026-02-08T21:43:16.422007Z","steps":["trace[1649530823] 'read index received' (duration: 234.150736ms)","trace[1649530823] 'applied index is now lower than readState.Index' (duration: 176.464µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:16.422582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.908214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T21:43:16.422627Z","caller":"traceutil/trace.go:171","msg":"trace[1337804818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:926; }","duration":"234.990545ms","start":"2026-02-08T21:43:16.187631Z","end":"2026-02-08T21:43:16.422622Z","steps":["trace[1337804818] 'agreement among raft nodes before linearized reading' (duration: 234.888002ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:16.972985Z","caller":"traceutil/trace.go:171","msg":"trace[2045966060] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"503.803504ms","start":"2026-02-08T21:43:16.469159Z","end":"2026-02-08T21:43:16.972963Z","steps":["trace[2045966060] 'read index received' (duration: 457.137765ms)","trace[2045966060] 'applied index is now lower than readState.Index' (duration: 46.664839ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:16.973181Z","caller":"traceutil/trace.go:171","msg":"trace[595284034] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"544.299462ms","start":"2026-02-08T21:43:16.428869Z","end":"2026-02-08T21:43:16.973169Z","steps":["trace[595284034] 'process raft request' (duration: 497.386137ms)","trace[595284034] 'compare' (duration: 46.552247ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:16.973275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:16.428844Z","time spent":"544.363394ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:16.973439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.284965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:16.973469Z","caller":"traceutil/trace.go:171","msg":"trace[698125516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"504.314776ms","start":"2026-02-08T21:43:16.469146Z","end":"2026-02-08T21:43:16.973461Z","steps":["trace[698125516] 'agreement among raft nodes before linearized reading' (duration: 504.267595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:16.973495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:16.46907Z","time spent":"504.419598ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:16.973642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.812278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:43:16.973668Z","caller":"traceutil/trace.go:171","msg":"trace[364633428] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:927; }","duration":"415.869609ms","start":"2026-02-08T21:43:16.557791Z","end":"2026-02-08T21:43:16.97366Z","steps":["trace[364633428] 'agreement among raft nodes before linearized reading' (duration: 415.823928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:16.973694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:16.557768Z","time spent":"415.918901ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:16.974217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.044999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:43:16.974318Z","caller":"traceutil/trace.go:171","msg":"trace[1874361611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:927; }","duration":"205.12173ms","start":"2026-02-08T21:43:16.769126Z","end":"2026-02-08T21:43:16.974247Z","steps":["trace[1874361611] 'agreement among raft nodes before linearized reading' (duration: 204.740841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:17.69729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.660867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:17.697365Z","caller":"traceutil/trace.go:171","msg":"trace[935211790] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:935; }","duration":"255.81383ms","start":"2026-02-08T21:43:17.441534Z","end":"2026-02-08T21:43:17.697347Z","steps":["trace[935211790] 'range keys from in-memory index tree' (duration: 255.604746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:17.697369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.65244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:17.697426Z","caller":"traceutil/trace.go:171","msg":"trace[1946289011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"225.742272ms","start":"2026-02-08T21:43:17.471671Z","end":"2026-02-08T21:43:17.697414Z","steps":["trace[1946289011] 'range keys from in-memory index tree' (duration: 225.604838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:17.697752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.067922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T21:43:17.697768Z","caller":"traceutil/trace.go:171","msg":"trace[1688450372] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:935; }","duration":"364.108302ms","start":"2026-02-08T21:43:17.333655Z","end":"2026-02-08T21:43:17.697763Z","steps":["trace[1688450372] 'range keys from in-memory index tree' (duration: 363.967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:17.697781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:17.33364Z","time spent":"364.136783ms","remote":"127.0.0.1:36606","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-08T21:43:17.820114Z","caller":"traceutil/trace.go:171","msg":"trace[742678757] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"119.028257ms","start":"2026-02-08T21:43:17.701064Z","end":"2026-02-08T21:43:17.820093Z","steps":["trace[742678757] 'process raft request' (duration: 118.881614ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:19.211168Z","caller":"traceutil/trace.go:171","msg":"trace[1431661342] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"225.294713ms","start":"2026-02-08T21:43:18.985857Z","end":"2026-02-08T21:43:19.211151Z","steps":["trace[1431661342] 'read index received' (duration: 225.135109ms)","trace[1431661342] 'applied index is now lower than readState.Index' (duration: 158.924µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:19.211752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.872286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:43:19.21191Z","caller":"traceutil/trace.go:171","msg":"trace[901463800] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:937; }","duration":"226.022739ms","start":"2026-02-08T21:43:18.985841Z","end":"2026-02-08T21:43:19.211864Z","steps":["trace[901463800] 'agreement among raft nodes before linearized reading' (duration: 225.747743ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:19.212325Z","caller":"traceutil/trace.go:171","msg":"trace[1271301557] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"231.447213ms","start":"2026-02-08T21:43:18.980868Z","end":"2026-02-08T21:43:19.212316Z","steps":["trace[1271301557] 'process raft request' (duration: 230.175544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:19.760664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021238627860215,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:20.260889Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021238627860215,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:43:20.483577Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.267944725s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T21:43:20.483853Z","caller":"traceutil/trace.go:171","msg":"trace[1414159344] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"1.22367649s","start":"2026-02-08T21:43:19.26016Z","end":"2026-02-08T21:43:20.483836Z","steps":["trace[1414159344] 'read index received' (duration: 1.223518027s)","trace[1414159344] 'applied index is now lower than readState.Index' (duration: 157.873µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:20.48392Z","caller":"traceutil/trace.go:171","msg":"trace[1444174236] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"1.268333954s","start":"2026-02-08T21:43:19.215561Z","end":"2026-02-08T21:43:20.483895Z","steps":["trace[1444174236] 'process raft request' (duration: 1.26814987s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:20.484004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223827074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-08T21:43:20.484027Z","caller":"traceutil/trace.go:171","msg":"trace[1091110310] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:938; }","duration":"1.223866075s","start":"2026-02-08T21:43:19.260154Z","end":"2026-02-08T21:43:20.48402Z","steps":["trace[1091110310] 'agreement among raft nodes before linearized reading' (duration: 1.223744342s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:20.484052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:19.260109Z","time spent":"1.223937476s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:20.48405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:19.215546Z","time spent":"1.268425717s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:20.484204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196442057s","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-08T21:43:20.484229Z","caller":"traceutil/trace.go:171","msg":"trace[1971239993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:938; }","duration":"1.196504838s","start":"2026-02-08T21:43:19.287719Z","end":"2026-02-08T21:43:20.484224Z","steps":["trace[1971239993] 'agreement among raft nodes before linearized reading' (duration: 1.196448027s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:20.484247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:19.287699Z","time spent":"1.196544169s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:20.48431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.798789ms","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-08T21:43:20.484335Z","caller":"traceutil/trace.go:171","msg":"trace[215736935] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:938; }","duration":"628.861951ms","start":"2026-02-08T21:43:19.855465Z","end":"2026-02-08T21:43:20.484327Z","steps":["trace[215736935] 'agreement among raft nodes before linearized reading' (duration: 628.82663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:20.484355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:19.855451Z","time spent":"628.897531ms","remote":"127.0.0.1:40200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-08T21:43:20.484464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012392749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:20.484486Z","caller":"traceutil/trace.go:171","msg":"trace[1629701804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"1.01244949s","start":"2026-02-08T21:43:19.47203Z","end":"2026-02-08T21:43:20.484479Z","steps":["trace[1629701804] 'agreement among raft nodes before linearized reading' (duration: 1.012411119s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:20.484501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:19.472012Z","time spent":"1.012486442s","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:20.484645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.527996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T21:43:20.484661Z","caller":"traceutil/trace.go:171","msg":"trace[934559355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:938; }","duration":"660.566167ms","start":"2026-02-08T21:43:19.824089Z","end":"2026-02-08T21:43:20.484655Z","steps":["trace[934559355] 'agreement among raft nodes before linearized reading' (duration: 660.483635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:20.484673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:19.824076Z","time spent":"660.594228ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:43:21.152902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.296972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:21.153011Z","caller":"traceutil/trace.go:171","msg":"trace[237315696] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:974; }","duration":"655.347467ms","start":"2026-02-08T21:43:20.497655Z","end":"2026-02-08T21:43:21.153002Z","steps":["trace[237315696] 'read index received' (duration: 69.856741ms)","trace[237315696] 'applied index is now lower than readState.Index' (duration: 585.490236ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:21.153134Z","caller":"traceutil/trace.go:171","msg":"trace[591767560] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"661.030687ms","start":"2026-02-08T21:43:20.492097Z","end":"2026-02-08T21:43:21.153128Z","steps":["trace[591767560] 'process raft request' (duration: 75.462169ms)","trace[591767560] 'compare' (duration: 585.036896ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:21.153165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:20.492087Z","time spent":"661.060869ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:21.153265Z","caller":"traceutil/trace.go:171","msg":"trace[1619859318] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"656.495953ms","start":"2026-02-08T21:43:20.496765Z","end":"2026-02-08T21:43:21.153261Z","steps":["trace[1619859318] 'process raft request' (duration: 656.193436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:21.153289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:20.496746Z","time spent":"656.529995ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:21.153351Z","caller":"traceutil/trace.go:171","msg":"trace[1360943181] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"656.502054ms","start":"2026-02-08T21:43:20.496844Z","end":"2026-02-08T21:43:21.153346Z","steps":["trace[1360943181] 'process raft request' (duration: 656.141956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:21.153371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:20.49684Z","time spent":"656.519914ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:21.153445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.790167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:21.153458Z","caller":"traceutil/trace.go:171","msg":"trace[1008690090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"655.819138ms","start":"2026-02-08T21:43:20.497634Z","end":"2026-02-08T21:43:21.153454Z","steps":["trace[1008690090] 'agreement among raft nodes before linearized reading' (duration: 655.795117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:21.153471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:20.497626Z","time spent":"655.842429ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:43:21.153572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.152617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T21:43:21.153584Z","caller":"traceutil/trace.go:171","msg":"trace[1490352035] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:941; }","duration":"540.200138ms","start":"2026-02-08T21:43:20.61338Z","end":"2026-02-08T21:43:21.15358Z","steps":["trace[1490352035] 'agreement among raft nodes before linearized reading' (duration: 540.149927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:21.153596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:20.613359Z","time spent":"540.233ms","remote":"127.0.0.1:36366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.217\" "} {"level":"warn","ts":"2026-02-08T21:43:21.156595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.317966ms","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-08T21:43:21.156651Z","caller":"traceutil/trace.go:171","msg":"trace[1972606167] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:941; }","duration":"233.412868ms","start":"2026-02-08T21:43:20.923224Z","end":"2026-02-08T21:43:21.156637Z","steps":["trace[1972606167] 'agreement among raft nodes before linearized reading' (duration: 233.293636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:22.203401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.935173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:22.20352Z","caller":"traceutil/trace.go:171","msg":"trace[1022591949] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"968.723949ms","start":"2026-02-08T21:43:21.234785Z","end":"2026-02-08T21:43:22.203509Z","steps":["trace[1022591949] 'process raft request' (duration: 968.692808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:22.203552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:21.234779Z","time spent":"968.757719ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:22.203683Z","caller":"traceutil/trace.go:171","msg":"trace[1521214948] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"971.465881ms","start":"2026-02-08T21:43:21.232212Z","end":"2026-02-08T21:43:22.203678Z","steps":["trace[1521214948] 'process raft request' (duration: 671.207721ms)","trace[1521214948] 'compare' (duration: 299.769879ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:22.203709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:21.232197Z","time spent":"971.498242ms","remote":"127.0.0.1:36366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:27.393739Z","caller":"traceutil/trace.go:171","msg":"trace[1100140032] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"208.261382ms","start":"2026-02-08T21:43:27.18546Z","end":"2026-02-08T21:43:27.393722Z","steps":["trace[1100140032] 'read index received' (duration: 208.13785ms)","trace[1100140032] 'applied index is now lower than readState.Index' (duration: 122.482µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:27.393982Z","caller":"traceutil/trace.go:171","msg":"trace[714145547] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"209.324846ms","start":"2026-02-08T21:43:27.184649Z","end":"2026-02-08T21:43:27.393974Z","steps":["trace[714145547] 'process raft request' (duration: 208.951158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:27.394235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.751723ms","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-08T21:43:27.394301Z","caller":"traceutil/trace.go:171","msg":"trace[705121595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:969; }","duration":"208.857736ms","start":"2026-02-08T21:43:27.185426Z","end":"2026-02-08T21:43:27.394284Z","steps":["trace[705121595] 'agreement among raft nodes before linearized reading' (duration: 208.670042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:27.39426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.789087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-08T21:43:27.394527Z","caller":"traceutil/trace.go:171","msg":"trace[2117009628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:969; }","duration":"193.077944ms","start":"2026-02-08T21:43:27.20143Z","end":"2026-02-08T21:43:27.394508Z","steps":["trace[2117009628] 'agreement among raft nodes before linearized reading' (duration: 192.735207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:27.394312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.205482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-fx9lz\" ","response":"range_response_count:1 size:10928"} {"level":"info","ts":"2026-02-08T21:43:27.396112Z","caller":"traceutil/trace.go:171","msg":"trace[1035044675] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-fx9lz; range_end:; response_count:1; response_revision:969; }","duration":"193.012773ms","start":"2026-02-08T21:43:27.20309Z","end":"2026-02-08T21:43:27.396103Z","steps":["trace[1035044675] 'agreement among raft nodes before linearized reading' (duration: 191.186511ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:27.547583Z","caller":"traceutil/trace.go:171","msg":"trace[929254128] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"149.064996ms","start":"2026-02-08T21:43:27.398492Z","end":"2026-02-08T21:43:27.547557Z","steps":["trace[929254128] 'process raft request' (duration: 79.902591ms)","trace[929254128] 'compare' (duration: 68.833607ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:27.548055Z","caller":"traceutil/trace.go:171","msg":"trace[509972412] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"146.285792ms","start":"2026-02-08T21:43:27.401757Z","end":"2026-02-08T21:43:27.548043Z","steps":["trace[509972412] 'process raft request' (duration: 145.667218ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:27.549683Z","caller":"traceutil/trace.go:171","msg":"trace[72080489] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"142.819053ms","start":"2026-02-08T21:43:27.406851Z","end":"2026-02-08T21:43:27.54967Z","steps":["trace[72080489] 'process raft request' (duration: 141.109034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:29.626942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.925851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:43:29.627854Z","caller":"traceutil/trace.go:171","msg":"trace[1261909557] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:985; }","duration":"152.149337ms","start":"2026-02-08T21:43:29.474982Z","end":"2026-02-08T21:43:29.627131Z","steps":["trace[1261909557] 'count revisions from in-memory index tree' (duration: 151.637135ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:31.575425Z","caller":"traceutil/trace.go:171","msg":"trace[1432591462] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"116.772256ms","start":"2026-02-08T21:43:31.458613Z","end":"2026-02-08T21:43:31.575385Z","steps":["trace[1432591462] 'process raft request' (duration: 116.237764ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:32.470643Z","caller":"traceutil/trace.go:171","msg":"trace[782372535] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"210.454363ms","start":"2026-02-08T21:43:32.260165Z","end":"2026-02-08T21:43:32.470619Z","steps":["trace[782372535] 'process raft request' (duration: 210.32595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:33.759774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.992254ms","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-08T21:43:33.759889Z","caller":"traceutil/trace.go:171","msg":"trace[1042976729] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1001; }","duration":"777.117427ms","start":"2026-02-08T21:43:32.982755Z","end":"2026-02-08T21:43:33.759872Z","steps":["trace[1042976729] 'range keys from in-memory index tree' (duration: 776.977784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:33.760118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028906117s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:43:33.760168Z","caller":"traceutil/trace.go:171","msg":"trace[1378012264] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"477.83351ms","start":"2026-02-08T21:43:33.282325Z","end":"2026-02-08T21:43:33.760159Z","steps":["trace[1378012264] 'read index received' (duration: 37.401µs)","trace[1378012264] 'applied index is now lower than readState.Index' (duration: 477.795259ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:33.760342Z","caller":"traceutil/trace.go:171","msg":"trace[1041957294] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"1.223626559s","start":"2026-02-08T21:43:32.536703Z","end":"2026-02-08T21:43:33.76033Z","steps":["trace[1041957294] 'process raft request' (duration: 194.452085ms)","trace[1041957294] 'compare' (duration: 1.028189691s)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:33.7604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:32.536687Z","time spent":"1.223682591s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:33.760606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.28805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-08T21:43:33.760634Z","caller":"traceutil/trace.go:171","msg":"trace[130187851] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1002; }","duration":"478.348362ms","start":"2026-02-08T21:43:33.282277Z","end":"2026-02-08T21:43:33.760625Z","steps":["trace[130187851] 'agreement among raft nodes before linearized reading' (duration: 478.249669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:33.760662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:33.282259Z","time spent":"478.397192ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-08T21:43:33.760784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.987029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:33.760811Z","caller":"traceutil/trace.go:171","msg":"trace[1072928489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"288.049871ms","start":"2026-02-08T21:43:33.472753Z","end":"2026-02-08T21:43:33.760803Z","steps":["trace[1072928489] 'agreement among raft nodes before linearized reading' (duration: 288.00882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:33.760966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.687174ms","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-08T21:43:33.760992Z","caller":"traceutil/trace.go:171","msg":"trace[1720576336] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"116.712305ms","start":"2026-02-08T21:43:33.644272Z","end":"2026-02-08T21:43:33.760984Z","steps":["trace[1720576336] 'agreement among raft nodes before linearized reading' (duration: 116.637853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:33.761153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.894196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T21:43:33.761181Z","caller":"traceutil/trace.go:171","msg":"trace[1373721726] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1002; }","duration":"170.955877ms","start":"2026-02-08T21:43:33.590216Z","end":"2026-02-08T21:43:33.761172Z","steps":["trace[1373721726] 'agreement among raft nodes before linearized reading' (duration: 170.866655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:36.179977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.973769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:36.180069Z","caller":"traceutil/trace.go:171","msg":"trace[155659137] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1020; }","duration":"480.084921ms","start":"2026-02-08T21:43:35.699955Z","end":"2026-02-08T21:43:36.180039Z","steps":["trace[155659137] 'range keys from in-memory index tree' (duration: 479.929008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:36.180123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:35.699943Z","time spent":"480.170653ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-08T21:43:36.180507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.453858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T21:43:36.180562Z","caller":"traceutil/trace.go:171","msg":"trace[1204808214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1020; }","duration":"414.531919ms","start":"2026-02-08T21:43:35.766021Z","end":"2026-02-08T21:43:36.180552Z","steps":["trace[1204808214] 'range keys from in-memory index tree' (duration: 414.292774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:36.180596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:35.765994Z","time spent":"414.595601ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:43:36.180851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.743091ms","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-08T21:43:36.180887Z","caller":"traceutil/trace.go:171","msg":"trace[476758458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1020; }","duration":"382.806032ms","start":"2026-02-08T21:43:35.798073Z","end":"2026-02-08T21:43:36.180879Z","steps":["trace[476758458] 'range keys from in-memory index tree' (duration: 382.652249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:36.180923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:35.798057Z","time spent":"382.857453ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:36.181091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.923425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-08T21:43:36.181124Z","caller":"traceutil/trace.go:171","msg":"trace[172538437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"382.982706ms","start":"2026-02-08T21:43:35.798133Z","end":"2026-02-08T21:43:36.181116Z","steps":["trace[172538437] 'range keys from in-memory index tree' (duration: 382.825032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:36.181149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:35.798112Z","time spent":"383.031167ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:43:36.181383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.586091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:43:36.181418Z","caller":"traceutil/trace.go:171","msg":"trace[1957676278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1020; }","duration":"373.638103ms","start":"2026-02-08T21:43:35.807772Z","end":"2026-02-08T21:43:36.18141Z","steps":["trace[1957676278] 'range keys from in-memory index tree' (duration: 373.513289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:36.181449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:35.807763Z","time spent":"373.679973ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:36.181716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.483369ms","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-08T21:43:36.181752Z","caller":"traceutil/trace.go:171","msg":"trace[769983471] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1020; }","duration":"157.568431ms","start":"2026-02-08T21:43:36.024176Z","end":"2026-02-08T21:43:36.181744Z","steps":["trace[769983471] 'count revisions from in-memory index tree' (duration: 157.409727ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:45:10.692933Z","caller":"traceutil/trace.go:171","msg":"trace[841039424] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"160.347376ms","start":"2026-02-08T21:45:10.532568Z","end":"2026-02-08T21:45:10.692915Z","steps":["trace[841039424] 'process raft request' (duration: 160.206633ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:45:55.488532Z","caller":"traceutil/trace.go:171","msg":"trace[507368873] linearizableReadLoop","detail":"{readStateIndex:1671; appliedIndex:1670; }","duration":"237.591103ms","start":"2026-02-08T21:45:55.250922Z","end":"2026-02-08T21:45:55.488514Z","steps":["trace[507368873] 'read index received' (duration: 219.044649ms)","trace[507368873] 'applied index is now lower than readState.Index' (duration: 18.545444ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:45:55.490433Z","caller":"traceutil/trace.go:171","msg":"trace[1545880868] transaction","detail":"{read_only:false; response_revision:1604; number_of_response:1; }","duration":"277.594404ms","start":"2026-02-08T21:45:55.212818Z","end":"2026-02-08T21:45:55.490412Z","steps":["trace[1545880868] 'process raft request' (duration: 257.187924ms)","trace[1545880868] 'compare' (duration: 17.812546ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:45:55.492564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.630152ms","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-08T21:45:55.492667Z","caller":"traceutil/trace.go:171","msg":"trace[566949752] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1604; }","duration":"241.679544ms","start":"2026-02-08T21:45:55.250918Z","end":"2026-02-08T21:45:55.492598Z","steps":["trace[566949752] 'agreement among raft nodes before linearized reading' (duration: 240.448044ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:07.195997Z","caller":"traceutil/trace.go:171","msg":"trace[1284132866] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"111.187735ms","start":"2026-02-08T21:46:07.084786Z","end":"2026-02-08T21:46:07.195974Z","steps":["trace[1284132866] 'process raft request' (duration: 110.792905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:46.12046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.039174ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-08T21:46:46.121705Z","caller":"traceutil/trace.go:171","msg":"trace[1443655766] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1909; }","duration":"202.870446ms","start":"2026-02-08T21:46:45.918785Z","end":"2026-02-08T21:46:46.121655Z","steps":["trace[1443655766] 'read index received' (duration: 87.572092ms)","trace[1443655766] 'applied index is now lower than readState.Index' (duration: 115.295314ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:46:46.122093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.298175ms","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-08T21:46:46.122558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.397962ms","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:510"} {"level":"info","ts":"2026-02-08T21:46:46.122719Z","caller":"traceutil/trace.go:171","msg":"trace[239509145] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1831; }","duration":"148.592376ms","start":"2026-02-08T21:46:45.974111Z","end":"2026-02-08T21:46:46.122703Z","steps":["trace[239509145] 'agreement among raft nodes before linearized reading' (duration: 148.35844ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:46.124587Z","caller":"traceutil/trace.go:171","msg":"trace[332565290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1831; }","duration":"203.874679ms","start":"2026-02-08T21:46:45.918732Z","end":"2026-02-08T21:46:46.122607Z","steps":["trace[332565290] 'agreement among raft nodes before linearized reading' (duration: 203.201813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:52.184581Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021238627862535,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:46:52.621127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074673405s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-08T21:46:52.621238Z","caller":"traceutil/trace.go:171","msg":"trace[481892683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1859; }","duration":"1.074823718s","start":"2026-02-08T21:46:51.54639Z","end":"2026-02-08T21:46:52.621214Z","steps":["trace[481892683] 'range keys from in-memory index tree' (duration: 1.074485531s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:52.621294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:46:51.546382Z","time spent":"1.07490062s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T21:46:52.621518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151964847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:46:52.621594Z","caller":"traceutil/trace.go:171","msg":"trace[1019359701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1859; }","duration":"1.15209499s","start":"2026-02-08T21:46:51.469479Z","end":"2026-02-08T21:46:52.621574Z","steps":["trace[1019359701] 'range keys from in-memory index tree' (duration: 1.151888646s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:52.62164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:46:51.469454Z","time spent":"1.152174853s","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:46:52.621895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075632997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-08T21:46:52.621928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474233082s","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:510"} {"level":"info","ts":"2026-02-08T21:46:52.621966Z","caller":"traceutil/trace.go:171","msg":"trace[1670618645] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1859; }","duration":"1.474300933s","start":"2026-02-08T21:46:51.147651Z","end":"2026-02-08T21:46:52.621952Z","steps":["trace[1670618645] 'range keys from in-memory index tree' (duration: 1.474024717s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:52.621993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:46:51.147603Z","time spent":"1.474383006s","remote":"127.0.0.1:36606","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":"info","ts":"2026-02-08T21:46:52.621931Z","caller":"traceutil/trace.go:171","msg":"trace[857730135] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1859; }","duration":"1.075696648s","start":"2026-02-08T21:46:51.546223Z","end":"2026-02-08T21:46:52.62192Z","steps":["trace[857730135] 'range keys from in-memory index tree' (duration: 1.075566645s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:52.622331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:46:51.546206Z","time spent":"1.076115758s","remote":"127.0.0.1:36494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-02-08T21:46:52.622359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.534369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:46:52.622428Z","caller":"traceutil/trace.go:171","msg":"trace[1409277398] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"938.842805ms","start":"2026-02-08T21:46:51.683572Z","end":"2026-02-08T21:46:52.622415Z","steps":["trace[1409277398] 'read index received' (duration: 704.173362ms)","trace[1409277398] 'applied index is now lower than readState.Index' (duration: 234.668183ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:46:52.622643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.06992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-08T21:46:52.622684Z","caller":"traceutil/trace.go:171","msg":"trace[2096968913] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1860; }","duration":"939.167552ms","start":"2026-02-08T21:46:51.683504Z","end":"2026-02-08T21:46:52.622671Z","steps":["trace[2096968913] 'agreement among raft nodes before linearized reading' (duration: 939.038569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:52.622712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:46:51.683479Z","time spent":"939.226434ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-08T21:46:52.622768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.234439ms","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-08T21:46:52.6228Z","caller":"traceutil/trace.go:171","msg":"trace[1525904047] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1860; }","duration":"900.287761ms","start":"2026-02-08T21:46:51.722503Z","end":"2026-02-08T21:46:52.622791Z","steps":["trace[1525904047] 'agreement among raft nodes before linearized reading' (duration: 900.154828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:52.622827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:46:51.722471Z","time spent":"900.349831ms","remote":"127.0.0.1:36606","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-08T21:46:52.622864Z","caller":"traceutil/trace.go:171","msg":"trace[1955797848] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"1.026511468s","start":"2026-02-08T21:46:51.596339Z","end":"2026-02-08T21:46:52.62285Z","steps":["trace[1955797848] 'process raft request' (duration: 791.421976ms)","trace[1955797848] 'compare' (duration: 233.503085ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:46:52.622965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:46:51.596292Z","time spent":"1.02660023s","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:46:52.623024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.248057ms","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-08T21:46:52.623052Z","caller":"traceutil/trace.go:171","msg":"trace[2130890054] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1860; }","duration":"411.300808ms","start":"2026-02-08T21:46:52.211743Z","end":"2026-02-08T21:46:52.623044Z","steps":["trace[2130890054] 'agreement among raft nodes before linearized reading' (duration: 411.224117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:52.623081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:46:52.211732Z","time spent":"411.343559ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-08T21:46:52.62321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.482754ms","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-08T21:46:52.623235Z","caller":"traceutil/trace.go:171","msg":"trace[514612695] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1860; }","duration":"449.549656ms","start":"2026-02-08T21:46:52.173678Z","end":"2026-02-08T21:46:52.623228Z","steps":["trace[514612695] 'agreement among raft nodes before linearized reading' (duration: 449.478914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:52.623259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:46:52.173667Z","time spent":"449.586626ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T21:46:52.623318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.618344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:46:52.623353Z","caller":"traceutil/trace.go:171","msg":"trace[413835045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1860; }","duration":"454.709375ms","start":"2026-02-08T21:46:52.168634Z","end":"2026-02-08T21:46:52.623343Z","steps":["trace[413835045] 'agreement among raft nodes before linearized reading' (duration: 454.616963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:52.6234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:46:52.168588Z","time spent":"454.786347ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T21:46:52.62968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.555825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T21:46:52.629719Z","caller":"traceutil/trace.go:171","msg":"trace[1295466012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1860; }","duration":"458.660037ms","start":"2026-02-08T21:46:52.171045Z","end":"2026-02-08T21:46:52.629705Z","steps":["trace[1295466012] 'agreement among raft nodes before linearized reading' (duration: 458.545664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:52.62974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:46:52.170995Z","time spent":"458.740299ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-08T21:46:52.854467Z","caller":"traceutil/trace.go:171","msg":"trace[1531092827] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"209.666803ms","start":"2026-02-08T21:46:52.644764Z","end":"2026-02-08T21:46:52.854431Z","steps":["trace[1531092827] 'process raft request' (duration: 125.987022ms)","trace[1531092827] 'compare' (duration: 83.064037ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:46:52.862302Z","caller":"traceutil/trace.go:171","msg":"trace[40475505] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"217.350421ms","start":"2026-02-08T21:46:52.644934Z","end":"2026-02-08T21:46:52.862285Z","steps":["trace[40475505] 'process raft request' (duration: 216.916761ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:52.862643Z","caller":"traceutil/trace.go:171","msg":"trace[685792079] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"217.670099ms","start":"2026-02-08T21:46:52.644967Z","end":"2026-02-08T21:46:52.862637Z","steps":["trace[685792079] 'process raft request' (duration: 217.036424ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:52.863661Z","caller":"traceutil/trace.go:171","msg":"trace[1506269886] linearizableReadLoop","detail":"{readStateIndex:1947; appliedIndex:1940; }","duration":"209.915639ms","start":"2026-02-08T21:46:52.653737Z","end":"2026-02-08T21:46:52.863653Z","steps":["trace[1506269886] 'read index received' (duration: 51.246639ms)","trace[1506269886] 'applied index is now lower than readState.Index' (duration: 158.66805ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:46:52.863844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.086503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:46:52.864144Z","caller":"traceutil/trace.go:171","msg":"trace[905325655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1868; }","duration":"210.190095ms","start":"2026-02-08T21:46:52.653687Z","end":"2026-02-08T21:46:52.863877Z","steps":["trace[905325655] 'agreement among raft nodes before linearized reading' (duration: 209.996891ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:52.862567Z","caller":"traceutil/trace.go:171","msg":"trace[138140278] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"217.74405ms","start":"2026-02-08T21:46:52.644816Z","end":"2026-02-08T21:46:52.86256Z","steps":["trace[138140278] 'process raft request' (duration: 209.547309ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:52.865315Z","caller":"traceutil/trace.go:171","msg":"trace[1741429730] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"209.818756ms","start":"2026-02-08T21:46:52.655489Z","end":"2026-02-08T21:46:52.865308Z","steps":["trace[1741429730] 'process raft request' (duration: 208.120157ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:52.865482Z","caller":"traceutil/trace.go:171","msg":"trace[349087528] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"220.580956ms","start":"2026-02-08T21:46:52.644894Z","end":"2026-02-08T21:46:52.865475Z","steps":["trace[349087528] 'process raft request' (duration: 217.71404ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:52.865563Z","caller":"traceutil/trace.go:171","msg":"trace[251362063] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"214.217959ms","start":"2026-02-08T21:46:52.651067Z","end":"2026-02-08T21:46:52.865285Z","steps":["trace[251362063] 'process raft request' (duration: 212.51451ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:54.501827Z","caller":"traceutil/trace.go:171","msg":"trace[1482475480] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"172.570142ms","start":"2026-02-08T21:46:54.32924Z","end":"2026-02-08T21:46:54.50181Z","steps":["trace[1482475480] 'process raft request' (duration: 130.592019ms)","trace[1482475480] 'compare' (duration: 41.794359ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:47:13.056569Z","caller":"traceutil/trace.go:171","msg":"trace[715452505] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"141.471642ms","start":"2026-02-08T21:47:12.915086Z","end":"2026-02-08T21:47:13.056557Z","steps":["trace[715452505] 'process raft request' (duration: 141.38816ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:13.059678Z","caller":"traceutil/trace.go:171","msg":"trace[562313737] linearizableReadLoop","detail":"{readStateIndex:2036; appliedIndex:2035; }","duration":"111.209849ms","start":"2026-02-08T21:47:12.948454Z","end":"2026-02-08T21:47:13.059664Z","steps":["trace[562313737] 'read index received' (duration: 108.207779ms)","trace[562313737] 'applied index is now lower than readState.Index' (duration: 3.00156ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:47:13.059855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.370493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-node-lease/\" range_end:\"/registry/rolebindings/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:47:13.060597Z","caller":"traceutil/trace.go:171","msg":"trace[623626328] range","detail":"{range_begin:/registry/rolebindings/kube-node-lease/; range_end:/registry/rolebindings/kube-node-lease0; response_count:0; response_revision:1951; }","duration":"112.168321ms","start":"2026-02-08T21:47:12.948419Z","end":"2026-02-08T21:47:13.060588Z","steps":["trace[623626328] 'agreement among raft nodes before linearized reading' (duration: 111.374403ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:47:13.060288Z","caller":"traceutil/trace.go:171","msg":"trace[47882233] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"132.951914ms","start":"2026-02-08T21:47:12.927327Z","end":"2026-02-08T21:47:13.060279Z","steps":["trace[47882233] 'process raft request' (duration: 132.264168ms)"],"step_count":1}