{"level":"warn","ts":"2026-02-10T21:29:24.361061Z","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-10T21:29:24.361205Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-10T21:29:24.361293Z","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-10T21:29:24.361306Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-02-10T21:29:24.361357Z","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-10T21:29:24.36268Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-02-10T21:29:24.365292Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-10T21:29:24.371481Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.165569ms"} {"level":"info","ts":"2026-02-10T21:29:24.379387Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-02-10T21:29:24.379436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T21:29:24.379474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-02-10T21:29:24.379483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T21:29:24.379493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-02-10T21:29:24.379523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-02-10T21:29:24.391449Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T21:29:24.39432Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T21:29:24.39857Z","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-10T21:29:24.402506Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T21:29:24.402659Z","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-10T21:29:24.402781Z","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-10T21:29:24.402837Z","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-10T21:29:24.404156Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T21:29:24.40452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-02-10T21:29:24.405037Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-02-10T21:29:24.417904Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-02-10T21:29:24.417943Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-02-10T21:29:24.417655Z","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-10T21:29:24.418986Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T21:29:24.41912Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T21:29:24.78017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T21:29:24.780218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T21:29:24.780238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-02-10T21:29:24.780249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-02-10T21:29:24.780254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-02-10T21:29:24.780268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-02-10T21:29:24.780274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-02-10T21:29:24.784003Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T21:29:24.784028Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T21:29:24.78426Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:29:24.784329Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T21:29:24.785898Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T21:29:24.797396Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T21:29:24.797486Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T21:29:24.800981Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:29:24.801096Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:29:24.801134Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:29:24.826437Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-02-10T21:29:39.58458Z","caller":"traceutil/trace.go:171","msg":"trace[974276746] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"169.15996ms","start":"2026-02-10T21:29:39.415401Z","end":"2026-02-10T21:29:39.584561Z","steps":["trace[974276746] 'process raft request' (duration: 168.716029ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:41.692042Z","caller":"traceutil/trace.go:171","msg":"trace[141050642] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"135.893883ms","start":"2026-02-10T21:29:41.556124Z","end":"2026-02-10T21:29:41.692018Z","steps":["trace[141050642] 'process raft request' (duration: 135.734519ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:41.69264Z","caller":"traceutil/trace.go:171","msg":"trace[740639213] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:423; }","duration":"103.265702ms","start":"2026-02-10T21:29:41.589332Z","end":"2026-02-10T21:29:41.692598Z","steps":["trace[740639213] 'read index received' (duration: 103.250282ms)","trace[740639213] 'applied index is now lower than readState.Index' (duration: 11.76µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:41.692856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.493689ms","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-10T21:29:41.694087Z","caller":"traceutil/trace.go:171","msg":"trace[839434498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:411; }","duration":"104.638558ms","start":"2026-02-10T21:29:41.589297Z","end":"2026-02-10T21:29:41.693935Z","steps":["trace[839434498] 'agreement among raft nodes before linearized reading' (duration: 103.456937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:45.383354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.361976ms","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-10T21:29:45.383423Z","caller":"traceutil/trace.go:171","msg":"trace[1454837786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:454; }","duration":"502.489719ms","start":"2026-02-10T21:29:44.880918Z","end":"2026-02-10T21:29:45.383408Z","steps":["trace[1454837786] 'range keys from in-memory index tree' (duration: 502.288464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:45.383456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:44.880906Z","time spent":"502.54288ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T21:29:45.383662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.55162ms","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-10T21:29:45.383737Z","caller":"traceutil/trace.go:171","msg":"trace[2086812484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:454; }","duration":"505.666113ms","start":"2026-02-10T21:29:44.87805Z","end":"2026-02-10T21:29:45.383716Z","steps":["trace[2086812484] 'range keys from in-memory index tree' (duration: 505.447127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:45.383776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:44.878031Z","time spent":"505.735845ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-10T21:29:45.589998Z","caller":"traceutil/trace.go:171","msg":"trace[1834269815] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"201.164694ms","start":"2026-02-10T21:29:45.388813Z","end":"2026-02-10T21:29:45.589978Z","steps":["trace[1834269815] 'process raft request' (duration: 201.118863ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:45.590396Z","caller":"traceutil/trace.go:171","msg":"trace[1845231157] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"202.50449ms","start":"2026-02-10T21:29:45.387881Z","end":"2026-02-10T21:29:45.590385Z","steps":["trace[1845231157] 'process raft request' (duration: 201.932644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:46.014123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.152483ms","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-10T21:29:46.01417Z","caller":"traceutil/trace.go:171","msg":"trace[864123480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:456; }","duration":"292.288136ms","start":"2026-02-10T21:29:45.721871Z","end":"2026-02-10T21:29:46.014159Z","steps":["trace[864123480] 'range keys from in-memory index tree' (duration: 292.006128ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:52.185354Z","caller":"traceutil/trace.go:171","msg":"trace[1314033726] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"143.118144ms","start":"2026-02-10T21:29:52.042209Z","end":"2026-02-10T21:29:52.185327Z","steps":["trace[1314033726] 'process raft request' (duration: 143.010371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:53.995405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.766537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:53.995489Z","caller":"traceutil/trace.go:171","msg":"trace[2092004566] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:475; }","duration":"144.89625ms","start":"2026-02-10T21:29:53.850574Z","end":"2026-02-10T21:29:53.995471Z","steps":["trace[2092004566] 'range keys from in-memory index tree' (duration: 144.686645ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:54.352016Z","caller":"traceutil/trace.go:171","msg":"trace[605360542] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"162.32796ms","start":"2026-02-10T21:29:54.189662Z","end":"2026-02-10T21:29:54.35199Z","steps":["trace[605360542] 'process raft request' (duration: 98.927548ms)","trace[605360542] 'compare' (duration: 63.262488ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:54.375497Z","caller":"traceutil/trace.go:171","msg":"trace[306229194] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"182.82625ms","start":"2026-02-10T21:29:54.192585Z","end":"2026-02-10T21:29:54.375411Z","steps":["trace[306229194] 'process raft request' (duration: 182.668836ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:54.719001Z","caller":"traceutil/trace.go:171","msg":"trace[698930619] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"104.467114ms","start":"2026-02-10T21:29:54.614518Z","end":"2026-02-10T21:29:54.718985Z","steps":["trace[698930619] 'process raft request' (duration: 104.436083ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:54.719316Z","caller":"traceutil/trace.go:171","msg":"trace[1566302870] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"106.633332ms","start":"2026-02-10T21:29:54.612675Z","end":"2026-02-10T21:29:54.719309Z","steps":["trace[1566302870] 'process raft request' (duration: 106.19925ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:55.001197Z","caller":"traceutil/trace.go:171","msg":"trace[213315399] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"175.789185ms","start":"2026-02-10T21:29:54.82538Z","end":"2026-02-10T21:29:55.001169Z","steps":["trace[213315399] 'process raft request' (duration: 143.894154ms)","trace[213315399] 'compare' (duration: 31.656895ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:55.001608Z","caller":"traceutil/trace.go:171","msg":"trace[1143088688] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"138.412519ms","start":"2026-02-10T21:29:54.863175Z","end":"2026-02-10T21:29:55.001587Z","steps":["trace[1143088688] 'process raft request' (duration: 137.926147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:55.00191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.85145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4313"} {"level":"info","ts":"2026-02-10T21:29:55.001978Z","caller":"traceutil/trace.go:171","msg":"trace[1276098356] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:501; }","duration":"141.999074ms","start":"2026-02-10T21:29:54.859964Z","end":"2026-02-10T21:29:55.001964Z","steps":["trace[1276098356] 'agreement among raft nodes before linearized reading' (duration: 141.688366ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:55.001628Z","caller":"traceutil/trace.go:171","msg":"trace[1011629487] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"141.622594ms","start":"2026-02-10T21:29:54.859991Z","end":"2026-02-10T21:29:55.001614Z","steps":["trace[1011629487] 'read index received' (duration: 109.303382ms)","trace[1011629487] 'applied index is now lower than readState.Index' (duration: 32.317812ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:55.002683Z","caller":"traceutil/trace.go:171","msg":"trace[1274177848] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"136.112909ms","start":"2026-02-10T21:29:54.866558Z","end":"2026-02-10T21:29:55.002671Z","steps":["trace[1274177848] 'process raft request' (duration: 134.965809ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:55.003284Z","caller":"traceutil/trace.go:171","msg":"trace[1742216774] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"136.658994ms","start":"2026-02-10T21:29:54.866612Z","end":"2026-02-10T21:29:55.003271Z","steps":["trace[1742216774] 'process raft request' (duration: 136.009187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:55.168186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.949979ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-10T21:29:55.168418Z","caller":"traceutil/trace.go:171","msg":"trace[1030962295] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"152.977583ms","start":"2026-02-10T21:29:55.015425Z","end":"2026-02-10T21:29:55.168403Z","steps":["trace[1030962295] 'process raft request' (duration: 152.85804ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:55.168766Z","caller":"traceutil/trace.go:171","msg":"trace[1085296938] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:517; }","duration":"166.451329ms","start":"2026-02-10T21:29:55.002301Z","end":"2026-02-10T21:29:55.168752Z","steps":["trace[1085296938] 'read index received' (duration: 111.283µs)","trace[1085296938] 'applied index is now lower than readState.Index' (duration: 166.338826ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:55.16905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.887094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rg7b7\" ","response":"range_response_count:1 size:14921"} {"level":"info","ts":"2026-02-10T21:29:55.169121Z","caller":"traceutil/trace.go:171","msg":"trace[887838596] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rg7b7; range_end:; response_count:1; response_revision:504; }","duration":"308.983497ms","start":"2026-02-10T21:29:54.860119Z","end":"2026-02-10T21:29:55.169103Z","steps":["trace[887838596] 'agreement among raft nodes before linearized reading' (duration: 308.843013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:55.169163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:54.860115Z","time spent":"309.038978ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":14943,"request content":"key:\"/registry/pods/kube-system/cilium-rg7b7\" "} {"level":"warn","ts":"2026-02-10T21:29:55.16935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.536082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329936"} {"level":"info","ts":"2026-02-10T21:29:55.169431Z","caller":"traceutil/trace.go:171","msg":"trace[1632697713] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:504; }","duration":"262.646055ms","start":"2026-02-10T21:29:54.90677Z","end":"2026-02-10T21:29:55.169417Z","steps":["trace[1632697713] 'agreement among raft nodes before linearized reading' (duration: 262.048119ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:55.199939Z","caller":"traceutil/trace.go:171","msg":"trace[555018478] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"184.30336ms","start":"2026-02-10T21:29:55.015619Z","end":"2026-02-10T21:29:55.199923Z","steps":["trace[555018478] 'process raft request' (duration: 183.897599ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:55.200246Z","caller":"traceutil/trace.go:171","msg":"trace[1691867321] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"183.355185ms","start":"2026-02-10T21:29:55.016881Z","end":"2026-02-10T21:29:55.200236Z","steps":["trace[1691867321] 'process raft request' (duration: 182.757649ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:55.410012Z","caller":"traceutil/trace.go:171","msg":"trace[1082528010] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"205.107158ms","start":"2026-02-10T21:29:55.204884Z","end":"2026-02-10T21:29:55.409991Z","steps":["trace[1082528010] 'read index received' (duration: 204.600665ms)","trace[1082528010] 'applied index is now lower than readState.Index' (duration: 505.583µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:55.410083Z","caller":"traceutil/trace.go:171","msg":"trace[1975809680] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"220.284728ms","start":"2026-02-10T21:29:55.189793Z","end":"2026-02-10T21:29:55.410077Z","steps":["trace[1975809680] 'process raft request' (duration: 219.703233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:55.410298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.399616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rg7b7\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-10T21:29:55.410324Z","caller":"traceutil/trace.go:171","msg":"trace[1757455309] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rg7b7; range_end:; response_count:1; response_revision:507; }","duration":"205.458727ms","start":"2026-02-10T21:29:55.204856Z","end":"2026-02-10T21:29:55.410314Z","steps":["trace[1757455309] 'agreement among raft nodes before linearized reading' (duration: 205.400725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:55.487452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.840652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-10T21:29:55.488173Z","caller":"traceutil/trace.go:171","msg":"trace[889199276] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:508; }","duration":"271.586051ms","start":"2026-02-10T21:29:55.216564Z","end":"2026-02-10T21:29:55.48815Z","steps":["trace[889199276] 'agreement among raft nodes before linearized reading' (duration: 270.79172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:55.487657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.006063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329936"} {"level":"info","ts":"2026-02-10T21:29:55.488582Z","caller":"traceutil/trace.go:171","msg":"trace[1064630392] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:508; }","duration":"193.010789ms","start":"2026-02-10T21:29:55.295553Z","end":"2026-02-10T21:29:55.488564Z","steps":["trace[1064630392] 'agreement among raft nodes before linearized reading' (duration: 191.799717ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:55.487899Z","caller":"traceutil/trace.go:171","msg":"trace[2043232825] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"271.510789ms","start":"2026-02-10T21:29:55.216371Z","end":"2026-02-10T21:29:55.487881Z","steps":["trace[2043232825] 'process raft request' (duration: 270.80586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:58.000306Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338640892960597,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T21:29:58.122064Z","caller":"traceutil/trace.go:171","msg":"trace[1825595608] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"622.152193ms","start":"2026-02-10T21:29:57.499891Z","end":"2026-02-10T21:29:58.122043Z","steps":["trace[1825595608] 'read index received' (duration: 621.977249ms)","trace[1825595608] 'applied index is now lower than readState.Index' (duration: 174.074µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:58.122411Z","caller":"traceutil/trace.go:171","msg":"trace[1272854348] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"950.43509ms","start":"2026-02-10T21:29:57.171963Z","end":"2026-02-10T21:29:58.122398Z","steps":["trace[1272854348] 'process raft request' (duration: 949.967907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:58.124201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.104617ms","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-10T21:29:58.124256Z","caller":"traceutil/trace.go:171","msg":"trace[709797754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"384.21271ms","start":"2026-02-10T21:29:57.740032Z","end":"2026-02-10T21:29:58.124244Z","steps":["trace[709797754] 'agreement among raft nodes before linearized reading' (duration: 383.761908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:58.124303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:57.740008Z","time spent":"384.285932ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T21:29:58.124539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.641309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:58.124583Z","caller":"traceutil/trace.go:171","msg":"trace[1691469600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"624.705361ms","start":"2026-02-10T21:29:57.499865Z","end":"2026-02-10T21:29:58.12457Z","steps":["trace[1691469600] 'agreement among raft nodes before linearized reading' (duration: 624.646099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:58.124621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:57.499851Z","time spent":"624.759452ms","remote":"127.0.0.1:52234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T21:29:58.124778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:57.171945Z","time spent":"950.495521ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:29:58.124809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.507547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4062"} {"level":"info","ts":"2026-02-10T21:29:58.12484Z","caller":"traceutil/trace.go:171","msg":"trace[1869347194] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:516; }","duration":"142.569929ms","start":"2026-02-10T21:29:57.982261Z","end":"2026-02-10T21:29:58.124831Z","steps":["trace[1869347194] 'agreement among raft nodes before linearized reading' (duration: 142.508327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:58.125138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.939857ms","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-10T21:29:58.125164Z","caller":"traceutil/trace.go:171","msg":"trace[1219577095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"382.992048ms","start":"2026-02-10T21:29:57.742162Z","end":"2026-02-10T21:29:58.125154Z","steps":["trace[1219577095] 'agreement among raft nodes before linearized reading' (duration: 382.928367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:58.125187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:57.742147Z","time spent":"383.036799ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-10T21:30:00.964387Z","caller":"traceutil/trace.go:171","msg":"trace[286083275] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"466.013507ms","start":"2026-02-10T21:30:00.498358Z","end":"2026-02-10T21:30:00.964372Z","steps":["trace[286083275] 'read index received' (duration: 465.895974ms)","trace[286083275] 'applied index is now lower than readState.Index' (duration: 117.013µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:00.964617Z","caller":"traceutil/trace.go:171","msg":"trace[808459318] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"546.425808ms","start":"2026-02-10T21:30:00.418183Z","end":"2026-02-10T21:30:00.964608Z","steps":["trace[808459318] 'process raft request' (duration: 546.115739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:00.964695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:00.418163Z","time spent":"546.478559ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:30:00.964795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.441248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:00.964814Z","caller":"traceutil/trace.go:171","msg":"trace[1448014807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"466.48079ms","start":"2026-02-10T21:30:00.498327Z","end":"2026-02-10T21:30:00.964808Z","steps":["trace[1448014807] 'agreement among raft nodes before linearized reading' (duration: 466.454319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:00.964829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:00.498313Z","time spent":"466.51174ms","remote":"127.0.0.1:52234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T21:30:00.96491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.360661ms","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-10T21:30:00.964923Z","caller":"traceutil/trace.go:171","msg":"trace[703201583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"228.397242ms","start":"2026-02-10T21:30:00.736522Z","end":"2026-02-10T21:30:00.964919Z","steps":["trace[703201583] 'agreement among raft nodes before linearized reading' (duration: 228.371001ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:05.233835Z","caller":"traceutil/trace.go:171","msg":"trace[1473901660] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"523.843461ms","start":"2026-02-10T21:30:04.709969Z","end":"2026-02-10T21:30:05.233812Z","steps":["trace[1473901660] 'process raft request' (duration: 523.702338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:05.233986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:04.709958Z","time spent":"523.955784ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:30:05.753172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.097934ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-10T21:30:05.753246Z","caller":"traceutil/trace.go:171","msg":"trace[2055803412] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:554; }","duration":"774.095909ms","start":"2026-02-10T21:30:04.979138Z","end":"2026-02-10T21:30:05.753234Z","steps":["trace[2055803412] 'read index received' (duration: 254.50229ms)","trace[2055803412] 'applied index is now lower than readState.Index' (duration: 519.592739ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:05.75338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.242824ms","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-10T21:30:05.753395Z","caller":"traceutil/trace.go:171","msg":"trace[818525727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"774.289114ms","start":"2026-02-10T21:30:04.9791Z","end":"2026-02-10T21:30:05.753389Z","steps":["trace[818525727] 'agreement among raft nodes before linearized reading' (duration: 774.189862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:05.753415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:04.979047Z","time spent":"774.364257ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T21:30:05.753526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.404419ms","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-10T21:30:05.753597Z","caller":"traceutil/trace.go:171","msg":"trace[2124790895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"746.492891ms","start":"2026-02-10T21:30:05.007086Z","end":"2026-02-10T21:30:05.753579Z","steps":["trace[2124790895] 'agreement among raft nodes before linearized reading' (duration: 746.266475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:05.753638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:05.007047Z","time spent":"746.582844ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T21:30:08.212902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.630206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:08.213056Z","caller":"traceutil/trace.go:171","msg":"trace[1163964990] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:571; }","duration":"212.826462ms","start":"2026-02-10T21:30:08.00013Z","end":"2026-02-10T21:30:08.212957Z","steps":["trace[1163964990] 'range keys from in-memory index tree' (duration: 212.519743ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:08.90992Z","caller":"traceutil/trace.go:171","msg":"trace[1533707632] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"168.371339ms","start":"2026-02-10T21:30:08.741528Z","end":"2026-02-10T21:30:08.9099Z","steps":["trace[1533707632] 'process raft request' (duration: 168.137633ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:08.910418Z","caller":"traceutil/trace.go:171","msg":"trace[972456658] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"104.30543ms","start":"2026-02-10T21:30:08.806101Z","end":"2026-02-10T21:30:08.910406Z","steps":["trace[972456658] 'process raft request' (duration: 103.759275ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:09.853727Z","caller":"traceutil/trace.go:171","msg":"trace[1960724913] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"109.044175ms","start":"2026-02-10T21:30:09.744656Z","end":"2026-02-10T21:30:09.8537Z","steps":["trace[1960724913] 'process raft request' (duration: 48.523309ms)","trace[1960724913] 'compare' (duration: 60.371322ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:10.858707Z","caller":"traceutil/trace.go:171","msg":"trace[1029563181] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"108.353717ms","start":"2026-02-10T21:30:10.75032Z","end":"2026-02-10T21:30:10.858674Z","steps":["trace[1029563181] 'process raft request' (duration: 108.203013ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:10.922725Z","caller":"traceutil/trace.go:171","msg":"trace[1439535235] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"159.716111ms","start":"2026-02-10T21:30:10.762987Z","end":"2026-02-10T21:30:10.922703Z","steps":["trace[1439535235] 'process raft request' (duration: 159.177537ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:10.923255Z","caller":"traceutil/trace.go:171","msg":"trace[1057262524] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"121.932456ms","start":"2026-02-10T21:30:10.8004Z","end":"2026-02-10T21:30:10.922333Z","steps":["trace[1057262524] 'read index received' (duration: 59.410397ms)","trace[1057262524] 'applied index is now lower than readState.Index' (duration: 62.520679ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:10.923723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.303811ms","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-10T21:30:10.925985Z","caller":"traceutil/trace.go:171","msg":"trace[880321338] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:608; }","duration":"125.589521ms","start":"2026-02-10T21:30:10.800383Z","end":"2026-02-10T21:30:10.925972Z","steps":["trace[880321338] 'agreement among raft nodes before linearized reading' (duration: 123.001693ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:11.511455Z","caller":"traceutil/trace.go:171","msg":"trace[1586477941] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"163.224034ms","start":"2026-02-10T21:30:11.348215Z","end":"2026-02-10T21:30:11.511439Z","steps":["trace[1586477941] 'process raft request' (duration: 81.598062ms)","trace[1586477941] 'compare' (duration: 81.16287ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:18.675195Z","caller":"traceutil/trace.go:171","msg":"trace[252639179] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"276.958472ms","start":"2026-02-10T21:30:18.398226Z","end":"2026-02-10T21:30:18.675184Z","steps":["trace[252639179] 'process raft request' (duration: 261.564656ms)","trace[252639179] 'compare' (duration: 15.17788ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:18.675457Z","caller":"traceutil/trace.go:171","msg":"trace[1111374671] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:690; }","duration":"232.301625ms","start":"2026-02-10T21:30:18.44278Z","end":"2026-02-10T21:30:18.675081Z","steps":["trace[1111374671] 'read index received' (duration: 216.957651ms)","trace[1111374671] 'applied index is now lower than readState.Index' (duration: 15.343244ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:18.675625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.791449ms","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-10T21:30:18.675643Z","caller":"traceutil/trace.go:171","msg":"trace[1758113880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:668; }","duration":"193.847241ms","start":"2026-02-10T21:30:18.481791Z","end":"2026-02-10T21:30:18.675639Z","steps":["trace[1758113880] 'agreement among raft nodes before linearized reading' (duration: 193.717397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:18.675754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.507041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:18.675767Z","caller":"traceutil/trace.go:171","msg":"trace[144123278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"171.552043ms","start":"2026-02-10T21:30:18.504212Z","end":"2026-02-10T21:30:18.675764Z","steps":["trace[144123278] 'agreement among raft nodes before linearized reading' (duration: 171.515962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:18.676012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.220579ms","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-10T21:30:18.676029Z","caller":"traceutil/trace.go:171","msg":"trace[82706084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:668; }","duration":"233.274731ms","start":"2026-02-10T21:30:18.442748Z","end":"2026-02-10T21:30:18.676023Z","steps":["trace[82706084] 'agreement among raft nodes before linearized reading' (duration: 232.738427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:20.815195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.784516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:30:20.815266Z","caller":"traceutil/trace.go:171","msg":"trace[140026038] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"939.016508ms","start":"2026-02-10T21:30:19.876236Z","end":"2026-02-10T21:30:20.815253Z","steps":["trace[140026038] 'process raft request' (duration: 334.122939ms)","trace[140026038] 'compare' (duration: 604.472838ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:20.815304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:19.87623Z","time spent":"939.056589ms","remote":"127.0.0.1:42586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:30:21.947977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024224583s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:30:21.948088Z","caller":"traceutil/trace.go:171","msg":"trace[83624364] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:711; }","duration":"1.927164249s","start":"2026-02-10T21:30:20.020912Z","end":"2026-02-10T21:30:21.948076Z","steps":["trace[83624364] 'read index received' (duration: 189.381173ms)","trace[83624364] 'applied index is now lower than readState.Index' (duration: 1.737782426s)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:21.948209Z","caller":"traceutil/trace.go:171","msg":"trace[162483811] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"2.06899858s","start":"2026-02-10T21:30:19.879202Z","end":"2026-02-10T21:30:21.948201Z","steps":["trace[162483811] 'process raft request' (duration: 1.044499349s)","trace[162483811] 'compare' (duration: 1.023920116s)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:21.948248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:19.879196Z","time spent":"2.06903064s","remote":"127.0.0.1:52550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1291,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:30:21.949334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.928399582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-10T21:30:21.949384Z","caller":"traceutil/trace.go:171","msg":"trace[1796636815] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:690; }","duration":"1.928486355s","start":"2026-02-10T21:30:20.020886Z","end":"2026-02-10T21:30:21.949372Z","steps":["trace[1796636815] 'agreement among raft nodes before linearized reading' (duration: 1.92832363s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:21.94941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:20.020872Z","time spent":"1.928533926s","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-10T21:30:21.94968Z","caller":"traceutil/trace.go:171","msg":"trace[1425979317] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"2.066178195s","start":"2026-02-10T21:30:19.883488Z","end":"2026-02-10T21:30:21.949666Z","steps":["trace[1425979317] 'process raft request' (duration: 2.064547692s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:21.949732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:19.883482Z","time spent":"2.066212486s","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4833,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:30:22.014892Z","caller":"traceutil/trace.go:171","msg":"trace[222240023] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"1.187031207s","start":"2026-02-10T21:30:20.827844Z","end":"2026-02-10T21:30:22.014875Z","steps":["trace[222240023] 'process raft request' (duration: 1.186930234s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:22.014998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:20.827829Z","time spent":"1.187112348s","remote":"127.0.0.1:42554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:30:22.015442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.500313ms","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-10T21:30:22.015491Z","caller":"traceutil/trace.go:171","msg":"trace[1607285267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:691; }","duration":"278.572855ms","start":"2026-02-10T21:30:21.736911Z","end":"2026-02-10T21:30:22.015484Z","steps":["trace[1607285267] 'agreement among raft nodes before linearized reading' (duration: 278.466792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:22.015606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.297781196s","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-10T21:30:22.015623Z","caller":"traceutil/trace.go:171","msg":"trace[1986429713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:691; }","duration":"1.297819377s","start":"2026-02-10T21:30:20.717798Z","end":"2026-02-10T21:30:22.015617Z","steps":["trace[1986429713] 'agreement among raft nodes before linearized reading' (duration: 1.297769376s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:22.015638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:20.717788Z","time spent":"1.297846298s","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T21:30:22.015728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.303857286s","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-10T21:30:22.015745Z","caller":"traceutil/trace.go:171","msg":"trace[2122188650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:691; }","duration":"1.303906367s","start":"2026-02-10T21:30:20.711833Z","end":"2026-02-10T21:30:22.015739Z","steps":["trace[2122188650] 'agreement among raft nodes before linearized reading' (duration: 1.303861596s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:22.015761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:20.711813Z","time spent":"1.303943029s","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T21:30:22.015832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.518215998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:22.015847Z","caller":"traceutil/trace.go:171","msg":"trace[557135049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"1.518253189s","start":"2026-02-10T21:30:20.49759Z","end":"2026-02-10T21:30:22.015843Z","steps":["trace[557135049] 'agreement among raft nodes before linearized reading' (duration: 1.518229669s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:22.015862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:20.497575Z","time spent":"1.51828451s","remote":"127.0.0.1:52234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T21:30:22.015969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.986775411s","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-10T21:30:22.015984Z","caller":"traceutil/trace.go:171","msg":"trace[844120161] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:691; }","duration":"1.986805392s","start":"2026-02-10T21:30:20.029174Z","end":"2026-02-10T21:30:22.01598Z","steps":["trace[844120161] 'agreement among raft nodes before linearized reading' (duration: 1.986757731s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:22.015999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:20.029169Z","time spent":"1.986826352s","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-10T21:30:22.15669Z","caller":"traceutil/trace.go:171","msg":"trace[1701609885] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"194.786815ms","start":"2026-02-10T21:30:21.961887Z","end":"2026-02-10T21:30:22.156674Z","steps":["trace[1701609885] 'process raft request' (duration: 194.756254ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:22.156958Z","caller":"traceutil/trace.go:171","msg":"trace[649313840] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"199.365877ms","start":"2026-02-10T21:30:21.957585Z","end":"2026-02-10T21:30:22.156951Z","steps":["trace[649313840] 'process raft request' (duration: 198.896764ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:22.157062Z","caller":"traceutil/trace.go:171","msg":"trace[417163655] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"198.38715ms","start":"2026-02-10T21:30:21.958671Z","end":"2026-02-10T21:30:22.157058Z","steps":["trace[417163655] 'process raft request' (duration: 197.900537ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:22.157175Z","caller":"traceutil/trace.go:171","msg":"trace[533991734] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"195.763661ms","start":"2026-02-10T21:30:21.961407Z","end":"2026-02-10T21:30:22.15717Z","steps":["trace[533991734] 'process raft request' (duration: 195.199386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:22.15968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.093497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-d7wjz\" ","response":"range_response_count:1 size:4733"} {"level":"info","ts":"2026-02-10T21:30:22.159703Z","caller":"traceutil/trace.go:171","msg":"trace[1659602514] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-d7wjz; range_end:; response_count:1; response_revision:695; }","duration":"194.126878ms","start":"2026-02-10T21:30:21.96557Z","end":"2026-02-10T21:30:22.159697Z","steps":["trace[1659602514] 'agreement among raft nodes before linearized reading' (duration: 194.029366ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:22.156912Z","caller":"traceutil/trace.go:171","msg":"trace[1663594985] linearizableReadLoop","detail":"{readStateIndex:719; appliedIndex:719; }","duration":"141.509391ms","start":"2026-02-10T21:30:22.015367Z","end":"2026-02-10T21:30:22.156876Z","steps":["trace[1663594985] 'read index received' (duration: 141.50082ms)","trace[1663594985] 'applied index is now lower than readState.Index' (duration: 6.951µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:22.170192Z","caller":"traceutil/trace.go:171","msg":"trace[2024679162] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"145.674171ms","start":"2026-02-10T21:30:22.024486Z","end":"2026-02-10T21:30:22.17016Z","steps":["trace[2024679162] 'process raft request' (duration: 145.62239ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:22.171565Z","caller":"traceutil/trace.go:171","msg":"trace[528239545] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"147.569661ms","start":"2026-02-10T21:30:22.023986Z","end":"2026-02-10T21:30:22.171556Z","steps":["trace[528239545] 'process raft request' (duration: 145.454415ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:22.171694Z","caller":"traceutil/trace.go:171","msg":"trace[839919670] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"147.555921ms","start":"2026-02-10T21:30:22.024115Z","end":"2026-02-10T21:30:22.171671Z","steps":["trace[839919670] 'process raft request' (duration: 145.944468ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:22.171873Z","caller":"traceutil/trace.go:171","msg":"trace[1662034984] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"147.775597ms","start":"2026-02-10T21:30:22.02409Z","end":"2026-02-10T21:30:22.171866Z","steps":["trace[1662034984] 'process raft request' (duration: 145.515597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:22.191012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.937042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:22.191065Z","caller":"traceutil/trace.go:171","msg":"trace[39074446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"167.013394ms","start":"2026-02-10T21:30:22.024039Z","end":"2026-02-10T21:30:22.191053Z","steps":["trace[39074446] 'agreement among raft nodes before linearized reading' (duration: 166.913831ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:28.467755Z","caller":"traceutil/trace.go:171","msg":"trace[701593330] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"196.118431ms","start":"2026-02-10T21:30:28.271616Z","end":"2026-02-10T21:30:28.467735Z","steps":["trace[701593330] 'process raft request' (duration: 196.08232ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:28.468052Z","caller":"traceutil/trace.go:171","msg":"trace[356950603] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"196.438819ms","start":"2026-02-10T21:30:28.271575Z","end":"2026-02-10T21:30:28.468013Z","steps":["trace[356950603] 'process raft request' (duration: 113.162184ms)","trace[356950603] 'compare' (duration: 82.867525ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:29.487776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.610099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:30:29.48788Z","caller":"traceutil/trace.go:171","msg":"trace[1429243419] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"614.39957ms","start":"2026-02-10T21:30:28.873462Z","end":"2026-02-10T21:30:29.487862Z","steps":["trace[1429243419] 'process raft request' (duration: 446.587895ms)","trace[1429243419] 'compare' (duration: 167.213159ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:29.487979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:28.873431Z","time spent":"614.481001ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:30:44.847186Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338640892961164,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T21:30:45.138032Z","caller":"traceutil/trace.go:171","msg":"trace[1230533546] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"794.700875ms","start":"2026-02-10T21:30:44.343313Z","end":"2026-02-10T21:30:45.138014Z","steps":["trace[1230533546] 'process raft request' (duration: 794.602403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:45.138136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:44.343296Z","time spent":"794.784467ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:30:45.138484Z","caller":"traceutil/trace.go:171","msg":"trace[985590256] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:809; }","duration":"791.85487ms","start":"2026-02-10T21:30:44.346622Z","end":"2026-02-10T21:30:45.138477Z","steps":["trace[985590256] 'read index received' (duration: 791.85226ms)","trace[985590256] 'applied index is now lower than readState.Index' (duration: 2.19µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:45.138574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.947852ms","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-10T21:30:45.138594Z","caller":"traceutil/trace.go:171","msg":"trace[1343525032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:780; }","duration":"791.979754ms","start":"2026-02-10T21:30:44.346607Z","end":"2026-02-10T21:30:45.138587Z","steps":["trace[1343525032] 'agreement among raft nodes before linearized reading' (duration: 791.906202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:45.138612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:44.3466Z","time spent":"792.007274ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T21:30:45.272794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.839918ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-10T21:30:45.272856Z","caller":"traceutil/trace.go:171","msg":"trace[299554553] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"134.345973ms","start":"2026-02-10T21:30:45.1385Z","end":"2026-02-10T21:30:45.272846Z","steps":["trace[299554553] 'read index received' (duration: 25.33965ms)","trace[299554553] 'applied index is now lower than readState.Index' (duration: 109.005523ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:45.27295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.849816ms","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-10T21:30:45.272963Z","caller":"traceutil/trace.go:171","msg":"trace[473375330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:780; }","duration":"820.886728ms","start":"2026-02-10T21:30:44.452071Z","end":"2026-02-10T21:30:45.272958Z","steps":["trace[473375330] 'agreement among raft nodes before linearized reading' (duration: 820.800115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:45.272986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:44.452061Z","time spent":"820.919618ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T21:30:45.273079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.260376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-10T21:30:45.273105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.473984ms","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-10T21:30:45.273118Z","caller":"traceutil/trace.go:171","msg":"trace[1151762605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"714.511365ms","start":"2026-02-10T21:30:44.558603Z","end":"2026-02-10T21:30:45.273114Z","steps":["trace[1151762605] 'agreement among raft nodes before linearized reading' (duration: 714.472914ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:45.273118Z","caller":"traceutil/trace.go:171","msg":"trace[1617288032] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:780; }","duration":"709.310967ms","start":"2026-02-10T21:30:44.563796Z","end":"2026-02-10T21:30:45.273107Z","steps":["trace[1617288032] 'agreement among raft nodes before linearized reading' (duration: 709.205274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:45.27313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:44.558589Z","time spent":"714.537875ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T21:30:45.273149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:44.563791Z","time spent":"709.350939ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-10T21:30:45.302423Z","caller":"traceutil/trace.go:171","msg":"trace[2137596964] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"160.209217ms","start":"2026-02-10T21:30:45.142202Z","end":"2026-02-10T21:30:45.302412Z","steps":["trace[2137596964] 'process raft request' (duration: 160.122144ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:50.199986Z","caller":"traceutil/trace.go:171","msg":"trace[1997987661] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"276.741639ms","start":"2026-02-10T21:30:49.923223Z","end":"2026-02-10T21:30:50.199965Z","steps":["trace[1997987661] 'read index received' (duration: 276.661677ms)","trace[1997987661] 'applied index is now lower than readState.Index' (duration: 78.932µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:50.200407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.138619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T21:30:50.200597Z","caller":"traceutil/trace.go:171","msg":"trace[1575827877] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"325.026186ms","start":"2026-02-10T21:30:49.875541Z","end":"2026-02-10T21:30:50.200567Z","steps":["trace[1575827877] 'process raft request' (duration: 324.287976ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:50.202277Z","caller":"traceutil/trace.go:171","msg":"trace[1623482662] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:796; }","duration":"279.074331ms","start":"2026-02-10T21:30:49.923189Z","end":"2026-02-10T21:30:50.202263Z","steps":["trace[1623482662] 'agreement among raft nodes before linearized reading' (duration: 277.056247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:50.202447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:49.875513Z","time spent":"326.807502ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:30:50.92974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.037187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:50.929797Z","caller":"traceutil/trace.go:171","msg":"trace[837242500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"426.129059ms","start":"2026-02-10T21:30:50.503655Z","end":"2026-02-10T21:30:50.929784Z","steps":["trace[837242500] 'range keys from in-memory index tree' (duration: 425.976545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:50.929823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:50.50364Z","time spent":"426.176761ms","remote":"127.0.0.1:52234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T21:31:09.434176Z","caller":"traceutil/trace.go:171","msg":"trace[139348823] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:941; }","duration":"138.557484ms","start":"2026-02-10T21:31:09.295599Z","end":"2026-02-10T21:31:09.434156Z","steps":["trace[139348823] 'read index received' (duration: 138.39933ms)","trace[139348823] 'applied index is now lower than readState.Index' (duration: 157.064µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:31:09.434309Z","caller":"traceutil/trace.go:171","msg":"trace[446696435] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"143.272389ms","start":"2026-02-10T21:31:09.29103Z","end":"2026-02-10T21:31:09.434302Z","steps":["trace[446696435] 'process raft request' (duration: 142.982481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:09.434783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.16875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T21:31:09.434806Z","caller":"traceutil/trace.go:171","msg":"trace[690875638] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:906; }","duration":"139.249162ms","start":"2026-02-10T21:31:09.295549Z","end":"2026-02-10T21:31:09.434799Z","steps":["trace[690875638] 'agreement among raft nodes before linearized reading' (duration: 138.895633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:09.794358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.77802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:31:09.794464Z","caller":"traceutil/trace.go:171","msg":"trace[220379556] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:944; }","duration":"178.284695ms","start":"2026-02-10T21:31:09.61616Z","end":"2026-02-10T21:31:09.794445Z","steps":["trace[220379556] 'read index received' (duration: 23.286546ms)","trace[220379556] 'applied index is now lower than readState.Index' (duration: 154.997439ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:31:09.794537Z","caller":"traceutil/trace.go:171","msg":"trace[1692371658] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"196.25825ms","start":"2026-02-10T21:31:09.598212Z","end":"2026-02-10T21:31:09.79447Z","steps":["trace[1692371658] 'process raft request' (duration: 88.279145ms)","trace[1692371658] 'compare' (duration: 107.627936ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:31:09.794662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.50036ms","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-10T21:31:09.794682Z","caller":"traceutil/trace.go:171","msg":"trace[1422052482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:909; }","duration":"178.561762ms","start":"2026-02-10T21:31:09.616114Z","end":"2026-02-10T21:31:09.794676Z","steps":["trace[1422052482] 'agreement among raft nodes before linearized reading' (duration: 178.429888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:09.795012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.830389ms","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-10T21:31:09.795039Z","caller":"traceutil/trace.go:171","msg":"trace[1470304557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:909; }","duration":"178.918461ms","start":"2026-02-10T21:31:09.616113Z","end":"2026-02-10T21:31:09.795032Z","steps":["trace[1470304557] 'agreement among raft nodes before linearized reading' (duration: 178.764967ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:31:14.430122Z","caller":"traceutil/trace.go:171","msg":"trace[197433719] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"275.090094ms","start":"2026-02-10T21:31:14.155006Z","end":"2026-02-10T21:31:14.430096Z","steps":["trace[197433719] 'process raft request' (duration: 274.960101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:14.806797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.089918ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-10T21:31:14.806897Z","caller":"traceutil/trace.go:171","msg":"trace[58962227] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"133.931132ms","start":"2026-02-10T21:31:14.672951Z","end":"2026-02-10T21:31:14.806882Z","steps":["trace[58962227] 'read index received' (duration: 77.142µs)","trace[58962227] 'applied index is now lower than readState.Index' (duration: 133.85227ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:31:14.806994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.040895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T21:31:14.80701Z","caller":"traceutil/trace.go:171","msg":"trace[952715056] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:923; }","duration":"134.115256ms","start":"2026-02-10T21:31:14.672889Z","end":"2026-02-10T21:31:14.807004Z","steps":["trace[952715056] 'agreement among raft nodes before linearized reading' (duration: 134.027144ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:31:15.632233Z","caller":"traceutil/trace.go:171","msg":"trace[1941355456] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"149.822132ms","start":"2026-02-10T21:31:15.482381Z","end":"2026-02-10T21:31:15.632203Z","steps":["trace[1941355456] 'read index received' (duration: 149.583896ms)","trace[1941355456] 'applied index is now lower than readState.Index' (duration: 237.436µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:31:15.632543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.13039ms","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-10T21:31:15.632582Z","caller":"traceutil/trace.go:171","msg":"trace[1468221985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"150.244233ms","start":"2026-02-10T21:31:15.482329Z","end":"2026-02-10T21:31:15.632574Z","steps":["trace[1468221985] 'agreement among raft nodes before linearized reading' (duration: 149.980446ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:31:15.633987Z","caller":"traceutil/trace.go:171","msg":"trace[385378125] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"179.428545ms","start":"2026-02-10T21:31:15.454546Z","end":"2026-02-10T21:31:15.633974Z","steps":["trace[385378125] 'process raft request' (duration: 177.494964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:16.446501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.681771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:31:16.446585Z","caller":"traceutil/trace.go:171","msg":"trace[1995800820] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"787.588227ms","start":"2026-02-10T21:31:15.658982Z","end":"2026-02-10T21:31:16.44657Z","steps":["trace[1995800820] 'read index received' (duration: 334.65788ms)","trace[1995800820] 'applied index is now lower than readState.Index' (duration: 452.929517ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:31:16.446722Z","caller":"traceutil/trace.go:171","msg":"trace[1356534363] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"808.655884ms","start":"2026-02-10T21:31:15.638058Z","end":"2026-02-10T21:31:16.446714Z","steps":["trace[1356534363] 'process raft request' (duration: 355.677425ms)","trace[1356534363] 'compare' (duration: 452.574198ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:31:16.446758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:31:15.638035Z","time spent":"808.703375ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:31:16.446954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.975197ms","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-10T21:31:16.447762Z","caller":"traceutil/trace.go:171","msg":"trace[1629438686] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:925; }","duration":"788.816139ms","start":"2026-02-10T21:31:15.658937Z","end":"2026-02-10T21:31:16.447753Z","steps":["trace[1629438686] 'agreement among raft nodes before linearized reading' (duration: 787.933326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:16.447784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:31:15.658899Z","time spent":"788.877761ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T21:31:16.447887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.019824ms","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-10T21:31:16.4479Z","caller":"traceutil/trace.go:171","msg":"trace[54463369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:925; }","duration":"567.074465ms","start":"2026-02-10T21:31:15.880821Z","end":"2026-02-10T21:31:16.447896Z","steps":["trace[54463369] 'agreement among raft nodes before linearized reading' (duration: 567.030844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:16.447912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:31:15.880797Z","time spent":"567.112086ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T21:31:16.448081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.397452ms","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-10T21:31:16.448094Z","caller":"traceutil/trace.go:171","msg":"trace[1092077965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:925; }","duration":"548.411142ms","start":"2026-02-10T21:31:15.899679Z","end":"2026-02-10T21:31:16.44809Z","steps":["trace[1092077965] 'agreement among raft nodes before linearized reading' (duration: 548.370921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:16.448106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:31:15.899641Z","time spent":"548.461623ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-10T21:31:23.93999Z","caller":"traceutil/trace.go:171","msg":"trace[183671466] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"278.116815ms","start":"2026-02-10T21:31:23.661856Z","end":"2026-02-10T21:31:23.939973Z","steps":["trace[183671466] 'process raft request' (duration: 278.008692ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:31:27.279391Z","caller":"traceutil/trace.go:171","msg":"trace[908358836] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"384.171149ms","start":"2026-02-10T21:31:26.895194Z","end":"2026-02-10T21:31:27.279365Z","steps":["trace[908358836] 'read index received' (duration: 383.956904ms)","trace[908358836] 'applied index is now lower than readState.Index' (duration: 213.285µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:31:27.279532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.314923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T21:31:27.279559Z","caller":"traceutil/trace.go:171","msg":"trace[77954609] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:967; }","duration":"384.416315ms","start":"2026-02-10T21:31:26.895134Z","end":"2026-02-10T21:31:27.279551Z","steps":["trace[77954609] 'agreement among raft nodes before linearized reading' (duration: 384.318903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:27.279584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:31:26.895111Z","time spent":"384.466177ms","remote":"127.0.0.1:42512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-02-10T21:31:27.279863Z","caller":"traceutil/trace.go:171","msg":"trace[937349734] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"460.045795ms","start":"2026-02-10T21:31:26.819809Z","end":"2026-02-10T21:31:27.279855Z","steps":["trace[937349734] 'process raft request' (duration: 459.415439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:27.279937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:31:26.81979Z","time spent":"460.092707ms","remote":"127.0.0.1:52426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4909,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:31:27.445024Z","caller":"traceutil/trace.go:171","msg":"trace[418608089] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"151.512996ms","start":"2026-02-10T21:31:27.293481Z","end":"2026-02-10T21:31:27.444994Z","steps":["trace[418608089] 'process raft request' (duration: 113.705016ms)","trace[418608089] 'compare' (duration: 37.695587ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:31:41.038267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.09808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-10T21:31:41.040209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.609492ms","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-10T21:31:41.04131Z","caller":"traceutil/trace.go:171","msg":"trace[1371347119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1056; }","duration":"205.740751ms","start":"2026-02-10T21:31:40.835549Z","end":"2026-02-10T21:31:41.041289Z","steps":["trace[1371347119] 'range keys from in-memory index tree' (duration: 204.409066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:41.04078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.612418ms","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-10T21:31:41.041564Z","caller":"traceutil/trace.go:171","msg":"trace[1685158225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1056; }","duration":"439.434869ms","start":"2026-02-10T21:31:40.602117Z","end":"2026-02-10T21:31:41.041552Z","steps":["trace[1685158225] 'range keys from in-memory index tree' (duration: 438.528036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:41.041628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:31:40.602106Z","time spent":"439.506912ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T21:31:41.040849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.257614ms","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-10T21:31:41.041861Z","caller":"traceutil/trace.go:171","msg":"trace[1494607162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1056; }","duration":"362.296041ms","start":"2026-02-10T21:31:40.679551Z","end":"2026-02-10T21:31:41.041847Z","steps":["trace[1494607162] 'range keys from in-memory index tree' (duration: 361.158922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:41.041923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:31:40.67953Z","time spent":"362.381394ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-10T21:31:41.042449Z","caller":"traceutil/trace.go:171","msg":"trace[780946472] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1056; }","duration":"449.328852ms","start":"2026-02-10T21:31:40.593106Z","end":"2026-02-10T21:31:41.042435Z","steps":["trace[780946472] 'range keys from in-memory index tree' (duration: 444.959886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:41.042557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:31:40.593088Z","time spent":"449.453934ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T21:34:49.964812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.710722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:34:49.964917Z","caller":"traceutil/trace.go:171","msg":"trace[672717174] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"287.244808ms","start":"2026-02-10T21:34:49.677654Z","end":"2026-02-10T21:34:49.964899Z","steps":["trace[672717174] 'process raft request' (duration: 159.371742ms)","trace[672717174] 'compare' (duration: 127.479906ms)"],"step_count":2}