{"level":"warn","ts":"2026-02-02T16:00:25.063429Z","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-02T16:00:25.06368Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.99:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.99:2380","--initial-cluster=instance=https://199.204.45.99:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.99:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.99: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-02T16:00:25.063838Z","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-02T16:00:25.063895Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-02-02T16:00:25.064Z","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-02T16:00:25.064713Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"]} {"level":"info","ts":"2026-02-02T16:00:25.064866Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.99: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-02T16:00:25.070934Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.79796ms"} {"level":"info","ts":"2026-02-02T16:00:25.086044Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"21032583b6cd9182","cluster-id":"a96cbe7391f279b7"} {"level":"info","ts":"2026-02-02T16:00:25.086237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=()"} {"level":"info","ts":"2026-02-02T16:00:25.086301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 0"} {"level":"info","ts":"2026-02-02T16:00:25.086362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 21032583b6cd9182 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-02T16:00:25.086411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 1"} {"level":"info","ts":"2026-02-02T16:00:25.086493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"warn","ts":"2026-02-02T16:00:25.097635Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-02T16:00:25.101421Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-02T16:00:25.104424Z","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-02T16:00:25.108784Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"21032583b6cd9182","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-02T16:00:25.111232Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"21032583b6cd9182","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-02T16:00:25.111323Z","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-02T16:00:25.111349Z","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-02T16:00:25.111355Z","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-02T16:00:25.111496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"info","ts":"2026-02-02T16:00:25.111544Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","added-peer-id":"21032583b6cd9182","added-peer-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-02-02T16:00:25.156691Z","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-02T16:00:25.157135Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-02-02T16:00:25.157211Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"21032583b6cd9182","initial-advertise-peer-urls":["https://199.204.45.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-02T16:00:25.157244Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-02-02T16:00:25.157875Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-02T16:00:25.287649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 is starting a new election at term 1"} {"level":"info","ts":"2026-02-02T16:00:25.287861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-02T16:00:25.287948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgPreVoteResp from 21032583b6cd9182 at term 1"} {"level":"info","ts":"2026-02-02T16:00:25.288029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became candidate at term 2"} {"level":"info","ts":"2026-02-02T16:00:25.288063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgVoteResp from 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-02-02T16:00:25.288234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became leader at term 2"} {"level":"info","ts":"2026-02-02T16:00:25.288285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 21032583b6cd9182 elected leader 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-02-02T16:00:25.292515Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"21032583b6cd9182","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.99:2379]}","request-path":"/0/members/21032583b6cd9182/attributes","cluster-id":"a96cbe7391f279b7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-02T16:00:25.296404Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T16:00:25.296485Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T16:00:25.296778Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-02T16:00:25.296794Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-02T16:00:25.297124Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T16:00:25.304893Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.99:2379"} {"level":"info","ts":"2026-02-02T16:00:25.310863Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-02T16:00:25.311009Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T16:00:25.311548Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T16:00:25.311573Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T16:00:50.054345Z","caller":"traceutil/trace.go:171","msg":"trace[1359540254] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"447.086383ms","start":"2026-02-02T16:00:49.607244Z","end":"2026-02-02T16:00:50.05433Z","steps":["trace[1359540254] 'read index received' (duration: 446.99346ms)","trace[1359540254] 'applied index is now lower than readState.Index' (duration: 91.803µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:00:50.054778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:00:49.425271Z","time spent":"629.504663ms","remote":"127.0.0.1:41684","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-02T16:00:50.054962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.711926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-02T16:00:50.05499Z","caller":"traceutil/trace.go:171","msg":"trace[897198911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:462; }","duration":"447.786898ms","start":"2026-02-02T16:00:49.607196Z","end":"2026-02-02T16:00:50.054983Z","steps":["trace[897198911] 'agreement among raft nodes before linearized reading' (duration: 447.722816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:00:50.055009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:00:49.607183Z","time spent":"447.821778ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T16:00:50.055112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.672311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T16:00:50.055135Z","caller":"traceutil/trace.go:171","msg":"trace[42583419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:462; }","duration":"358.736082ms","start":"2026-02-02T16:00:49.696391Z","end":"2026-02-02T16:00:50.055127Z","steps":["trace[42583419] 'agreement among raft nodes before linearized reading' (duration: 358.696582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:00:50.055158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:00:49.696377Z","time spent":"358.775193ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T16:00:50.487887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.643079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:00:50.487944Z","caller":"traceutil/trace.go:171","msg":"trace[1888645207] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"431.126047ms","start":"2026-02-02T16:00:50.056808Z","end":"2026-02-02T16:00:50.487934Z","steps":["trace[1888645207] 'process raft request' (duration: 283.146251ms)","trace[1888645207] 'compare' (duration: 147.580577ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:00:50.487975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:00:50.056783Z","time spent":"431.178087ms","remote":"127.0.0.1:41684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:00:50.492566Z","caller":"traceutil/trace.go:171","msg":"trace[1442422901] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"433.082598ms","start":"2026-02-02T16:00:50.05947Z","end":"2026-02-02T16:00:50.492553Z","steps":["trace[1442422901] 'process raft request' (duration: 430.926472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:00:50.492628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:00:50.059455Z","time spent":"433.153799ms","remote":"127.0.0.1:41894","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-02T16:00:50.493094Z","caller":"traceutil/trace.go:171","msg":"trace[1298861105] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"433.553917ms","start":"2026-02-02T16:00:50.059526Z","end":"2026-02-02T16:00:50.49308Z","steps":["trace[1298861105] 'process raft request' (duration: 430.925892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:00:50.493126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:00:50.059519Z","time spent":"433.587958ms","remote":"127.0.0.1:41894","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-02T16:00:50.493408Z","caller":"traceutil/trace.go:171","msg":"trace[1858656717] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"431.719148ms","start":"2026-02-02T16:00:50.061677Z","end":"2026-02-02T16:00:50.493396Z","steps":["trace[1858656717] 'process raft request' (duration: 428.792237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:00:50.493434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:00:50.061668Z","time spent":"431.751199ms","remote":"127.0.0.1:41894","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-02T16:01:00.054688Z","caller":"traceutil/trace.go:171","msg":"trace[391279544] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"629.090924ms","start":"2026-02-02T16:00:59.425582Z","end":"2026-02-02T16:01:00.054673Z","steps":["trace[391279544] 'process raft request' (duration: 629.027242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:00.054778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:00:59.425573Z","time spent":"629.168185ms","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T16:01:00.326343Z","caller":"traceutil/trace.go:171","msg":"trace[855973794] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"701.959268ms","start":"2026-02-02T16:00:59.624336Z","end":"2026-02-02T16:01:00.326295Z","steps":["trace[855973794] 'read index received' (duration: 430.657306ms)","trace[855973794] 'applied index is now lower than readState.Index' (duration: 271.301022ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:00.326444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:00:59.426001Z","time spent":"900.439906ms","remote":"127.0.0.1:41684","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-02T16:01:00.32673Z","caller":"traceutil/trace.go:171","msg":"trace[1402928354] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"299.938814ms","start":"2026-02-02T16:01:00.02678Z","end":"2026-02-02T16:01:00.326719Z","steps":["trace[1402928354] 'process raft request' (duration: 299.430934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:00.326904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.557631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:00.32694Z","caller":"traceutil/trace.go:171","msg":"trace[221083589] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:548; }","duration":"702.690784ms","start":"2026-02-02T16:00:59.624238Z","end":"2026-02-02T16:01:00.326928Z","steps":["trace[221083589] 'agreement among raft nodes before linearized reading' (duration: 702.603292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:00.326969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:00:59.624225Z","time spent":"702.737565ms","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-02T16:01:00.57637Z","caller":"traceutil/trace.go:171","msg":"trace[819366567] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"249.934332ms","start":"2026-02-02T16:01:00.326403Z","end":"2026-02-02T16:01:00.576338Z","steps":["trace[819366567] 'read index received' (duration: 157.130637ms)","trace[819366567] 'applied index is now lower than readState.Index' (duration: 92.802935ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:01:00.576463Z","caller":"traceutil/trace.go:171","msg":"trace[1207932225] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"516.826871ms","start":"2026-02-02T16:01:00.059629Z","end":"2026-02-02T16:01:00.576456Z","steps":["trace[1207932225] 'process raft request' (duration: 423.894624ms)","trace[1207932225] 'compare' (duration: 92.711913ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:00.576551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:00.059617Z","time spent":"516.875272ms","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T16:01:00.576624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.610243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-02T16:01:00.576669Z","caller":"traceutil/trace.go:171","msg":"trace[452934418] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:549; }","duration":"352.686666ms","start":"2026-02-02T16:01:00.223971Z","end":"2026-02-02T16:01:00.576657Z","steps":["trace[452934418] 'agreement among raft nodes before linearized reading' (duration: 352.569493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:00.576691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:00.223935Z","time spent":"352.751166ms","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-02T16:01:00.576753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.081453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:00.576779Z","caller":"traceutil/trace.go:171","msg":"trace[2046160769] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:549; }","duration":"752.140005ms","start":"2026-02-02T16:00:59.824632Z","end":"2026-02-02T16:01:00.576772Z","steps":["trace[2046160769] 'agreement among raft nodes before linearized reading' (duration: 752.097954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:00.576799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:00:59.824609Z","time spent":"752.185986ms","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-02T16:01:01.077224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114539789648546,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T16:01:01.577904Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114539789648546,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T16:01:01.884333Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.317076549s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-02T16:01:01.888335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:01.531894Z","time spent":"356.437684ms","remote":"127.0.0.1:49586","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-02T16:01:01.888657Z","caller":"traceutil/trace.go:171","msg":"trace[1108318229] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.463007212s","start":"2026-02-02T16:01:00.425636Z","end":"2026-02-02T16:01:01.888643Z","steps":["trace[1108318229] 'process raft request' (duration: 1.462948s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:01.888726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:00.425619Z","time spent":"1.463067543s","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T16:01:01.888904Z","caller":"traceutil/trace.go:171","msg":"trace[1351801115] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.558623334s","start":"2026-02-02T16:01:00.330274Z","end":"2026-02-02T16:01:01.888897Z","steps":["trace[1351801115] 'process raft request' (duration: 1.554385245s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:01.888955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:00.330251Z","time spent":"1.558677336s","remote":"127.0.0.1:41684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T16:01:02.413718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00017994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T16:01:02.414421Z","caller":"traceutil/trace.go:171","msg":"trace[2015553263] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000901716s","start":"2026-02-02T16:01:00.413499Z","end":"2026-02-02T16:01:02.4144Z","steps":["trace[2015553263] 'agreement among raft nodes before linearized reading' (duration: 2.00017615s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.414604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:00.413457Z","time spent":"2.001130761s","remote":"127.0.0.1:41658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T16:01:02.466091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.802392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:01:02.466882Z","caller":"traceutil/trace.go:171","msg":"trace[1056708669] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.882203136s","start":"2026-02-02T16:01:00.58466Z","end":"2026-02-02T16:01:02.466863Z","steps":["trace[1056708669] 'process raft request' (duration: 1.523573177s)","trace[1056708669] 'compare' (duration: 356.474504ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:02.467053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:00.584648Z","time spent":"1.882361619s","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:01:02.467404Z","caller":"traceutil/trace.go:171","msg":"trace[1311596136] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.137162321s","start":"2026-02-02T16:01:01.330228Z","end":"2026-02-02T16:01:02.46739Z","steps":["trace[1311596136] 'process raft request' (duration: 1.136587768s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.467609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:01.33021Z","time spent":"1.137236292s","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4407,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:01:02.467854Z","caller":"traceutil/trace.go:171","msg":"trace[237682024] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.882303209s","start":"2026-02-02T16:01:00.585129Z","end":"2026-02-02T16:01:02.467432Z","steps":["trace[237682024] 'process raft request' (duration: 1.881628044s)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:02.468595Z","caller":"traceutil/trace.go:171","msg":"trace[713387425] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:570; }","duration":"1.892147965s","start":"2026-02-02T16:01:00.576434Z","end":"2026-02-02T16:01:02.468582Z","steps":["trace[713387425] 'read index received' (duration: 1.308225213s)","trace[713387425] 'applied index is now lower than readState.Index' (duration: 583.922022ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:02.472397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:00.585123Z","time spent":"1.882941852s","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:01:02.47277Z","caller":"traceutil/trace.go:171","msg":"trace[1131694253] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"821.422103ms","start":"2026-02-02T16:01:01.651334Z","end":"2026-02-02T16:01:02.472756Z","steps":["trace[1131694253] 'process raft request' (duration: 817.173063ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:02.472135Z","caller":"traceutil/trace.go:171","msg":"trace[237999243] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.042297974s","start":"2026-02-02T16:01:01.429815Z","end":"2026-02-02T16:01:02.472113Z","steps":["trace[237999243] 'process raft request' (duration: 1.037441272s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.473306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:01.429791Z","time spent":"1.04349105s","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T16:01:02.473519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:01.651317Z","time spent":"821.493465ms","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T16:01:02.476982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.88712925s","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-02T16:01:02.477448Z","caller":"traceutil/trace.go:171","msg":"trace[1641082541] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"1.88760291s","start":"2026-02-02T16:01:00.589825Z","end":"2026-02-02T16:01:02.477428Z","steps":["trace[1641082541] 'agreement among raft nodes before linearized reading' (duration: 1.887077609s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.477535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:00.589819Z","time spent":"1.887706092s","remote":"127.0.0.1:41894","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-02T16:01:02.477717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.975734205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-02T16:01:02.477976Z","caller":"traceutil/trace.go:171","msg":"trace[883580947] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"545.152506ms","start":"2026-02-02T16:01:01.932815Z","end":"2026-02-02T16:01:02.477968Z","steps":["trace[883580947] 'process raft request' (duration: 542.470219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.478422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:01.932801Z","time spent":"545.585335ms","remote":"127.0.0.1:41894","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-02T16:01:02.478855Z","caller":"traceutil/trace.go:171","msg":"trace[1678085929] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"424.902955ms","start":"2026-02-02T16:01:02.053944Z","end":"2026-02-02T16:01:02.478847Z","steps":["trace[1678085929] 'process raft request' (duration: 422.901692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.479564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:02.053928Z","time spent":"425.60788ms","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T16:01:02.480249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.856391673s","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":"warn","ts":"2026-02-02T16:01:02.480442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.869371446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T16:01:02.480274Z","caller":"traceutil/trace.go:171","msg":"trace[1831783229] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:558; }","duration":"1.856440563s","start":"2026-02-02T16:01:00.623826Z","end":"2026-02-02T16:01:02.480267Z","steps":["trace[1831783229] 'agreement among raft nodes before linearized reading' (duration: 1.856379462s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.481549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:00.623814Z","time spent":"1.857727622s","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-02-02T16:01:02.481532Z","caller":"traceutil/trace.go:171","msg":"trace[316073597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"1.87047741s","start":"2026-02-02T16:01:00.611047Z","end":"2026-02-02T16:01:02.481525Z","steps":["trace[316073597] 'agreement among raft nodes before linearized reading' (duration: 1.869340586s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.481646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:00.611042Z","time spent":"1.870597103s","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T16:01:02.48202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.871068422s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-02T16:01:02.482152Z","caller":"traceutil/trace.go:171","msg":"trace[1564777859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"1.871239986s","start":"2026-02-02T16:01:00.610906Z","end":"2026-02-02T16:01:02.482146Z","steps":["trace[1564777859] 'agreement among raft nodes before linearized reading' (duration: 1.871080023s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.482249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:00.610885Z","time spent":"1.871357518s","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-02T16:01:02.477741Z","caller":"traceutil/trace.go:171","msg":"trace[1040162687] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:556; }","duration":"1.975759916s","start":"2026-02-02T16:01:00.501975Z","end":"2026-02-02T16:01:02.477735Z","steps":["trace[1040162687] 'agreement among raft nodes before linearized reading' (duration: 1.971577098s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.481716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.630004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5577"} {"level":"info","ts":"2026-02-02T16:01:02.483013Z","caller":"traceutil/trace.go:171","msg":"trace[1283714187] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:558; }","duration":"258.959791ms","start":"2026-02-02T16:01:02.224045Z","end":"2026-02-02T16:01:02.483005Z","steps":["trace[1283714187] 'agreement among raft nodes before linearized reading' (duration: 257.646714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.482258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.564697822s","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-02T16:01:02.483324Z","caller":"traceutil/trace.go:171","msg":"trace[808185113] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"1.565793015s","start":"2026-02-02T16:01:00.917525Z","end":"2026-02-02T16:01:02.483318Z","steps":["trace[808185113] 'agreement among raft nodes before linearized reading' (duration: 1.564346455s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.483373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:00.917509Z","time spent":"1.565857436s","remote":"127.0.0.1:41894","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-02T16:01:02.481786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.722326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-02T16:01:02.484549Z","caller":"traceutil/trace.go:171","msg":"trace[1035211856] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:558; }","duration":"659.520774ms","start":"2026-02-02T16:01:01.825022Z","end":"2026-02-02T16:01:02.484543Z","steps":["trace[1035211856] 'agreement among raft nodes before linearized reading' (duration: 656.753296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.484586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:01.825002Z","time spent":"659.576385ms","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-02T16:01:02.481833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257709819s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-02T16:01:02.48468Z","caller":"traceutil/trace.go:171","msg":"trace[956530518] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:558; }","duration":"1.260581979s","start":"2026-02-02T16:01:01.224093Z","end":"2026-02-02T16:01:02.484675Z","steps":["trace[956530518] 'agreement among raft nodes before linearized reading' (duration: 1.257701419s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.484707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:01.224079Z","time spent":"1.2606229s","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-02T16:01:02.481864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455888341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-02T16:01:02.484943Z","caller":"traceutil/trace.go:171","msg":"trace[877760703] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:558; }","duration":"1.458992857s","start":"2026-02-02T16:01:01.025944Z","end":"2026-02-02T16:01:02.484937Z","steps":["trace[877760703] 'agreement among raft nodes before linearized reading' (duration: 1.455897302s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.484997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:01.025931Z","time spent":"1.459058898s","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-02T16:01:02.482417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658713702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-02T16:01:02.485394Z","caller":"traceutil/trace.go:171","msg":"trace[327996661] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:558; }","duration":"1.661720024s","start":"2026-02-02T16:01:00.823664Z","end":"2026-02-02T16:01:02.485384Z","steps":["trace[327996661] 'agreement among raft nodes before linearized reading' (duration: 1.658712391s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.482973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:00.5019Z","time spent":"1.981064127s","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-02T16:01:02.481765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.798786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-02-02T16:01:02.485741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:00.823646Z","time spent":"1.662085692s","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-02-02T16:01:02.485719Z","caller":"traceutil/trace.go:171","msg":"trace[852973334] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:558; }","duration":"593.734868ms","start":"2026-02-02T16:01:01.891963Z","end":"2026-02-02T16:01:02.485698Z","steps":["trace[852973334] 'agreement among raft nodes before linearized reading' (duration: 589.763165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:02.486736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:01.891924Z","time spent":"594.804742ms","remote":"127.0.0.1:41802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-02T16:01:08.960496Z","caller":"traceutil/trace.go:171","msg":"trace[211615713] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"372.463252ms","start":"2026-02-02T16:01:08.588004Z","end":"2026-02-02T16:01:08.960468Z","steps":["trace[211615713] 'process raft request' (duration: 372.236557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:08.960711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:08.587996Z","time spent":"372.593884ms","remote":"127.0.0.1:41894","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-02T16:01:08.961694Z","caller":"traceutil/trace.go:171","msg":"trace[312372243] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:607; }","duration":"331.665263ms","start":"2026-02-02T16:01:08.630018Z","end":"2026-02-02T16:01:08.961683Z","steps":["trace[312372243] 'read index received' (duration: 331.188493ms)","trace[312372243] 'applied index is now lower than readState.Index' (duration: 476.37µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:08.961838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.837237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-02T16:01:08.961863Z","caller":"traceutil/trace.go:171","msg":"trace[1759195282] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:588; }","duration":"331.910309ms","start":"2026-02-02T16:01:08.629946Z","end":"2026-02-02T16:01:08.961856Z","steps":["trace[1759195282] 'agreement among raft nodes before linearized reading' (duration: 331.782566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:08.961884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:08.629913Z","time spent":"331.966279ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-02T16:01:08.96202Z","caller":"traceutil/trace.go:171","msg":"trace[333142052] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"373.911553ms","start":"2026-02-02T16:01:08.588102Z","end":"2026-02-02T16:01:08.962013Z","steps":["trace[333142052] 'process raft request' (duration: 373.478864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:08.962077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:08.588096Z","time spent":"373.951444ms","remote":"127.0.0.1:41894","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-02T16:01:08.962158Z","caller":"traceutil/trace.go:171","msg":"trace[1875301635] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"371.328358ms","start":"2026-02-02T16:01:08.590821Z","end":"2026-02-02T16:01:08.96215Z","steps":["trace[1875301635] 'process raft request' (duration: 370.815947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:08.962193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:08.590801Z","time spent":"371.371089ms","remote":"127.0.0.1:41894","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-02T16:01:08.963009Z","caller":"traceutil/trace.go:171","msg":"trace[626992722] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"370.787545ms","start":"2026-02-02T16:01:08.592212Z","end":"2026-02-02T16:01:08.963Z","steps":["trace[626992722] 'process raft request' (duration: 369.445978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:08.963068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:08.592204Z","time spent":"370.836637ms","remote":"127.0.0.1:41894","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-02T16:01:09.702032Z","caller":"traceutil/trace.go:171","msg":"trace[1145954819] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"287.41238ms","start":"2026-02-02T16:01:09.414596Z","end":"2026-02-02T16:01:09.702008Z","steps":["trace[1145954819] 'read index received' (duration: 287.173245ms)","trace[1145954819] 'applied index is now lower than readState.Index' (duration: 238.265µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:01:09.702168Z","caller":"traceutil/trace.go:171","msg":"trace[52045354] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"436.47755ms","start":"2026-02-02T16:01:09.265656Z","end":"2026-02-02T16:01:09.702133Z","steps":["trace[52045354] 'process raft request' (duration: 436.200434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:09.702299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:09.265639Z","time spent":"436.557631ms","remote":"127.0.0.1:41894","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-02T16:01:09.702462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.124773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.99\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-02T16:01:09.702512Z","caller":"traceutil/trace.go:171","msg":"trace[1073457015] range","detail":"{range_begin:/registry/masterleases/199.204.45.99; range_end:; response_count:1; response_revision:594; }","duration":"276.190444ms","start":"2026-02-02T16:01:09.426307Z","end":"2026-02-02T16:01:09.702498Z","steps":["trace[1073457015] 'agreement among raft nodes before linearized reading' (duration: 276.036931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:09.702675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.073975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:09.70271Z","caller":"traceutil/trace.go:171","msg":"trace[973997531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"288.136886ms","start":"2026-02-02T16:01:09.414562Z","end":"2026-02-02T16:01:09.702699Z","steps":["trace[973997531] 'agreement among raft nodes before linearized reading' (duration: 288.084764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:11.062973Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.358101152s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-02T16:01:11.063656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:09.704691Z","time spent":"1.358961109s","remote":"127.0.0.1:41684","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-02T16:01:11.256072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.486772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:01:11.256335Z","caller":"traceutil/trace.go:171","msg":"trace[1216217664] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"191.266737ms","start":"2026-02-02T16:01:11.065051Z","end":"2026-02-02T16:01:11.256318Z","steps":["trace[1216217664] 'process raft request' (duration: 46.428067ms)","trace[1216217664] 'compare' (duration: 143.764147ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:01:11.256683Z","caller":"traceutil/trace.go:171","msg":"trace[1620376497] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"184.927023ms","start":"2026-02-02T16:01:11.071751Z","end":"2026-02-02T16:01:11.256678Z","steps":["trace[1620376497] 'process raft request' (duration: 184.516015ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:11.257879Z","caller":"traceutil/trace.go:171","msg":"trace[1432500598] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"181.747197ms","start":"2026-02-02T16:01:11.076122Z","end":"2026-02-02T16:01:11.257869Z","steps":["trace[1432500598] 'process raft request' (duration: 181.711636ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:11.258039Z","caller":"traceutil/trace.go:171","msg":"trace[1692266655] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"184.593376ms","start":"2026-02-02T16:01:11.073437Z","end":"2026-02-02T16:01:11.25803Z","steps":["trace[1692266655] 'process raft request' (duration: 183.066584ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:11.258423Z","caller":"traceutil/trace.go:171","msg":"trace[236584321] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"183.063675ms","start":"2026-02-02T16:01:11.075336Z","end":"2026-02-02T16:01:11.2584Z","steps":["trace[236584321] 'process raft request' (duration: 182.443941ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:11.258473Z","caller":"traceutil/trace.go:171","msg":"trace[1571150325] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"183.606976ms","start":"2026-02-02T16:01:11.074861Z","end":"2026-02-02T16:01:11.258468Z","steps":["trace[1571150325] 'process raft request' (duration: 181.704826ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:19.70615Z","caller":"traceutil/trace.go:171","msg":"trace[324447129] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"419.039241ms","start":"2026-02-02T16:01:19.287095Z","end":"2026-02-02T16:01:19.706134Z","steps":["trace[324447129] 'read index received' (duration: 418.928299ms)","trace[324447129] 'applied index is now lower than readState.Index' (duration: 110.202µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:01:19.706226Z","caller":"traceutil/trace.go:171","msg":"trace[577670758] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"670.464325ms","start":"2026-02-02T16:01:19.035755Z","end":"2026-02-02T16:01:19.706219Z","steps":["trace[577670758] 'process raft request' (duration: 670.306912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:19.706286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:19.03574Z","time spent":"670.502737ms","remote":"127.0.0.1:41728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T16:01:19.706519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.419919ms","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-02T16:01:19.706541Z","caller":"traceutil/trace.go:171","msg":"trace[1685089028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"419.44523ms","start":"2026-02-02T16:01:19.287089Z","end":"2026-02-02T16:01:19.706535Z","steps":["trace[1685089028] 'agreement among raft nodes before linearized reading' (duration: 419.337737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:19.706559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:19.287039Z","time spent":"419.515692ms","remote":"127.0.0.1:41894","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-02T16:01:19.706683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.840422ms","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-02T16:01:19.7067Z","caller":"traceutil/trace.go:171","msg":"trace[1116164618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"383.888782ms","start":"2026-02-02T16:01:19.322806Z","end":"2026-02-02T16:01:19.706694Z","steps":["trace[1116164618] 'agreement among raft nodes before linearized reading' (duration: 383.839171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:19.706715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:19.322785Z","time spent":"383.926383ms","remote":"127.0.0.1:41894","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-02T16:01:19.706848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.572716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.99\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-02T16:01:19.706868Z","caller":"traceutil/trace.go:171","msg":"trace[1567227285] range","detail":"{range_begin:/registry/masterleases/199.204.45.99; range_end:; response_count:1; response_revision:645; }","duration":"279.593416ms","start":"2026-02-02T16:01:19.42727Z","end":"2026-02-02T16:01:19.706863Z","steps":["trace[1567227285] 'agreement among raft nodes before linearized reading' (duration: 279.543135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:19.706955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.120149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:19.706971Z","caller":"traceutil/trace.go:171","msg":"trace[478233353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"290.162999ms","start":"2026-02-02T16:01:19.416803Z","end":"2026-02-02T16:01:19.706966Z","steps":["trace[478233353] 'agreement among raft nodes before linearized reading' (duration: 290.139349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:19.70707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.194645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-02T16:01:19.707086Z","caller":"traceutil/trace.go:171","msg":"trace[1958805449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"379.219565ms","start":"2026-02-02T16:01:19.327861Z","end":"2026-02-02T16:01:19.70708Z","steps":["trace[1958805449] 'agreement among raft nodes before linearized reading' (duration: 379.176174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:19.707101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:19.327858Z","time spent":"379.239745ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T16:01:19.70719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.337117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-02T16:01:19.707206Z","caller":"traceutil/trace.go:171","msg":"trace[1363544903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:645; }","duration":"379.364687ms","start":"2026-02-02T16:01:19.327837Z","end":"2026-02-02T16:01:19.707202Z","steps":["trace[1363544903] 'agreement among raft nodes before linearized reading' (duration: 379.320176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:19.707221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:19.32783Z","time spent":"379.387248ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-02T16:01:19.707322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.445335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T16:01:19.707339Z","caller":"traceutil/trace.go:171","msg":"trace[1089822058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"384.475845ms","start":"2026-02-02T16:01:19.322859Z","end":"2026-02-02T16:01:19.707335Z","steps":["trace[1089822058] 'agreement among raft nodes before linearized reading' (duration: 384.433144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:19.707354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:19.322854Z","time spent":"384.497395ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T16:01:20.123305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.52648ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-02T16:01:20.123369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:19.713306Z","time spent":"410.060132ms","remote":"127.0.0.1:41684","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-02T16:01:20.12455Z","caller":"traceutil/trace.go:171","msg":"trace[296481920] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:669; }","duration":"410.615974ms","start":"2026-02-02T16:01:19.713925Z","end":"2026-02-02T16:01:20.124541Z","steps":["trace[296481920] 'read index received' (duration: 285.931129ms)","trace[296481920] 'applied index is now lower than readState.Index' (duration: 124.684325ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:01:20.124653Z","caller":"traceutil/trace.go:171","msg":"trace[1184803167] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"410.839269ms","start":"2026-02-02T16:01:19.713807Z","end":"2026-02-02T16:01:20.124646Z","steps":["trace[1184803167] 'process raft request' (duration: 410.581144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:20.124703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:19.713788Z","time spent":"410.87694ms","remote":"127.0.0.1:41894","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-02T16:01:20.124762Z","caller":"traceutil/trace.go:171","msg":"trace[415018984] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"410.88767ms","start":"2026-02-02T16:01:19.713869Z","end":"2026-02-02T16:01:20.124757Z","steps":["trace[415018984] 'process raft request' (duration: 410.591484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:20.124785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:19.713864Z","time spent":"410.906681ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:01:20.124873Z","caller":"traceutil/trace.go:171","msg":"trace[1553974448] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"410.516392ms","start":"2026-02-02T16:01:19.714353Z","end":"2026-02-02T16:01:20.124869Z","steps":["trace[1553974448] 'process raft request' (duration: 410.126264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:20.124894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:19.714345Z","time spent":"410.537633ms","remote":"127.0.0.1:41894","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-02T16:01:20.124947Z","caller":"traceutil/trace.go:171","msg":"trace[110370836] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"409.5301ms","start":"2026-02-02T16:01:19.715414Z","end":"2026-02-02T16:01:20.124944Z","steps":["trace[110370836] 'process raft request' (duration: 409.081761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:20.124968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:19.715406Z","time spent":"409.550532ms","remote":"127.0.0.1:41894","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-02T16:01:20.125014Z","caller":"traceutil/trace.go:171","msg":"trace[1090275485] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"407.582791ms","start":"2026-02-02T16:01:19.717426Z","end":"2026-02-02T16:01:20.125009Z","steps":["trace[1090275485] 'process raft request' (duration: 407.08252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:20.125033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:19.717419Z","time spent":"407.604401ms","remote":"127.0.0.1:41894","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-02T16:01:20.125085Z","caller":"traceutil/trace.go:171","msg":"trace[1661621560] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"352.750576ms","start":"2026-02-02T16:01:19.77233Z","end":"2026-02-02T16:01:20.125081Z","steps":["trace[1661621560] 'process raft request' (duration: 352.193005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:20.125107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:19.772319Z","time spent":"352.774966ms","remote":"127.0.0.1:41894","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-02T16:01:20.125176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.250747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:20.125189Z","caller":"traceutil/trace.go:171","msg":"trace[1267841032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"411.282058ms","start":"2026-02-02T16:01:19.713902Z","end":"2026-02-02T16:01:20.125184Z","steps":["trace[1267841032] 'agreement among raft nodes before linearized reading' (duration: 411.260388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:20.125203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:19.713896Z","time spent":"411.304449ms","remote":"127.0.0.1:41670","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-02T16:01:20.211333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.696081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:20.211398Z","caller":"traceutil/trace.go:171","msg":"trace[947961998] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:652; }","duration":"452.794382ms","start":"2026-02-02T16:01:19.758589Z","end":"2026-02-02T16:01:20.211383Z","steps":["trace[947961998] 'agreement among raft nodes before linearized reading' (duration: 452.636949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:20.211658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.205541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:20.21171Z","caller":"traceutil/trace.go:171","msg":"trace[1183078810] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:652; }","duration":"452.278502ms","start":"2026-02-02T16:01:19.759419Z","end":"2026-02-02T16:01:20.211698Z","steps":["trace[1183078810] 'agreement among raft nodes before linearized reading' (duration: 452.1698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:20.211736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:19.759412Z","time spent":"452.318363ms","remote":"127.0.0.1:49102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-02-02T16:01:20.211436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:19.758557Z","time spent":"452.871204ms","remote":"127.0.0.1:49102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-02-02T16:01:20.882337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.839953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:01:20.882421Z","caller":"traceutil/trace.go:171","msg":"trace[1003736819] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"660.447525ms","start":"2026-02-02T16:01:20.221957Z","end":"2026-02-02T16:01:20.882405Z","steps":["trace[1003736819] 'read index received' (duration: 435.455269ms)","trace[1003736819] 'applied index is now lower than readState.Index' (duration: 224.991235ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:01:20.882593Z","caller":"traceutil/trace.go:171","msg":"trace[320624356] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"665.956949ms","start":"2026-02-02T16:01:20.216625Z","end":"2026-02-02T16:01:20.882582Z","steps":["trace[320624356] 'process raft request' (duration: 440.81954ms)","trace[320624356] 'compare' (duration: 224.485636ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:20.882642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:20.216608Z","time spent":"666.010181ms","remote":"127.0.0.1:49102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1652,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T16:01:20.882842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.818402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:20.882862Z","caller":"traceutil/trace.go:171","msg":"trace[760299693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"434.864844ms","start":"2026-02-02T16:01:20.447991Z","end":"2026-02-02T16:01:20.882856Z","steps":["trace[760299693] 'agreement among raft nodes before linearized reading' (duration: 434.807393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:20.882879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:20.447976Z","time spent":"434.899554ms","remote":"127.0.0.1:41658","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-02T16:01:20.88288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.896954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-02T16:01:20.882939Z","caller":"traceutil/trace.go:171","msg":"trace[1299259707] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:653; }","duration":"660.993875ms","start":"2026-02-02T16:01:20.221932Z","end":"2026-02-02T16:01:20.882926Z","steps":["trace[1299259707] 'agreement among raft nodes before linearized reading' (duration: 660.768521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:20.882971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:20.221921Z","time spent":"661.043766ms","remote":"127.0.0.1:41684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-02-02T16:01:20.882977Z","caller":"traceutil/trace.go:171","msg":"trace[1853335857] transaction","detail":"{read_only:false; number_of_response:0; response_revision:653; }","duration":"658.924721ms","start":"2026-02-02T16:01:20.224047Z","end":"2026-02-02T16:01:20.882971Z","steps":["trace[1853335857] 'process raft request' (duration: 658.706297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:20.885533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:20.224041Z","time spent":"661.467865ms","remote":"127.0.0.1:49102","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T16:01:21.184757Z","caller":"traceutil/trace.go:171","msg":"trace[1093737259] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"148.327983ms","start":"2026-02-02T16:01:21.036414Z","end":"2026-02-02T16:01:21.184742Z","steps":["trace[1093737259] 'read index received' (duration: 148.20277ms)","trace[1093737259] 'applied index is now lower than readState.Index' (duration: 124.533µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:01:21.184822Z","caller":"traceutil/trace.go:171","msg":"trace[250977873] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"271.744461ms","start":"2026-02-02T16:01:20.913072Z","end":"2026-02-02T16:01:21.184817Z","steps":["trace[250977873] 'process raft request' (duration: 271.553507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:21.184936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.513696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-hphph.189079598a318d0e\" ","response":"range_response_count:1 size:795"} {"level":"info","ts":"2026-02-02T16:01:21.184961Z","caller":"traceutil/trace.go:171","msg":"trace[889666106] range","detail":"{range_begin:/registry/events/kube-system/cilium-hphph.189079598a318d0e; range_end:; response_count:1; response_revision:654; }","duration":"148.586288ms","start":"2026-02-02T16:01:21.03637Z","end":"2026-02-02T16:01:21.184956Z","steps":["trace[889666106] 'agreement among raft nodes before linearized reading' (duration: 148.427465ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:30.319584Z","caller":"traceutil/trace.go:171","msg":"trace[1519948260] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"109.414114ms","start":"2026-02-02T16:01:30.210152Z","end":"2026-02-02T16:01:30.319567Z","steps":["trace[1519948260] 'process raft request' (duration: 109.392013ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:30.319836Z","caller":"traceutil/trace.go:171","msg":"trace[1934847789] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"109.754481ms","start":"2026-02-02T16:01:30.210074Z","end":"2026-02-02T16:01:30.319829Z","steps":["trace[1934847789] 'process raft request' (duration: 109.385293ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:30.319916Z","caller":"traceutil/trace.go:171","msg":"trace[2067511567] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"109.788582ms","start":"2026-02-02T16:01:30.210122Z","end":"2026-02-02T16:01:30.31991Z","steps":["trace[2067511567] 'process raft request' (duration: 109.396464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:33.645254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.925018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:01:33.645429Z","caller":"traceutil/trace.go:171","msg":"trace[1436195313] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"233.306192ms","start":"2026-02-02T16:01:33.412108Z","end":"2026-02-02T16:01:33.645414Z","steps":["trace[1436195313] 'process raft request' (duration: 233.23812ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:33.645734Z","caller":"traceutil/trace.go:171","msg":"trace[1931857698] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"342.68018ms","start":"2026-02-02T16:01:33.303042Z","end":"2026-02-02T16:01:33.645723Z","steps":["trace[1931857698] 'process raft request' (duration: 175.217569ms)","trace[1931857698] 'compare' (duration: 166.830376ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:33.645795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:33.303026Z","time spent":"342.738871ms","remote":"127.0.0.1:41894","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-02T16:01:34.857081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114539789648953,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T16:01:34.894838Z","caller":"traceutil/trace.go:171","msg":"trace[1665252667] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"538.807952ms","start":"2026-02-02T16:01:34.35601Z","end":"2026-02-02T16:01:34.894818Z","steps":["trace[1665252667] 'read index received' (duration: 538.648338ms)","trace[1665252667] 'applied index is now lower than readState.Index' (duration: 158.794µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:01:34.894933Z","caller":"traceutil/trace.go:171","msg":"trace[1071939516] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"676.970505ms","start":"2026-02-02T16:01:34.217956Z","end":"2026-02-02T16:01:34.894927Z","steps":["trace[1071939516] 'process raft request' (duration: 676.748039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:34.895015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:34.217942Z","time spent":"677.011385ms","remote":"127.0.0.1:41894","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-02T16:01:34.895044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.316762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:34.895086Z","caller":"traceutil/trace.go:171","msg":"trace[1757289343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"478.401503ms","start":"2026-02-02T16:01:34.416671Z","end":"2026-02-02T16:01:34.895073Z","steps":["trace[1757289343] 'agreement among raft nodes before linearized reading' (duration: 478.333602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:34.895114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:34.416651Z","time spent":"478.458095ms","remote":"127.0.0.1:41658","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-02T16:01:34.8953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.289494ms","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-02T16:01:34.895318Z","caller":"traceutil/trace.go:171","msg":"trace[1920975013] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:748; }","duration":"539.342875ms","start":"2026-02-02T16:01:34.35597Z","end":"2026-02-02T16:01:34.895313Z","steps":["trace[1920975013] 'agreement among raft nodes before linearized reading' (duration: 539.269423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:34.895335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:34.355952Z","time spent":"539.379045ms","remote":"127.0.0.1:41894","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-02T16:01:34.895358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.294463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-02T16:01:34.895386Z","caller":"traceutil/trace.go:171","msg":"trace[137646330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"454.334794ms","start":"2026-02-02T16:01:34.441043Z","end":"2026-02-02T16:01:34.895378Z","steps":["trace[137646330] 'agreement among raft nodes before linearized reading' (duration: 454.216601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:34.89541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:34.441037Z","time spent":"454.368904ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T16:01:34.895447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.4166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-02T16:01:34.895466Z","caller":"traceutil/trace.go:171","msg":"trace[1625465528] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:748; }","duration":"455.45916ms","start":"2026-02-02T16:01:34.440001Z","end":"2026-02-02T16:01:34.89546Z","steps":["trace[1625465528] 'agreement among raft nodes before linearized reading' (duration: 455.406819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:34.895483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:34.439976Z","time spent":"455.502392ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-02T16:01:34.895614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.792641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T16:01:34.895636Z","caller":"traceutil/trace.go:171","msg":"trace[564226342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:748; }","duration":"453.826232ms","start":"2026-02-02T16:01:34.441804Z","end":"2026-02-02T16:01:34.89563Z","steps":["trace[564226342] 'agreement among raft nodes before linearized reading' (duration: 453.76973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:34.895653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:34.441797Z","time spent":"453.851842ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T16:01:35.961553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.389226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:01:35.962014Z","caller":"traceutil/trace.go:171","msg":"trace[652929001] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"1.061292755s","start":"2026-02-02T16:01:34.900709Z","end":"2026-02-02T16:01:35.962002Z","steps":["trace[652929001] 'process raft request' (duration: 1.060913186s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:35.962076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:34.900702Z","time spent":"1.061346796s","remote":"127.0.0.1:41894","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-02T16:01:35.962229Z","caller":"traceutil/trace.go:171","msg":"trace[524686713] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"1.060246829s","start":"2026-02-02T16:01:34.901974Z","end":"2026-02-02T16:01:35.962221Z","steps":["trace[524686713] 'process raft request' (duration: 1.059681235s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:35.962282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:34.901965Z","time spent":"1.06029625s","remote":"127.0.0.1:41894","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-02T16:01:35.962378Z","caller":"traceutil/trace.go:171","msg":"trace[102999538] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"1.060350781s","start":"2026-02-02T16:01:34.90202Z","end":"2026-02-02T16:01:35.962371Z","steps":["trace[102999538] 'process raft request' (duration: 1.059657085s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:35.962418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:34.902014Z","time spent":"1.060384972s","remote":"127.0.0.1:41894","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-02T16:01:35.961897Z","caller":"traceutil/trace.go:171","msg":"trace[1480418415] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"1.061327965s","start":"2026-02-02T16:01:34.900554Z","end":"2026-02-02T16:01:35.961882Z","steps":["trace[1480418415] 'process raft request' (duration: 550.55592ms)","trace[1480418415] 'compare' (duration: 510.14282ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:35.963486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:34.900542Z","time spent":"1.062917045s","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:01:35.992994Z","caller":"traceutil/trace.go:171","msg":"trace[1373149808] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:778; }","duration":"146.284342ms","start":"2026-02-02T16:01:35.846698Z","end":"2026-02-02T16:01:35.992982Z","steps":["trace[1373149808] 'read index received' (duration: 113.876899ms)","trace[1373149808] 'applied index is now lower than readState.Index' (duration: 32.406643ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:35.993067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.344183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:35.993087Z","caller":"traceutil/trace.go:171","msg":"trace[894374719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"146.413834ms","start":"2026-02-02T16:01:35.846668Z","end":"2026-02-02T16:01:35.993081Z","steps":["trace[894374719] 'agreement among raft nodes before linearized reading' (duration: 146.356493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:37.689159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.803804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:37.689206Z","caller":"traceutil/trace.go:171","msg":"trace[289447454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"273.887926ms","start":"2026-02-02T16:01:37.415307Z","end":"2026-02-02T16:01:37.689195Z","steps":["trace[289447454] 'range keys from in-memory index tree' (duration: 273.700222ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:48.254029Z","caller":"traceutil/trace.go:171","msg":"trace[1365142007] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"246.28034ms","start":"2026-02-02T16:01:48.007724Z","end":"2026-02-02T16:01:48.254004Z","steps":["trace[1365142007] 'read index received' (duration: 246.114456ms)","trace[1365142007] 'applied index is now lower than readState.Index' (duration: 164.884µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:01:48.254148Z","caller":"traceutil/trace.go:171","msg":"trace[254705902] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"251.854396ms","start":"2026-02-02T16:01:48.002282Z","end":"2026-02-02T16:01:48.254136Z","steps":["trace[254705902] 'process raft request' (duration: 251.560609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:48.2544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.994113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T16:01:48.254467Z","caller":"traceutil/trace.go:171","msg":"trace[859629576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:787; }","duration":"246.077435ms","start":"2026-02-02T16:01:48.008371Z","end":"2026-02-02T16:01:48.254449Z","steps":["trace[859629576] 'agreement among raft nodes before linearized reading' (duration: 245.899221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:48.254691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.587138ms","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-02T16:01:48.254719Z","caller":"traceutil/trace.go:171","msg":"trace[43351600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:787; }","duration":"244.623969ms","start":"2026-02-02T16:01:48.010087Z","end":"2026-02-02T16:01:48.254711Z","steps":["trace[43351600] 'agreement among raft nodes before linearized reading' (duration: 244.546327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:48.257491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.741525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-02T16:01:48.25755Z","caller":"traceutil/trace.go:171","msg":"trace[1361074135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:787; }","duration":"249.827146ms","start":"2026-02-02T16:01:48.007709Z","end":"2026-02-02T16:01:48.257536Z","steps":["trace[1361074135] 'agreement among raft nodes before linearized reading' (duration: 246.750031ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:49.246604Z","caller":"traceutil/trace.go:171","msg":"trace[1915759662] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"300.836006ms","start":"2026-02-02T16:01:48.945747Z","end":"2026-02-02T16:01:49.246583Z","steps":["trace[1915759662] 'process raft request' (duration: 300.732623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:49.246723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:48.945734Z","time spent":"300.928197ms","remote":"127.0.0.1:41894","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-02T16:01:50.030375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.949058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.99\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-02T16:01:50.030445Z","caller":"traceutil/trace.go:171","msg":"trace[873599131] range","detail":"{range_begin:/registry/masterleases/199.204.45.99; range_end:; response_count:1; response_revision:791; }","duration":"602.04824ms","start":"2026-02-02T16:01:49.428378Z","end":"2026-02-02T16:01:50.030426Z","steps":["trace[873599131] 'range keys from in-memory index tree' (duration: 601.826515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:50.030468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:49.42836Z","time spent":"602.102682ms","remote":"127.0.0.1:41684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.99\" "} {"level":"info","ts":"2026-02-02T16:01:50.480196Z","caller":"traceutil/trace.go:171","msg":"trace[2033239665] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"147.997174ms","start":"2026-02-02T16:01:50.332184Z","end":"2026-02-02T16:01:50.480182Z","steps":["trace[2033239665] 'process raft request' (duration: 147.907741ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:50.493657Z","caller":"traceutil/trace.go:171","msg":"trace[990465975] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"157.806184ms","start":"2026-02-02T16:01:50.335842Z","end":"2026-02-02T16:01:50.493648Z","steps":["trace[990465975] 'process raft request' (duration: 157.578738ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:50.493855Z","caller":"traceutil/trace.go:171","msg":"trace[20760687] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"157.008836ms","start":"2026-02-02T16:01:50.336836Z","end":"2026-02-02T16:01:50.493845Z","steps":["trace[20760687] 'process raft request' (duration: 156.628405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:01.561252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.910229ms","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-02T16:02:01.561397Z","caller":"traceutil/trace.go:171","msg":"trace[1098560860] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:876; }","duration":"279.100453ms","start":"2026-02-02T16:02:01.282285Z","end":"2026-02-02T16:02:01.561386Z","steps":["trace[1098560860] 'range keys from in-memory index tree' (duration: 278.792136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:06.347586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114539789649292,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T16:02:06.848313Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114539789649292,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T16:02:06.910408Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.32251127s","expected-duration":"1s"} {"level":"info","ts":"2026-02-02T16:02:06.910765Z","caller":"traceutil/trace.go:171","msg":"trace[820068385] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"1.064017022s","start":"2026-02-02T16:02:05.846727Z","end":"2026-02-02T16:02:06.910744Z","steps":["trace[820068385] 'read index received' (duration: 1.063782236s)","trace[820068385] 'applied index is now lower than readState.Index' (duration: 234.036µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:02:06.910844Z","caller":"traceutil/trace.go:171","msg":"trace[1487656330] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"1.322989511s","start":"2026-02-02T16:02:05.587843Z","end":"2026-02-02T16:02:06.910833Z","steps":["trace[1487656330] 'process raft request' (duration: 1.322733365s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:06.910951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:05.587826Z","time spent":"1.323043463s","remote":"127.0.0.1:41894","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-02T16:02:06.911053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.627636ms","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-02T16:02:06.9111Z","caller":"traceutil/trace.go:171","msg":"trace[180589116] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:888; }","duration":"522.736189ms","start":"2026-02-02T16:02:06.388352Z","end":"2026-02-02T16:02:06.911089Z","steps":["trace[180589116] 'agreement among raft nodes before linearized reading' (duration: 522.597326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:06.911123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:06.38828Z","time spent":"522.839261ms","remote":"127.0.0.1:41894","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-02T16:02:06.911197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064472553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:02:06.911208Z","caller":"traceutil/trace.go:171","msg":"trace[1793871522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"1.064517284s","start":"2026-02-02T16:02:05.846687Z","end":"2026-02-02T16:02:06.911205Z","steps":["trace[1793871522] 'agreement among raft nodes before linearized reading' (duration: 1.064498643s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:06.91122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:05.846669Z","time spent":"1.064547774s","remote":"127.0.0.1:41658","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-02T16:02:06.91135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.840892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-02T16:02:06.911363Z","caller":"traceutil/trace.go:171","msg":"trace[1527997370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:888; }","duration":"308.867483ms","start":"2026-02-02T16:02:06.602491Z","end":"2026-02-02T16:02:06.911359Z","steps":["trace[1527997370] 'agreement among raft nodes before linearized reading' (duration: 308.826002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:06.911379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:06.602486Z","time spent":"308.888983ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T16:02:06.91145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.647283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:02:06.911461Z","caller":"traceutil/trace.go:171","msg":"trace[232742305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"499.688854ms","start":"2026-02-02T16:02:06.411768Z","end":"2026-02-02T16:02:06.911457Z","steps":["trace[232742305] 'agreement among raft nodes before linearized reading' (duration: 499.669473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:06.911472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:06.411753Z","time spent":"499.716184ms","remote":"127.0.0.1:41670","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-02T16:02:06.9115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.160681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-02T16:02:06.91154Z","caller":"traceutil/trace.go:171","msg":"trace[1802546425] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:888; }","duration":"374.223053ms","start":"2026-02-02T16:02:06.537304Z","end":"2026-02-02T16:02:06.911527Z","steps":["trace[1802546425] 'agreement among raft nodes before linearized reading' (duration: 374.082249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:06.911571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:06.53729Z","time spent":"374.273494ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-02T16:02:06.911689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.851405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T16:02:06.911706Z","caller":"traceutil/trace.go:171","msg":"trace[1710430059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:888; }","duration":"338.896867ms","start":"2026-02-02T16:02:06.572804Z","end":"2026-02-02T16:02:06.911701Z","steps":["trace[1710430059] 'agreement among raft nodes before linearized reading' (duration: 338.854956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:06.911721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:06.572794Z","time spent":"338.924057ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-02T16:02:07.123602Z","caller":"traceutil/trace.go:171","msg":"trace[471121661] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"198.047799ms","start":"2026-02-02T16:02:06.925501Z","end":"2026-02-02T16:02:07.123549Z","steps":["trace[471121661] 'process raft request' (duration: 197.842324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:07.688747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.031046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:02:07.688864Z","caller":"traceutil/trace.go:171","msg":"trace[1329939763] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:927; }","duration":"271.632061ms","start":"2026-02-02T16:02:07.417224Z","end":"2026-02-02T16:02:07.688856Z","steps":["trace[1329939763] 'read index received' (duration: 35.791µs)","trace[1329939763] 'applied index is now lower than readState.Index' (duration: 271.59574ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:02:07.688915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.691982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:02:07.688932Z","caller":"traceutil/trace.go:171","msg":"trace[213559786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:892; }","duration":"271.736663ms","start":"2026-02-02T16:02:07.417191Z","end":"2026-02-02T16:02:07.688928Z","steps":["trace[213559786] 'agreement among raft nodes before linearized reading' (duration: 271.693992ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:02:07.689186Z","caller":"traceutil/trace.go:171","msg":"trace[660069323] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"763.491563ms","start":"2026-02-02T16:02:06.925687Z","end":"2026-02-02T16:02:07.689178Z","steps":["trace[660069323] 'process raft request' (duration: 337.982425ms)","trace[660069323] 'compare' (duration: 424.864842ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:02:07.689386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:06.925681Z","time spent":"763.598547ms","remote":"127.0.0.1:41894","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-02T16:02:07.690229Z","caller":"traceutil/trace.go:171","msg":"trace[1265679138] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"764.468398ms","start":"2026-02-02T16:02:06.925753Z","end":"2026-02-02T16:02:07.690221Z","steps":["trace[1265679138] 'process raft request' (duration: 763.049193ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:02:07.69077Z","caller":"traceutil/trace.go:171","msg":"trace[1236231186] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"764.978469ms","start":"2026-02-02T16:02:06.925777Z","end":"2026-02-02T16:02:07.690756Z","steps":["trace[1236231186] 'process raft request' (duration: 763.059563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:07.690985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:06.925774Z","time spent":"765.035851ms","remote":"127.0.0.1:41894","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-02T16:02:07.692496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:06.925749Z","time spent":"764.701003ms","remote":"127.0.0.1:41894","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-02T16:02:15.867511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114539789649360,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T16:02:15.935535Z","caller":"traceutil/trace.go:171","msg":"trace[1613344305] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:951; }","duration":"568.56259ms","start":"2026-02-02T16:02:15.366958Z","end":"2026-02-02T16:02:15.935521Z","steps":["trace[1613344305] 'read index received' (duration: 568.438417ms)","trace[1613344305] 'applied index is now lower than readState.Index' (duration: 123.773µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:02:15.935733Z","caller":"traceutil/trace.go:171","msg":"trace[1341945298] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"574.327201ms","start":"2026-02-02T16:02:15.3614Z","end":"2026-02-02T16:02:15.935728Z","steps":["trace[1341945298] 'process raft request' (duration: 574.037604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:15.935806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:15.36139Z","time spent":"574.356363ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T16:02:15.935908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.94913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T16:02:15.935924Z","caller":"traceutil/trace.go:171","msg":"trace[1233348982] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:912; }","duration":"568.995891ms","start":"2026-02-02T16:02:15.366923Z","end":"2026-02-02T16:02:15.935919Z","steps":["trace[1233348982] 'agreement among raft nodes before linearized reading' (duration: 568.96643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:15.935938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:15.366906Z","time spent":"569.027862ms","remote":"127.0.0.1:49102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-02T16:02:15.936017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.887335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:02:15.936031Z","caller":"traceutil/trace.go:171","msg":"trace[1564377967] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:912; }","duration":"275.923217ms","start":"2026-02-02T16:02:15.660103Z","end":"2026-02-02T16:02:15.936026Z","steps":["trace[1564377967] 'agreement among raft nodes before linearized reading' (duration: 275.901486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:15.936209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.999277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-02T16:02:15.936222Z","caller":"traceutil/trace.go:171","msg":"trace[1796994810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:912; }","duration":"124.022687ms","start":"2026-02-02T16:02:15.812196Z","end":"2026-02-02T16:02:15.936218Z","steps":["trace[1796994810] 'agreement among raft nodes before linearized reading' (duration: 123.970056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:15.93628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.608716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T16:02:15.93629Z","caller":"traceutil/trace.go:171","msg":"trace[747009881] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:912; }","duration":"125.632626ms","start":"2026-02-02T16:02:15.810655Z","end":"2026-02-02T16:02:15.936287Z","steps":["trace[747009881] 'agreement among raft nodes before linearized reading' (duration: 125.611255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:15.936391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.547217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T16:02:15.936402Z","caller":"traceutil/trace.go:171","msg":"trace[1524198271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:912; }","duration":"130.581618ms","start":"2026-02-02T16:02:15.805817Z","end":"2026-02-02T16:02:15.936399Z","steps":["trace[1524198271] 'agreement among raft nodes before linearized reading' (duration: 130.548457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:15.936479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.977805ms","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-02T16:02:15.936489Z","caller":"traceutil/trace.go:171","msg":"trace[957144921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:912; }","duration":"193.016116ms","start":"2026-02-02T16:02:15.74347Z","end":"2026-02-02T16:02:15.936486Z","steps":["trace[957144921] 'agreement among raft nodes before linearized reading' (duration: 192.981945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:21.502534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.877468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:02:21.502627Z","caller":"traceutil/trace.go:171","msg":"trace[1379123894] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:927; }","duration":"213.99029ms","start":"2026-02-02T16:02:21.2886Z","end":"2026-02-02T16:02:21.502591Z","steps":["trace[1379123894] 'count revisions from in-memory index tree' (duration: 213.811196ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:02:26.121691Z","caller":"traceutil/trace.go:171","msg":"trace[1215812594] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"106.156578ms","start":"2026-02-02T16:02:26.015518Z","end":"2026-02-02T16:02:26.121675Z","steps":["trace[1215812594] 'process raft request' (duration: 106.131397ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:02:26.121866Z","caller":"traceutil/trace.go:171","msg":"trace[1316884046] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"107.486043ms","start":"2026-02-02T16:02:26.014375Z","end":"2026-02-02T16:02:26.121861Z","steps":["trace[1316884046] 'process raft request' (duration: 65.416702ms)","trace[1316884046] 'compare' (duration: 41.739802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:02:33.026398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.858246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:02:33.026589Z","caller":"traceutil/trace.go:171","msg":"trace[1725994688] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"743.882442ms","start":"2026-02-02T16:02:32.282688Z","end":"2026-02-02T16:02:33.026571Z","steps":["trace[1725994688] 'process raft request' (duration: 743.825681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:33.026701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:32.282666Z","time spent":"743.962334ms","remote":"127.0.0.1:41894","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-02T16:02:33.027008Z","caller":"traceutil/trace.go:171","msg":"trace[2017453036] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"957.738068ms","start":"2026-02-02T16:02:32.069257Z","end":"2026-02-02T16:02:33.026995Z","steps":["trace[2017453036] 'process raft request' (duration: 433.194766ms)","trace[2017453036] 'compare' (duration: 523.358464ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:02:33.027063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:32.06916Z","time spent":"957.874491ms","remote":"127.0.0.1:41894","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-02T16:02:33.027175Z","caller":"traceutil/trace.go:171","msg":"trace[1774940371] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"883.665075ms","start":"2026-02-02T16:02:32.143502Z","end":"2026-02-02T16:02:33.027167Z","steps":["trace[1774940371] 'read index received' (duration: 359.04549ms)","trace[1774940371] 'applied index is now lower than readState.Index' (duration: 524.618585ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:02:33.027391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.893462ms","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-02T16:02:33.027419Z","caller":"traceutil/trace.go:171","msg":"trace[1226439967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:986; }","duration":"883.951852ms","start":"2026-02-02T16:02:32.143458Z","end":"2026-02-02T16:02:33.02741Z","steps":["trace[1226439967] 'agreement among raft nodes before linearized reading' (duration: 883.84269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:33.027445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:32.143425Z","time spent":"884.014194ms","remote":"127.0.0.1:41894","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-02T16:02:33.027642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.995625ms","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-02T16:02:33.027668Z","caller":"traceutil/trace.go:171","msg":"trace[537264472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:986; }","duration":"882.020735ms","start":"2026-02-02T16:02:32.145639Z","end":"2026-02-02T16:02:33.02766Z","steps":["trace[537264472] 'agreement among raft nodes before linearized reading' (duration: 881.946194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:33.027693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:32.145604Z","time spent":"882.083487ms","remote":"127.0.0.1:41894","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-02T16:02:33.027869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.768112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:02:33.027895Z","caller":"traceutil/trace.go:171","msg":"trace[624189619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"614.822714ms","start":"2026-02-02T16:02:32.413064Z","end":"2026-02-02T16:02:33.027887Z","steps":["trace[624189619] 'agreement among raft nodes before linearized reading' (duration: 614.784213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:33.027917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:32.413049Z","time spent":"614.863665ms","remote":"127.0.0.1:41670","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-02T16:02:34.604979Z","caller":"traceutil/trace.go:171","msg":"trace[1445270183] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"533.018861ms","start":"2026-02-02T16:02:34.071943Z","end":"2026-02-02T16:02:34.604961Z","steps":["trace[1445270183] 'process raft request' (duration: 532.897708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:34.605096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:34.07193Z","time spent":"533.105154ms","remote":"127.0.0.1:41894","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-02T16:02:34.65827Z","caller":"traceutil/trace.go:171","msg":"trace[1695017357] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"425.608551ms","start":"2026-02-02T16:02:34.232643Z","end":"2026-02-02T16:02:34.658252Z","steps":["trace[1695017357] 'process raft request' (duration: 425.503119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:34.658403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:34.232627Z","time spent":"425.712313ms","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:02:37.208571Z","caller":"traceutil/trace.go:171","msg":"trace[1566913795] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1049; }","duration":"147.771094ms","start":"2026-02-02T16:02:37.060779Z","end":"2026-02-02T16:02:37.20855Z","steps":["trace[1566913795] 'read index received' (duration: 147.60688ms)","trace[1566913795] 'applied index is now lower than readState.Index' (duration: 163.314µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:02:37.208828Z","caller":"traceutil/trace.go:171","msg":"trace[2104744695] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"595.514843ms","start":"2026-02-02T16:02:36.613289Z","end":"2026-02-02T16:02:37.208804Z","steps":["trace[2104744695] 'process raft request' (duration: 595.139523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:37.208957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:36.613278Z","time spent":"595.613275ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T16:02:37.209214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.410211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-02T16:02:37.209402Z","caller":"traceutil/trace.go:171","msg":"trace[1614308839] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1005; }","duration":"148.614206ms","start":"2026-02-02T16:02:37.060774Z","end":"2026-02-02T16:02:37.209388Z","steps":["trace[1614308839] 'agreement among raft nodes before linearized reading' (duration: 148.068982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:41.813877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.739599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:02:41.813949Z","caller":"traceutil/trace.go:171","msg":"trace[815749044] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1036; }","duration":"356.843741ms","start":"2026-02-02T16:02:41.457091Z","end":"2026-02-02T16:02:41.813935Z","steps":["trace[815749044] 'range keys from in-memory index tree' (duration: 356.674537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:41.813988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:41.457079Z","time spent":"356.904354ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-02T16:02:41.813981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.308969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-02T16:02:41.814034Z","caller":"traceutil/trace.go:171","msg":"trace[1879640480] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1036; }","duration":"335.407872ms","start":"2026-02-02T16:02:41.478612Z","end":"2026-02-02T16:02:41.81402Z","steps":["trace[1879640480] 'range keys from in-memory index tree' (duration: 335.165565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:41.814061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:41.478592Z","time spent":"335.463164ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-02T16:04:35.941539Z","caller":"traceutil/trace.go:171","msg":"trace[1066724386] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"116.845353ms","start":"2026-02-02T16:04:35.824673Z","end":"2026-02-02T16:04:35.941518Z","steps":["trace[1066724386] 'process raft request' (duration: 116.411624ms)"],"step_count":1}