{"level":"warn","ts":"2026-04-07T12:55:16.454357Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T12:55:16.454466Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-04-07T12:55:16.454549Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T12:55:16.454566Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-04-07T12:55:16.454589Z","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-04-07T12:55:16.455299Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-04-07T12:55:16.455404Z","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":16,"max-cpu-available":16,"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.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-04-07T12:55:16.458565Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.905157ms"} {"level":"info","ts":"2026-04-07T12:55:16.465624Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-04-07T12:55:16.465744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T12:55:16.465782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-04-07T12:55:16.465833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T12:55:16.465867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-04-07T12:55:16.465964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-04-07T12:55:16.471317Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T12:55:16.472568Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T12:55:16.473562Z","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-04-07T12:55:16.476033Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T12:55:16.476453Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T12:55:16.476525Z","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-04-07T12:55:16.47667Z","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-04-07T12:55:16.4767Z","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-04-07T12:55:16.480179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-04-07T12:55:16.480406Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-04-07T12:55:16.481561Z","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-04-07T12:55:16.481742Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-04-07T12:55:16.481801Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-04-07T12:55:16.482093Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T12:55:16.482156Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T12:55:16.667152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T12:55:16.667227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T12:55:16.667282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-04-07T12:55:16.667305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-04-07T12:55:16.667316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-04-07T12:55:16.667332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-04-07T12:55:16.667353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-04-07T12:55:16.668878Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T12:55:16.669152Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:55:16.669168Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:55:16.669466Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T12:55:16.669492Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T12:55:16.669439Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:55:16.670237Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:55:16.670381Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:55:16.670417Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:55:16.672075Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T12:55:16.673537Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-04-07T12:55:53.509103Z","caller":"traceutil/trace.go:171","msg":"trace[1002948659] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"156.034417ms","start":"2026-04-07T12:55:53.353042Z","end":"2026-04-07T12:55:53.509077Z","steps":["trace[1002948659] 'process raft request' (duration: 155.478645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:53.831064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.117825ms","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-04-07T12:55:53.831347Z","caller":"traceutil/trace.go:171","msg":"trace[1763072916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"146.473583ms","start":"2026-04-07T12:55:53.684839Z","end":"2026-04-07T12:55:53.831313Z","steps":["trace[1763072916] 'range keys from in-memory index tree' (duration: 145.87437ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:54.038002Z","caller":"traceutil/trace.go:171","msg":"trace[98367515] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"104.328268ms","start":"2026-04-07T12:55:53.933636Z","end":"2026-04-07T12:55:54.037964Z","steps":["trace[98367515] 'read index received' (duration: 104.083043ms)","trace[98367515] 'applied index is now lower than readState.Index' (duration: 243.675µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:55:54.038195Z","caller":"traceutil/trace.go:171","msg":"trace[729015324] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"202.7212ms","start":"2026-04-07T12:55:53.835415Z","end":"2026-04-07T12:55:54.038136Z","steps":["trace[729015324] 'process raft request' (duration: 202.321052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:54.038513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.764297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dcxl8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-07T12:55:54.038615Z","caller":"traceutil/trace.go:171","msg":"trace[2081550120] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dcxl8; range_end:; response_count:1; response_revision:578; }","duration":"105.019632ms","start":"2026-04-07T12:55:53.933574Z","end":"2026-04-07T12:55:54.038594Z","steps":["trace[2081550120] 'agreement among raft nodes before linearized reading' (duration: 104.594304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:54.742286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.633418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:55:54.74254Z","caller":"traceutil/trace.go:171","msg":"trace[1002056363] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"689.652178ms","start":"2026-04-07T12:55:54.052857Z","end":"2026-04-07T12:55:54.742509Z","steps":["trace[1002056363] 'process raft request' (duration: 689.536656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:54.742611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:54.052824Z","time spent":"689.74918ms","remote":"127.0.0.1:57310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:55:54.742903Z","caller":"traceutil/trace.go:171","msg":"trace[1910051597] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"728.858311ms","start":"2026-04-07T12:55:54.014024Z","end":"2026-04-07T12:55:54.742882Z","steps":["trace[1910051597] 'process raft request' (duration: 236.687571ms)","trace[1910051597] 'compare' (duration: 489.397001ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:55:54.74311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:54.013992Z","time spent":"728.936403ms","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:55:54.743102Z","caller":"traceutil/trace.go:171","msg":"trace[361646350] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"704.81535ms","start":"2026-04-07T12:55:54.038207Z","end":"2026-04-07T12:55:54.743023Z","steps":["trace[361646350] 'read index received' (duration: 212.407015ms)","trace[361646350] 'applied index is now lower than readState.Index' (duration: 492.340634ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:55:54.745073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.958287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T12:55:54.745172Z","caller":"traceutil/trace.go:171","msg":"trace[163050013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"795.08309ms","start":"2026-04-07T12:55:53.950069Z","end":"2026-04-07T12:55:54.745152Z","steps":["trace[163050013] 'agreement among raft nodes before linearized reading' (duration: 793.077107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:54.745218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:53.950057Z","time spent":"795.152361ms","remote":"127.0.0.1:57382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T12:55:54.745322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.273213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T12:55:54.745432Z","caller":"traceutil/trace.go:171","msg":"trace[974139418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"109.431646ms","start":"2026-04-07T12:55:54.635973Z","end":"2026-04-07T12:55:54.745405Z","steps":["trace[974139418] 'agreement among raft nodes before linearized reading' (duration: 109.044538ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:57.451207Z","caller":"traceutil/trace.go:171","msg":"trace[362088593] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"166.808551ms","start":"2026-04-07T12:56:57.284369Z","end":"2026-04-07T12:56:57.451177Z","steps":["trace[362088593] 'process raft request' (duration: 166.583253ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:57.509668Z","caller":"traceutil/trace.go:171","msg":"trace[1225586470] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"208.942871ms","start":"2026-04-07T12:56:57.300691Z","end":"2026-04-07T12:56:57.509634Z","steps":["trace[1225586470] 'process raft request' (duration: 185.460896ms)","trace[1225586470] 'compare' (duration: 23.321119ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:57:22.993582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.408993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:57:22.993793Z","caller":"traceutil/trace.go:171","msg":"trace[337943677] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"369.32162ms","start":"2026-04-07T12:57:22.62445Z","end":"2026-04-07T12:57:22.993772Z","steps":["trace[337943677] 'process raft request' (duration: 369.238096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:22.993862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:22.624418Z","time spent":"369.409513ms","remote":"127.0.0.1:57382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:57:22.99387Z","caller":"traceutil/trace.go:171","msg":"trace[1822643188] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"427.462763ms","start":"2026-04-07T12:57:22.566377Z","end":"2026-04-07T12:57:22.99384Z","steps":["trace[1822643188] 'process raft request' (duration: 44.661455ms)","trace[1822643188] 'compare' (duration: 382.33036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:57:22.994009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:22.566342Z","time spent":"427.588607ms","remote":"127.0.0.1:57382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:57:22.994065Z","caller":"traceutil/trace.go:171","msg":"trace[1286196770] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"135.394766ms","start":"2026-04-07T12:57:22.858654Z","end":"2026-04-07T12:57:22.994049Z","steps":["trace[1286196770] 'process raft request' (duration: 135.086634ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:22.994061Z","caller":"traceutil/trace.go:171","msg":"trace[366422844] linearizableReadLoop","detail":"{readStateIndex:1879; appliedIndex:1876; }","duration":"117.313974ms","start":"2026-04-07T12:57:22.876535Z","end":"2026-04-07T12:57:22.993849Z","steps":["trace[366422844] 'read index received' (duration: 96.794281ms)","trace[366422844] 'applied index is now lower than readState.Index' (duration: 20.518243ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:57:22.994158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.625706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:57:22.994193Z","caller":"traceutil/trace.go:171","msg":"trace[1205241548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1808; }","duration":"117.684889ms","start":"2026-04-07T12:57:22.876498Z","end":"2026-04-07T12:57:22.994183Z","steps":["trace[1205241548] 'agreement among raft nodes before linearized reading' (duration: 117.628687ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:35.454952Z","caller":"traceutil/trace.go:171","msg":"trace[1782685680] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"124.576569ms","start":"2026-04-07T12:57:35.330349Z","end":"2026-04-07T12:57:35.454925Z","steps":["trace[1782685680] 'process raft request' (duration: 124.384925ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:35.466751Z","caller":"traceutil/trace.go:171","msg":"trace[1509236946] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"135.548147ms","start":"2026-04-07T12:57:35.331185Z","end":"2026-04-07T12:57:35.466733Z","steps":["trace[1509236946] 'process raft request' (duration: 135.420405ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:35.759228Z","caller":"traceutil/trace.go:171","msg":"trace[869492369] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"128.004891ms","start":"2026-04-07T12:57:35.631191Z","end":"2026-04-07T12:57:35.759196Z","steps":["trace[869492369] 'process raft request' (duration: 93.360155ms)","trace[869492369] 'compare' (duration: 33.710023ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:57:39.164792Z","caller":"traceutil/trace.go:171","msg":"trace[1412341750] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"113.933559ms","start":"2026-04-07T12:57:39.050837Z","end":"2026-04-07T12:57:39.16477Z","steps":["trace[1412341750] 'process raft request' (duration: 113.775155ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:39.175642Z","caller":"traceutil/trace.go:171","msg":"trace[1665924981] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"122.848359ms","start":"2026-04-07T12:57:39.052767Z","end":"2026-04-07T12:57:39.175615Z","steps":["trace[1665924981] 'process raft request' (duration: 122.656395ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:58:18.162716Z","caller":"traceutil/trace.go:171","msg":"trace[966668151] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"189.355169ms","start":"2026-04-07T12:58:17.973325Z","end":"2026-04-07T12:58:18.16268Z","steps":["trace[966668151] 'process raft request' (duration: 189.200866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:58:18.525377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.478986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T12:58:18.525459Z","caller":"traceutil/trace.go:171","msg":"trace[1779030371] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2157; }","duration":"187.612769ms","start":"2026-04-07T12:58:18.337828Z","end":"2026-04-07T12:58:18.525441Z","steps":["trace[1779030371] 'count revisions from in-memory index tree' (duration: 187.365794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:58:18.525475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.592866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-07T12:58:18.525579Z","caller":"traceutil/trace.go:171","msg":"trace[668676138] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2157; }","duration":"220.734059ms","start":"2026-04-07T12:58:18.304817Z","end":"2026-04-07T12:58:18.525551Z","steps":["trace[668676138] 'range keys from in-memory index tree' (duration: 220.487843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:58:18.526683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.589898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130430"} {"level":"info","ts":"2026-04-07T12:58:18.526966Z","caller":"traceutil/trace.go:171","msg":"trace[385657327] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2157; }","duration":"222.08359ms","start":"2026-04-07T12:58:18.304859Z","end":"2026-04-07T12:58:18.526943Z","steps":["trace[385657327] 'range keys from in-memory index tree' (duration: 220.250508ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:01.583353Z","caller":"traceutil/trace.go:171","msg":"trace[894903298] transaction","detail":"{read_only:false; response_revision:2563; number_of_response:1; }","duration":"144.823305ms","start":"2026-04-07T12:59:01.438504Z","end":"2026-04-07T12:59:01.583327Z","steps":["trace[894903298] 'process raft request' (duration: 144.6092ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:01.584328Z","caller":"traceutil/trace.go:171","msg":"trace[54958087] linearizableReadLoop","detail":"{readStateIndex:2663; appliedIndex:2661; }","duration":"120.168551ms","start":"2026-04-07T12:59:01.464128Z","end":"2026-04-07T12:59:01.584296Z","steps":["trace[54958087] 'read index received' (duration: 119.088157ms)","trace[54958087] 'applied index is now lower than readState.Index' (duration: 1.078844ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:59:01.58444Z","caller":"traceutil/trace.go:171","msg":"trace[1095159922] transaction","detail":"{read_only:false; response_revision:2564; number_of_response:1; }","duration":"145.834429ms","start":"2026-04-07T12:59:01.438583Z","end":"2026-04-07T12:59:01.584417Z","steps":["trace[1095159922] 'process raft request' (duration: 145.564152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:01.584559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.422268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-07T12:59:01.58462Z","caller":"traceutil/trace.go:171","msg":"trace[1565649497] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2564; }","duration":"120.52957ms","start":"2026-04-07T12:59:01.464077Z","end":"2026-04-07T12:59:01.584606Z","steps":["trace[1565649497] 'agreement among raft nodes before linearized reading' (duration: 120.346696ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:29.822523Z","caller":"traceutil/trace.go:171","msg":"trace[938558338] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"126.777587ms","start":"2026-04-07T12:59:29.695718Z","end":"2026-04-07T12:59:29.822496Z","steps":["trace[938558338] 'process raft request' (duration: 87.371972ms)","trace[938558338] 'compare' (duration: 39.18195ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:59:35.701333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.632345ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T12:59:35.701421Z","caller":"traceutil/trace.go:171","msg":"trace[115613699] linearizableReadLoop","detail":"{readStateIndex:2874; appliedIndex:2873; }","duration":"234.898809ms","start":"2026-04-07T12:59:35.466508Z","end":"2026-04-07T12:59:35.701406Z","steps":["trace[115613699] 'read index received' (duration: 64.092µs)","trace[115613699] 'applied index is now lower than readState.Index' (duration: 234.833547ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:59:35.702001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.490452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-07T12:59:35.70203Z","caller":"traceutil/trace.go:171","msg":"trace[975459679] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2767; }","duration":"235.567134ms","start":"2026-04-07T12:59:35.466455Z","end":"2026-04-07T12:59:35.702022Z","steps":["trace[975459679] 'agreement among raft nodes before linearized reading' (duration: 235.444061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:35.702244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.570208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:59:35.702357Z","caller":"traceutil/trace.go:171","msg":"trace[968158355] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2767; }","duration":"196.745313ms","start":"2026-04-07T12:59:35.505592Z","end":"2026-04-07T12:59:35.702337Z","steps":["trace[968158355] 'agreement among raft nodes before linearized reading' (duration: 196.564998ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:03:50.84174Z","caller":"traceutil/trace.go:171","msg":"trace[715490985] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"183.294304ms","start":"2026-04-07T13:03:50.658417Z","end":"2026-04-07T13:03:50.841711Z","steps":["trace[715490985] 'process raft request' (duration: 183.071779ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:03:50.84724Z","caller":"traceutil/trace.go:171","msg":"trace[218256529] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"133.98216ms","start":"2026-04-07T13:03:50.713238Z","end":"2026-04-07T13:03:50.84722Z","steps":["trace[218256529] 'process raft request' (duration: 133.738675ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:03:51.136706Z","caller":"traceutil/trace.go:171","msg":"trace[1443080906] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"141.106464ms","start":"2026-04-07T13:03:50.995566Z","end":"2026-04-07T13:03:51.136673Z","steps":["trace[1443080906] 'process raft request' (duration: 93.036289ms)","trace[1443080906] 'compare' (duration: 47.84029ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:04:12.880711Z","caller":"traceutil/trace.go:171","msg":"trace[1302799523] linearizableReadLoop","detail":"{readStateIndex:5659; appliedIndex:5658; }","duration":"106.85766ms","start":"2026-04-07T13:04:12.773833Z","end":"2026-04-07T13:04:12.880691Z","steps":["trace[1302799523] 'read index received' (duration: 106.736907ms)","trace[1302799523] 'applied index is now lower than readState.Index' (duration: 119.853µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:04:12.88086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.012623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T13:04:12.880883Z","caller":"traceutil/trace.go:171","msg":"trace[251088558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5454; }","duration":"107.082445ms","start":"2026-04-07T13:04:12.773794Z","end":"2026-04-07T13:04:12.880876Z","steps":["trace[251088558] 'agreement among raft nodes before linearized reading' (duration: 106.967293ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:12.881026Z","caller":"traceutil/trace.go:171","msg":"trace[1639444661] transaction","detail":"{read_only:false; response_revision:5454; number_of_response:1; }","duration":"117.805994ms","start":"2026-04-07T13:04:12.763204Z","end":"2026-04-07T13:04:12.88101Z","steps":["trace[1639444661] 'process raft request' (duration: 117.388255ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:13.035654Z","caller":"traceutil/trace.go:171","msg":"trace[890062844] transaction","detail":"{read_only:false; response_revision:5455; number_of_response:1; }","duration":"151.873774ms","start":"2026-04-07T13:04:12.883736Z","end":"2026-04-07T13:04:13.03561Z","steps":["trace[890062844] 'process raft request' (duration: 151.632538ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:13.04955Z","caller":"traceutil/trace.go:171","msg":"trace[1831306437] transaction","detail":"{read_only:false; response_revision:5457; number_of_response:1; }","duration":"126.675249ms","start":"2026-04-07T13:04:12.922856Z","end":"2026-04-07T13:04:13.049531Z","steps":["trace[1831306437] 'process raft request' (duration: 126.628268ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:13.049705Z","caller":"traceutil/trace.go:171","msg":"trace[1870728882] linearizableReadLoop","detail":"{readStateIndex:5662; appliedIndex:5659; }","duration":"113.480513ms","start":"2026-04-07T13:04:12.936177Z","end":"2026-04-07T13:04:13.049658Z","steps":["trace[1870728882] 'read index received' (duration: 99.175931ms)","trace[1870728882] 'applied index is now lower than readState.Index' (duration: 14.301392ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:04:13.049757Z","caller":"traceutil/trace.go:171","msg":"trace[1067538275] transaction","detail":"{read_only:false; response_revision:5456; number_of_response:1; }","duration":"133.798044ms","start":"2026-04-07T13:04:12.915779Z","end":"2026-04-07T13:04:13.049577Z","steps":["trace[1067538275] 'process raft request' (duration: 133.60886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:04:13.049857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.663087ms","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-04-07T13:04:13.050013Z","caller":"traceutil/trace.go:171","msg":"trace[693355159] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5458; }","duration":"113.857413ms","start":"2026-04-07T13:04:12.936137Z","end":"2026-04-07T13:04:13.049994Z","steps":["trace[693355159] 'agreement among raft nodes before linearized reading' (duration: 113.611486ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:42.982158Z","caller":"traceutil/trace.go:171","msg":"trace[1576499238] transaction","detail":"{read_only:false; response_revision:5830; number_of_response:1; }","duration":"237.262604ms","start":"2026-04-07T13:04:42.744873Z","end":"2026-04-07T13:04:42.982135Z","steps":["trace[1576499238] 'process raft request' (duration: 237.096671ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:17.077737Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2998} {"level":"info","ts":"2026-04-07T13:05:17.154337Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2998,"took":"74.850891ms","hash":545703210,"current-db-size-bytes":57884672,"current-db-size":"58 MB","current-db-size-in-use-bytes":33992704,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T13:05:17.154414Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":545703210,"revision":2998,"compact-revision":-1} {"level":"warn","ts":"2026-04-07T13:10:08.740871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.54114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:10:08.741455Z","caller":"traceutil/trace.go:171","msg":"trace[2061086686] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:8235; }","duration":"151.180955ms","start":"2026-04-07T13:10:08.590253Z","end":"2026-04-07T13:10:08.741434Z","steps":["trace[2061086686] 'count revisions from in-memory index tree' (duration: 150.443596ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:10:17.08659Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6082} {"level":"info","ts":"2026-04-07T13:10:17.187892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6082,"took":"99.555855ms","hash":205916805,"current-db-size-bytes":57884672,"current-db-size":"58 MB","current-db-size-in-use-bytes":23494656,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-07T13:10:17.187973Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":205916805,"revision":6082,"compact-revision":2998} {"level":"warn","ts":"2026-04-07T13:10:20.132582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.497182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T13:10:20.132601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.666344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-07T13:10:20.132643Z","caller":"traceutil/trace.go:171","msg":"trace[1245438930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8294; }","duration":"255.603735ms","start":"2026-04-07T13:10:19.877027Z","end":"2026-04-07T13:10:20.132631Z","steps":["trace[1245438930] 'range keys from in-memory index tree' (duration: 255.42389ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:10:20.132687Z","caller":"traceutil/trace.go:171","msg":"trace[596827481] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:8294; }","duration":"213.810278ms","start":"2026-04-07T13:10:19.918853Z","end":"2026-04-07T13:10:20.132664Z","steps":["trace[596827481] 'range keys from in-memory index tree' (duration: 213.43461ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:10:22.370015Z","caller":"traceutil/trace.go:171","msg":"trace[1050632177] transaction","detail":"{read_only:false; response_revision:8305; number_of_response:1; }","duration":"100.609998ms","start":"2026-04-07T13:10:22.268788Z","end":"2026-04-07T13:10:22.369398Z","steps":["trace[1050632177] 'process raft request' (duration: 100.449374ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:10:22.572886Z","caller":"traceutil/trace.go:171","msg":"trace[790125210] transaction","detail":"{read_only:false; response_revision:8306; number_of_response:1; }","duration":"240.174657ms","start":"2026-04-07T13:10:22.332685Z","end":"2026-04-07T13:10:22.57286Z","steps":["trace[790125210] 'process raft request' (duration: 239.999813ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:10:22.57319Z","caller":"traceutil/trace.go:171","msg":"trace[1972851021] transaction","detail":"{read_only:false; response_revision:8307; number_of_response:1; }","duration":"231.304824ms","start":"2026-04-07T13:10:22.341874Z","end":"2026-04-07T13:10:22.573179Z","steps":["trace[1972851021] 'process raft request' (duration: 230.945686ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:10:22.573211Z","caller":"traceutil/trace.go:171","msg":"trace[1372148965] linearizableReadLoop","detail":"{readStateIndex:8604; appliedIndex:8601; }","duration":"165.812944ms","start":"2026-04-07T13:10:22.407373Z","end":"2026-04-07T13:10:22.573186Z","steps":["trace[1372148965] 'read index received' (duration: 165.2484ms)","trace[1372148965] 'applied index is now lower than readState.Index' (duration: 563.434µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:10:22.573283Z","caller":"traceutil/trace.go:171","msg":"trace[1314298981] transaction","detail":"{read_only:false; response_revision:8308; number_of_response:1; }","duration":"231.145531ms","start":"2026-04-07T13:10:22.342126Z","end":"2026-04-07T13:10:22.573271Z","steps":["trace[1314298981] 'process raft request' (duration: 230.938076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:10:22.573347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.953817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:10:22.573387Z","caller":"traceutil/trace.go:171","msg":"trace[319962146] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:8308; }","duration":"166.058849ms","start":"2026-04-07T13:10:22.407314Z","end":"2026-04-07T13:10:22.573373Z","steps":["trace[319962146] 'agreement among raft nodes before linearized reading' (duration: 165.970217ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:10:49.711442Z","caller":"traceutil/trace.go:171","msg":"trace[1387970592] linearizableReadLoop","detail":"{readStateIndex:8811; appliedIndex:8810; }","duration":"100.940547ms","start":"2026-04-07T13:10:49.610477Z","end":"2026-04-07T13:10:49.711417Z","steps":["trace[1387970592] 'read index received' (duration: 100.731942ms)","trace[1387970592] 'applied index is now lower than readState.Index' (duration: 207.355µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:10:49.711729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.246934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T13:10:49.711782Z","caller":"traceutil/trace.go:171","msg":"trace[1443823571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8508; }","duration":"101.361267ms","start":"2026-04-07T13:10:49.610401Z","end":"2026-04-07T13:10:49.711762Z","steps":["trace[1443823571] 'agreement among raft nodes before linearized reading' (duration: 101.128552ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:10:49.712152Z","caller":"traceutil/trace.go:171","msg":"trace[1044700017] transaction","detail":"{read_only:false; response_revision:8508; number_of_response:1; }","duration":"160.803044ms","start":"2026-04-07T13:10:49.551336Z","end":"2026-04-07T13:10:49.712139Z","steps":["trace[1044700017] 'process raft request' (duration: 159.940164ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:10:53.023157Z","caller":"traceutil/trace.go:171","msg":"trace[1887533135] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"129.793165ms","start":"2026-04-07T13:10:52.893336Z","end":"2026-04-07T13:10:53.023129Z","steps":["trace[1887533135] 'process raft request' (duration: 129.571069ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:10:53.1481Z","caller":"traceutil/trace.go:171","msg":"trace[1825065354] transaction","detail":"{read_only:false; response_revision:8526; number_of_response:1; }","duration":"114.738516ms","start":"2026-04-07T13:10:53.033325Z","end":"2026-04-07T13:10:53.148063Z","steps":["trace[1825065354] 'process raft request' (duration: 109.251975ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:10:53.305865Z","caller":"traceutil/trace.go:171","msg":"trace[2060157351] linearizableReadLoop","detail":"{readStateIndex:8831; appliedIndex:8830; }","duration":"109.051059ms","start":"2026-04-07T13:10:53.196781Z","end":"2026-04-07T13:10:53.305832Z","steps":["trace[2060157351] 'read index received' (duration: 97.957865ms)","trace[2060157351] 'applied index is now lower than readState.Index' (duration: 11.092484ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:10:53.305932Z","caller":"traceutil/trace.go:171","msg":"trace[1449474571] transaction","detail":"{read_only:false; response_revision:8527; number_of_response:1; }","duration":"154.007502ms","start":"2026-04-07T13:10:53.151876Z","end":"2026-04-07T13:10:53.305884Z","steps":["trace[1449474571] 'process raft request' (duration: 142.844766ms)","trace[1449474571] 'compare' (duration: 10.974881ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:10:53.306087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.295006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ll9bj\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-07T13:10:53.306131Z","caller":"traceutil/trace.go:171","msg":"trace[895114562] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ll9bj; range_end:; response_count:1; response_revision:8527; }","duration":"109.388738ms","start":"2026-04-07T13:10:53.196731Z","end":"2026-04-07T13:10:53.30612Z","steps":["trace[895114562] 'agreement among raft nodes before linearized reading' (duration: 109.236045ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:11:02.997886Z","caller":"traceutil/trace.go:171","msg":"trace[1845282391] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"105.361482ms","start":"2026-04-07T13:11:02.892499Z","end":"2026-04-07T13:11:02.99786Z","steps":["trace[1845282391] 'process raft request' (duration: 105.177997ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:11:03.923322Z","caller":"traceutil/trace.go:171","msg":"trace[1966814776] linearizableReadLoop","detail":"{readStateIndex:8939; appliedIndex:8938; }","duration":"133.945374ms","start":"2026-04-07T13:11:03.789353Z","end":"2026-04-07T13:11:03.923298Z","steps":["trace[1966814776] 'read index received' (duration: 133.723649ms)","trace[1966814776] 'applied index is now lower than readState.Index' (duration: 220.835µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:11:03.923376Z","caller":"traceutil/trace.go:171","msg":"trace[1991725717] transaction","detail":"{read_only:false; response_revision:8631; number_of_response:1; }","duration":"134.151509ms","start":"2026-04-07T13:11:03.789202Z","end":"2026-04-07T13:11:03.923353Z","steps":["trace[1991725717] 'process raft request' (duration: 133.949114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:03.923665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.283582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T13:11:03.923808Z","caller":"traceutil/trace.go:171","msg":"trace[1170337545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8631; }","duration":"134.484017ms","start":"2026-04-07T13:11:03.789298Z","end":"2026-04-07T13:11:03.923782Z","steps":["trace[1170337545] 'agreement among raft nodes before linearized reading' (duration: 134.116538ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:11:18.538751Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002377.wal"} {"level":"info","ts":"2026-04-07T13:12:58.461244Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"90a0cb2044183f95","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T13:12:58.471205Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T13:12:58.471297Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T13:15:17.098479Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8280} {"level":"info","ts":"2026-04-07T13:15:17.175858Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8280,"took":"75.489818ms","hash":1725927041,"current-db-size-bytes":57884672,"current-db-size":"58 MB","current-db-size-in-use-bytes":24948736,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-07T13:15:17.175923Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1725927041,"revision":8280,"compact-revision":6082} {"level":"info","ts":"2026-04-07T13:20:17.106686Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10678} {"level":"info","ts":"2026-04-07T13:20:17.180406Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10678,"took":"71.638335ms","hash":1899427846,"current-db-size-bytes":57884672,"current-db-size":"58 MB","current-db-size-in-use-bytes":20353024,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-07T13:20:17.180461Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1899427846,"revision":10678,"compact-revision":8280}