{"level":"warn","ts":"2026-05-05T15:32:37.607496Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T15:32:37.607579Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-05-05T15:32:37.607643Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T15:32:37.607654Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-05-05T15:32:37.607678Z","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-05-05T15:32:37.608327Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-05-05T15:32:37.608397Z","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.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-05-05T15:32:37.612308Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.694618ms"} {"level":"info","ts":"2026-05-05T15:32:37.6244Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-05-05T15:32:37.624578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T15:32:37.624598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-05-05T15:32:37.624613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T15:32:37.624653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-05-05T15:32:37.624689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-05-05T15:32:37.630154Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T15:32:37.631515Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T15:32:37.632908Z","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-05-05T15:32:37.635157Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T15:32:37.635388Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T15:32:37.635608Z","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-05-05T15:32:37.635707Z","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-05-05T15:32:37.635789Z","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-05-05T15:32:37.636484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-05-05T15:32:37.636812Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-05-05T15:32:37.641156Z","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-05-05T15:32:37.641314Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-05-05T15:32:37.641388Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-05-05T15:32:37.641586Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T15:32:37.641676Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T15:32:38.625688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T15:32:38.625757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T15:32:38.625792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-05-05T15:32:38.625811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-05-05T15:32:38.625822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-05-05T15:32:38.625838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-05-05T15:32:38.625851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-05-05T15:32:38.626669Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T15:32:38.627646Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T15:32:38.627648Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T15:32:38.627721Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T15:32:38.627999Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T15:32:38.628121Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T15:32:38.628728Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T15:32:38.628841Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T15:32:38.628979Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T15:32:38.630901Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T15:32:38.631389Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"warn","ts":"2026-05-05T15:32:41.154767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.117195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-05T15:32:41.154916Z","caller":"traceutil/trace.go:171","msg":"trace[2115285324] linearizableReadLoop","detail":"{readStateIndex:89; appliedIndex:88; }","duration":"193.698789ms","start":"2026-05-05T15:32:40.961199Z","end":"2026-05-05T15:32:41.154898Z","steps":["trace[2115285324] 'read index received' (duration: 86.292683ms)","trace[2115285324] 'applied index is now lower than readState.Index' (duration: 107.403466ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T15:32:41.155044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.829902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T15:32:41.155078Z","caller":"traceutil/trace.go:171","msg":"trace[1195840712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:85; }","duration":"193.895414ms","start":"2026-05-05T15:32:40.961173Z","end":"2026-05-05T15:32:41.155069Z","steps":["trace[1195840712] 'agreement among raft nodes before linearized reading' (duration: 193.789182ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:32:41.155195Z","caller":"traceutil/trace.go:171","msg":"trace[664751229] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"227.646307ms","start":"2026-05-05T15:32:40.92747Z","end":"2026-05-05T15:32:41.155116Z","steps":["trace[664751229] 'process raft request' (duration: 120.045336ms)","trace[664751229] 'compare' (duration: 106.028593ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T15:32:48.559232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.311918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T15:32:48.559394Z","caller":"traceutil/trace.go:171","msg":"trace[1783645978] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:316; }","duration":"296.076784ms","start":"2026-05-05T15:32:48.263294Z","end":"2026-05-05T15:32:48.559371Z","steps":["trace[1783645978] 'read index received' (duration: 113.458019ms)","trace[1783645978] 'applied index is now lower than readState.Index' (duration: 182.617325ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:32:48.559434Z","caller":"traceutil/trace.go:171","msg":"trace[1508772147] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"327.640975ms","start":"2026-05-05T15:32:48.231757Z","end":"2026-05-05T15:32:48.559398Z","steps":["trace[1508772147] 'process raft request' (duration: 145.062571ms)","trace[1508772147] 'compare' (duration: 182.048221ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T15:32:48.559601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.306799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-05-05T15:32:48.559598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T15:32:48.231737Z","time spent":"327.780668ms","remote":"127.0.0.1:35160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T15:32:48.559668Z","caller":"traceutil/trace.go:171","msg":"trace[1499917748] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:306; }","duration":"296.420782ms","start":"2026-05-05T15:32:48.263231Z","end":"2026-05-05T15:32:48.559652Z","steps":["trace[1499917748] 'agreement among raft nodes before linearized reading' (duration: 296.261258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:32:48.559881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.75253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-05T15:32:48.559919Z","caller":"traceutil/trace.go:171","msg":"trace[787904381] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:306; }","duration":"246.843552ms","start":"2026-05-05T15:32:48.313066Z","end":"2026-05-05T15:32:48.55991Z","steps":["trace[787904381] 'agreement among raft nodes before linearized reading' (duration: 246.780061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:32:48.560197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.655555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4200"} {"level":"warn","ts":"2026-05-05T15:32:48.560254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.478485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-05T15:32:48.560263Z","caller":"traceutil/trace.go:171","msg":"trace[530547469] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:306; }","duration":"108.770988ms","start":"2026-05-05T15:32:48.451473Z","end":"2026-05-05T15:32:48.560244Z","steps":["trace[530547469] 'agreement among raft nodes before linearized reading' (duration: 108.577633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:32:48.560364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.1386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-05T15:32:48.560403Z","caller":"traceutil/trace.go:171","msg":"trace[2030633145] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:306; }","duration":"197.209911ms","start":"2026-05-05T15:32:48.363184Z","end":"2026-05-05T15:32:48.560394Z","steps":["trace[2030633145] 'agreement among raft nodes before linearized reading' (duration: 197.13673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:32:48.560612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.507518ms","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-05-05T15:32:48.560651Z","caller":"traceutil/trace.go:171","msg":"trace[1106193527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:306; }","duration":"231.569069ms","start":"2026-05-05T15:32:48.329072Z","end":"2026-05-05T15:32:48.560641Z","steps":["trace[1106193527] 'agreement among raft nodes before linearized reading' (duration: 231.499608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:32:48.560852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.240889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-05-05T15:32:48.560902Z","caller":"traceutil/trace.go:171","msg":"trace[539364194] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:306; }","duration":"109.317051ms","start":"2026-05-05T15:32:48.451575Z","end":"2026-05-05T15:32:48.560892Z","steps":["trace[539364194] 'agreement among raft nodes before linearized reading' (duration: 109.243399ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:32:48.560301Z","caller":"traceutil/trace.go:171","msg":"trace[300598985] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:306; }","duration":"146.560406ms","start":"2026-05-05T15:32:48.413731Z","end":"2026-05-05T15:32:48.560291Z","steps":["trace[300598985] 'agreement among raft nodes before linearized reading' (duration: 146.483284ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:32:48.867694Z","caller":"traceutil/trace.go:171","msg":"trace[1979460390] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:318; }","duration":"208.320878ms","start":"2026-05-05T15:32:48.659348Z","end":"2026-05-05T15:32:48.867668Z","steps":["trace[1979460390] 'read index received' (duration: 125.872665ms)","trace[1979460390] 'applied index is now lower than readState.Index' (duration: 82.445372ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:32:48.867716Z","caller":"traceutil/trace.go:171","msg":"trace[803370154] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"197.09547ms","start":"2026-05-05T15:32:48.670597Z","end":"2026-05-05T15:32:48.867692Z","steps":["trace[803370154] 'process raft request' (duration: 197.022618ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:32:48.867827Z","caller":"traceutil/trace.go:171","msg":"trace[704035861] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"303.313696ms","start":"2026-05-05T15:32:48.564499Z","end":"2026-05-05T15:32:48.867813Z","steps":["trace[704035861] 'process raft request' (duration: 220.747311ms)","trace[704035861] 'compare' (duration: 82.182206ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:32:48.867867Z","caller":"traceutil/trace.go:171","msg":"trace[1893909875] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"204.310041ms","start":"2026-05-05T15:32:48.663532Z","end":"2026-05-05T15:32:48.867842Z","steps":["trace[1893909875] 'process raft request' (duration: 204.038494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:32:48.868129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.942642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4200"} {"level":"warn","ts":"2026-05-05T15:32:48.868163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.595198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-05T15:32:48.868182Z","caller":"traceutil/trace.go:171","msg":"trace[208323274] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:310; }","duration":"304.041854ms","start":"2026-05-05T15:32:48.564128Z","end":"2026-05-05T15:32:48.86817Z","steps":["trace[208323274] 'agreement among raft nodes before linearized reading' (duration: 303.948732ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:32:48.868221Z","caller":"traceutil/trace.go:171","msg":"trace[479349964] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:310; }","duration":"254.71351ms","start":"2026-05-05T15:32:48.613491Z","end":"2026-05-05T15:32:48.868204Z","steps":["trace[479349964] 'agreement among raft nodes before linearized reading' (duration: 254.605108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:32:48.868226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T15:32:48.564104Z","time spent":"304.112796ms","remote":"127.0.0.1:35134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4224,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-05T15:32:48.868245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.66131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-05T15:32:48.868325Z","caller":"traceutil/trace.go:171","msg":"trace[1379055573] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:310; }","duration":"204.774241ms","start":"2026-05-05T15:32:48.663526Z","end":"2026-05-05T15:32:48.868301Z","steps":["trace[1379055573] 'agreement among raft nodes before linearized reading' (duration: 204.606497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:32:48.868351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.535406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-05T15:32:48.868382Z","caller":"traceutil/trace.go:171","msg":"trace[548382909] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:310; }","duration":"154.602928ms","start":"2026-05-05T15:32:48.713771Z","end":"2026-05-05T15:32:48.868374Z","steps":["trace[548382909] 'agreement among raft nodes before linearized reading' (duration: 154.550787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:32:48.868437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.04016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-05T15:32:48.86848Z","caller":"traceutil/trace.go:171","msg":"trace[781041957] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:310; }","duration":"105.134132ms","start":"2026-05-05T15:32:48.763334Z","end":"2026-05-05T15:32:48.868468Z","steps":["trace[781041957] 'agreement among raft nodes before linearized reading' (duration: 104.848936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:32:48.868468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.522098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-05-05T15:32:48.868557Z","caller":"traceutil/trace.go:171","msg":"trace[15350732] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:310; }","duration":"157.645561ms","start":"2026-05-05T15:32:48.710886Z","end":"2026-05-05T15:32:48.868532Z","steps":["trace[15350732] 'agreement among raft nodes before linearized reading' (duration: 157.458136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:32:48.869434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T15:32:48.564484Z","time spent":"303.375938ms","remote":"127.0.0.1:35220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T15:33:13.356999Z","caller":"traceutil/trace.go:171","msg":"trace[1388666163] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"118.811797ms","start":"2026-05-05T15:33:13.238163Z","end":"2026-05-05T15:33:13.356975Z","steps":["trace[1388666163] 'process raft request' (duration: 118.426597ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:33:13.701334Z","caller":"traceutil/trace.go:171","msg":"trace[1060133552] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"286.037846ms","start":"2026-05-05T15:33:13.41527Z","end":"2026-05-05T15:33:13.701308Z","steps":["trace[1060133552] 'process raft request' (duration: 285.576154ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:33:13.701266Z","caller":"traceutil/trace.go:171","msg":"trace[2053485497] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"284.996681ms","start":"2026-05-05T15:33:13.416237Z","end":"2026-05-05T15:33:13.701234Z","steps":["trace[2053485497] 'read index received' (duration: 284.589351ms)","trace[2053485497] 'applied index is now lower than readState.Index' (duration: 377.978µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T15:33:13.701729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.470072ms","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-05-05T15:33:13.701829Z","caller":"traceutil/trace.go:171","msg":"trace[1920740477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"285.621495ms","start":"2026-05-05T15:33:13.416178Z","end":"2026-05-05T15:33:13.701799Z","steps":["trace[1920740477] 'agreement among raft nodes before linearized reading' (duration: 285.259527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:33:14.194246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.584349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T15:33:14.194494Z","caller":"traceutil/trace.go:171","msg":"trace[1505761268] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"488.119673ms","start":"2026-05-05T15:33:13.706348Z","end":"2026-05-05T15:33:14.194467Z","steps":["trace[1505761268] 'process raft request' (duration: 488.044191ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:33:14.194491Z","caller":"traceutil/trace.go:171","msg":"trace[804698632] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"497.758241ms","start":"2026-05-05T15:33:13.696692Z","end":"2026-05-05T15:33:14.19445Z","steps":["trace[804698632] 'process raft request' (duration: 117.839073ms)","trace[804698632] 'compare' (duration: 379.366174ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T15:33:14.194596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T15:33:13.706326Z","time spent":"488.233396ms","remote":"127.0.0.1:35220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T15:33:14.194745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T15:33:13.696662Z","time spent":"497.965876ms","remote":"127.0.0.1:35220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T15:33:14.194755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.668469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kc6sf\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-05T15:33:14.194493Z","caller":"traceutil/trace.go:171","msg":"trace[296733874] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"493.020059ms","start":"2026-05-05T15:33:13.701446Z","end":"2026-05-05T15:33:14.194466Z","steps":["trace[296733874] 'read index received' (duration: 113.100251ms)","trace[296733874] 'applied index is now lower than readState.Index' (duration: 379.918217ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:33:14.194825Z","caller":"traceutil/trace.go:171","msg":"trace[1709962490] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kc6sf; range_end:; response_count:1; response_revision:574; }","duration":"721.794632ms","start":"2026-05-05T15:33:13.473014Z","end":"2026-05-05T15:33:14.194809Z","steps":["trace[1709962490] 'agreement among raft nodes before linearized reading' (duration: 721.540726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:33:14.194868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T15:33:13.472979Z","time spent":"721.880174ms","remote":"127.0.0.1:35142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-kc6sf\" "} {"level":"info","ts":"2026-05-05T15:33:14.365752Z","caller":"traceutil/trace.go:171","msg":"trace[873725163] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"170.860264ms","start":"2026-05-05T15:33:14.194863Z","end":"2026-05-05T15:33:14.365723Z","steps":["trace[873725163] 'read index received' (duration: 150.971312ms)","trace[873725163] 'applied index is now lower than readState.Index' (duration: 19.887882ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:33:14.365947Z","caller":"traceutil/trace.go:171","msg":"trace[645875479] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"332.056669ms","start":"2026-05-05T15:33:14.033866Z","end":"2026-05-05T15:33:14.365923Z","steps":["trace[645875479] 'process raft request' (duration: 311.848529ms)","trace[645875479] 'compare' (duration: 19.883882ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T15:33:14.36596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.129764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T15:33:14.366018Z","caller":"traceutil/trace.go:171","msg":"trace[1588526629] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:575; }","duration":"652.236726ms","start":"2026-05-05T15:33:13.713759Z","end":"2026-05-05T15:33:14.365996Z","steps":["trace[1588526629] 'agreement among raft nodes before linearized reading' (duration: 652.114463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:33:14.36605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T15:33:14.03384Z","time spent":"332.147812ms","remote":"127.0.0.1:35134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4542,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T15:33:14.366069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T15:33:13.71375Z","time spent":"652.307088ms","remote":"127.0.0.1:35194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-05T15:33:14.366044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.347401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T15:33:14.366067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.428779ms","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-05-05T15:33:14.366167Z","caller":"traceutil/trace.go:171","msg":"trace[769643605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"389.546046ms","start":"2026-05-05T15:33:13.976594Z","end":"2026-05-05T15:33:14.36614Z","steps":["trace[769643605] 'agreement among raft nodes before linearized reading' (duration: 389.200968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:33:14.366143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.15124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T15:33:14.366219Z","caller":"traceutil/trace.go:171","msg":"trace[2117765339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"530.635253ms","start":"2026-05-05T15:33:13.83556Z","end":"2026-05-05T15:33:14.366195Z","steps":["trace[2117765339] 'agreement among raft nodes before linearized reading' (duration: 530.236254ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:33:14.366262Z","caller":"traceutil/trace.go:171","msg":"trace[973515023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"548.323013ms","start":"2026-05-05T15:33:13.817914Z","end":"2026-05-05T15:33:14.366237Z","steps":["trace[973515023] 'agreement among raft nodes before linearized reading' (duration: 548.099538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:33:14.366305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T15:33:13.835514Z","time spent":"530.780007ms","remote":"127.0.0.1:35220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T15:33:14.366338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T15:33:13.817892Z","time spent":"548.436227ms","remote":"127.0.0.1:34950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T15:33:14.366245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T15:33:13.976569Z","time spent":"389.666419ms","remote":"127.0.0.1:35220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T15:33:14.373399Z","caller":"traceutil/trace.go:171","msg":"trace[1392905504] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"161.965662ms","start":"2026-05-05T15:33:14.211414Z","end":"2026-05-05T15:33:14.37338Z","steps":["trace[1392905504] 'process raft request' (duration: 161.812528ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:34:40.169667Z","caller":"traceutil/trace.go:171","msg":"trace[1615065861] linearizableReadLoop","detail":"{readStateIndex:1861; appliedIndex:1860; }","duration":"190.363984ms","start":"2026-05-05T15:34:39.979279Z","end":"2026-05-05T15:34:40.169643Z","steps":["trace[1615065861] 'read index received' (duration: 190.248261ms)","trace[1615065861] 'applied index is now lower than readState.Index' (duration: 114.703µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:34:40.169788Z","caller":"traceutil/trace.go:171","msg":"trace[423847044] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"236.635101ms","start":"2026-05-05T15:34:39.933102Z","end":"2026-05-05T15:34:40.169737Z","steps":["trace[423847044] 'process raft request' (duration: 236.386026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:34:40.169904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.60598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T15:34:40.170083Z","caller":"traceutil/trace.go:171","msg":"trace[894359375] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1796; }","duration":"190.804193ms","start":"2026-05-05T15:34:39.979238Z","end":"2026-05-05T15:34:40.170043Z","steps":["trace[894359375] 'agreement among raft nodes before linearized reading' (duration: 190.545367ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:34:40.182922Z","caller":"traceutil/trace.go:171","msg":"trace[641838346] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"185.328744ms","start":"2026-05-05T15:34:39.997563Z","end":"2026-05-05T15:34:40.182892Z","steps":["trace[641838346] 'process raft request' (duration: 185.13835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:34:40.183026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.863467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T15:34:40.183113Z","caller":"traceutil/trace.go:171","msg":"trace[790546813] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1797; }","duration":"132.00632ms","start":"2026-05-05T15:34:40.051088Z","end":"2026-05-05T15:34:40.183094Z","steps":["trace[790546813] 'agreement among raft nodes before linearized reading' (duration: 131.756564ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:34:55.295455Z","caller":"traceutil/trace.go:171","msg":"trace[595186126] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"143.087052ms","start":"2026-05-05T15:34:55.152344Z","end":"2026-05-05T15:34:55.295431Z","steps":["trace[595186126] 'process raft request' (duration: 142.909508ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:35:28.958234Z","caller":"traceutil/trace.go:171","msg":"trace[1413840412] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"105.491327ms","start":"2026-05-05T15:35:28.852717Z","end":"2026-05-05T15:35:28.958208Z","steps":["trace[1413840412] 'process raft request' (duration: 105.321413ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:35:35.144873Z","caller":"traceutil/trace.go:171","msg":"trace[934564578] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"164.10071ms","start":"2026-05-05T15:35:34.980742Z","end":"2026-05-05T15:35:35.144842Z","steps":["trace[934564578] 'process raft request' (duration: 163.927716ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:35:35.389424Z","caller":"traceutil/trace.go:171","msg":"trace[1389768958] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"239.900716ms","start":"2026-05-05T15:35:35.149417Z","end":"2026-05-05T15:35:35.389318Z","steps":["trace[1389768958] 'process raft request' (duration: 225.987112ms)","trace[1389768958] 'compare' (duration: 13.578575ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:35:35.43921Z","caller":"traceutil/trace.go:171","msg":"trace[376969826] linearizableReadLoop","detail":"{readStateIndex:2222; appliedIndex:2219; }","duration":"197.649003ms","start":"2026-05-05T15:35:35.241521Z","end":"2026-05-05T15:35:35.43917Z","steps":["trace[376969826] 'read index received' (duration: 133.893127ms)","trace[376969826] 'applied index is now lower than readState.Index' (duration: 63.754626ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:35:35.439365Z","caller":"traceutil/trace.go:171","msg":"trace[562835844] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"254.289759ms","start":"2026-05-05T15:35:35.185062Z","end":"2026-05-05T15:35:35.439352Z","steps":["trace[562835844] 'process raft request' (duration: 254.067734ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:35:35.439228Z","caller":"traceutil/trace.go:171","msg":"trace[438907548] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"289.034282ms","start":"2026-05-05T15:35:35.150172Z","end":"2026-05-05T15:35:35.439206Z","steps":["trace[438907548] 'process raft request' (duration: 288.827067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:35:35.439578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.303617ms","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-05-05T15:35:35.43966Z","caller":"traceutil/trace.go:171","msg":"trace[444477271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2145; }","duration":"195.42235ms","start":"2026-05-05T15:35:35.244219Z","end":"2026-05-05T15:35:35.439641Z","steps":["trace[444477271] 'agreement among raft nodes before linearized reading' (duration: 195.251315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:35:35.439578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.035072ms","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-05-05T15:35:35.439816Z","caller":"traceutil/trace.go:171","msg":"trace[770042394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2145; }","duration":"198.306849ms","start":"2026-05-05T15:35:35.241469Z","end":"2026-05-05T15:35:35.439776Z","steps":["trace[770042394] 'agreement among raft nodes before linearized reading' (duration: 197.922439ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:36:43.847413Z","caller":"traceutil/trace.go:171","msg":"trace[147119559] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"142.650625ms","start":"2026-05-05T15:36:43.704691Z","end":"2026-05-05T15:36:43.847342Z","steps":["trace[147119559] 'process raft request' (duration: 105.629654ms)","trace[147119559] 'compare' (duration: 36.847177ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T15:36:43.847466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.295378ms","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-05-05T15:36:43.847557Z","caller":"traceutil/trace.go:171","msg":"trace[1505643859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2713; }","duration":"135.405601ms","start":"2026-05-05T15:36:43.712112Z","end":"2026-05-05T15:36:43.847518Z","steps":["trace[1505643859] 'agreement among raft nodes before linearized reading' (duration: 135.216666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:36:44.27301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.157016ms","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-05-05T15:36:44.273074Z","caller":"traceutil/trace.go:171","msg":"trace[592911536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2716; }","duration":"194.263229ms","start":"2026-05-05T15:36:44.078795Z","end":"2026-05-05T15:36:44.273058Z","steps":["trace[592911536] 'range keys from in-memory index tree' (duration: 194.012992ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:39:09.271474Z","caller":"traceutil/trace.go:171","msg":"trace[236375539] transaction","detail":"{read_only:false; response_revision:3682; number_of_response:1; }","duration":"101.713751ms","start":"2026-05-05T15:39:09.169734Z","end":"2026-05-05T15:39:09.271448Z","steps":["trace[236375539] 'process raft request' (duration: 99.669901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:40:34.027444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.57026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T15:40:34.027546Z","caller":"traceutil/trace.go:171","msg":"trace[1254170607] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:4895; }","duration":"238.709973ms","start":"2026-05-05T15:40:33.788817Z","end":"2026-05-05T15:40:34.027527Z","steps":["trace[1254170607] 'count revisions from in-memory index tree' (duration: 238.501148ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:40:34.027652Z","caller":"traceutil/trace.go:171","msg":"trace[655396372] linearizableReadLoop","detail":"{readStateIndex:5082; appliedIndex:5081; }","duration":"210.818288ms","start":"2026-05-05T15:40:33.816793Z","end":"2026-05-05T15:40:34.027612Z","steps":["trace[655396372] 'read index received' (duration: 176.10149ms)","trace[655396372] 'applied index is now lower than readState.Index' (duration: 34.715858ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:40:34.027779Z","caller":"traceutil/trace.go:171","msg":"trace[716676005] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"222.842294ms","start":"2026-05-05T15:40:33.804897Z","end":"2026-05-05T15:40:34.027739Z","steps":["trace[716676005] 'process raft request' (duration: 188.110065ms)","trace[716676005] 'compare' (duration: 34.269858ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T15:40:34.027849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.038673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T15:40:34.02793Z","caller":"traceutil/trace.go:171","msg":"trace[2077316110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4896; }","duration":"211.168496ms","start":"2026-05-05T15:40:33.816744Z","end":"2026-05-05T15:40:34.027912Z","steps":["trace[2077316110] 'agreement among raft nodes before linearized reading' (duration: 211.015812ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:40:35.928306Z","caller":"traceutil/trace.go:171","msg":"trace[1982210262] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"100.23742ms","start":"2026-05-05T15:40:35.82805Z","end":"2026-05-05T15:40:35.928287Z","steps":["trace[1982210262] 'process raft request' (duration: 99.300178ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:40:43.923804Z","caller":"traceutil/trace.go:171","msg":"trace[1206099269] linearizableReadLoop","detail":"{readStateIndex:5288; appliedIndex:5287; }","duration":"106.368066ms","start":"2026-05-05T15:40:43.817416Z","end":"2026-05-05T15:40:43.923784Z","steps":["trace[1206099269] 'read index received' (duration: 106.322425ms)","trace[1206099269] 'applied index is now lower than readState.Index' (duration: 44.811µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:40:43.923906Z","caller":"traceutil/trace.go:171","msg":"trace[435617350] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"108.720543ms","start":"2026-05-05T15:40:43.815164Z","end":"2026-05-05T15:40:43.923885Z","steps":["trace[435617350] 'process raft request' (duration: 108.472047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:40:43.923947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.51936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T15:40:43.923992Z","caller":"traceutil/trace.go:171","msg":"trace[955293259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5096; }","duration":"106.569091ms","start":"2026-05-05T15:40:43.817404Z","end":"2026-05-05T15:40:43.923973Z","steps":["trace[955293259] 'agreement among raft nodes before linearized reading' (duration: 106.479149ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:40:43.94545Z","caller":"traceutil/trace.go:171","msg":"trace[911312350] transaction","detail":"{read_only:false; response_revision:5097; number_of_response:1; }","duration":"125.023062ms","start":"2026-05-05T15:40:43.820402Z","end":"2026-05-05T15:40:43.945425Z","steps":["trace[911312350] 'process raft request' (duration: 124.535621ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:40:51.953139Z","caller":"traceutil/trace.go:171","msg":"trace[1699161716] linearizableReadLoop","detail":"{readStateIndex:5469; appliedIndex:5468; }","duration":"145.18442ms","start":"2026-05-05T15:40:51.807916Z","end":"2026-05-05T15:40:51.9531Z","steps":["trace[1699161716] 'read index received' (duration: 91.970602ms)","trace[1699161716] 'applied index is now lower than readState.Index' (duration: 53.192508ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T15:40:51.953262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.322525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-09317c7b-3d6b-4123-8bab-b856ee3a9394\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-05T15:40:51.953344Z","caller":"traceutil/trace.go:171","msg":"trace[2037303325] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-09317c7b-3d6b-4123-8bab-b856ee3a9394; range_end:; response_count:1; response_revision:5272; }","duration":"145.450668ms","start":"2026-05-05T15:40:51.807875Z","end":"2026-05-05T15:40:51.953326Z","steps":["trace[2037303325] 'agreement among raft nodes before linearized reading' (duration: 145.208681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:40:51.953409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.208338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T15:40:51.9535Z","caller":"traceutil/trace.go:171","msg":"trace[701053729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5272; }","duration":"136.33482ms","start":"2026-05-05T15:40:51.817144Z","end":"2026-05-05T15:40:51.953479Z","steps":["trace[701053729] 'agreement among raft nodes before linearized reading' (duration: 136.175217ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:40:52.164449Z","caller":"traceutil/trace.go:171","msg":"trace[592877758] linearizableReadLoop","detail":"{readStateIndex:5471; appliedIndex:5470; }","duration":"157.459894ms","start":"2026-05-05T15:40:52.006946Z","end":"2026-05-05T15:40:52.164406Z","steps":["trace[592877758] 'read index received' (duration: 157.29076ms)","trace[592877758] 'applied index is now lower than readState.Index' (duration: 168.314µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:40:52.16452Z","caller":"traceutil/trace.go:171","msg":"trace[1289117508] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"202.853476ms","start":"2026-05-05T15:40:51.961629Z","end":"2026-05-05T15:40:52.164482Z","steps":["trace[1289117508] 'process raft request' (duration: 202.635701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:40:52.164679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.266169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-05T15:40:52.164758Z","caller":"traceutil/trace.go:171","msg":"trace[1078979388] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5274; }","duration":"157.418413ms","start":"2026-05-05T15:40:52.00732Z","end":"2026-05-05T15:40:52.164739Z","steps":["trace[1078979388] 'agreement among raft nodes before linearized reading' (duration: 157.233369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:40:52.1648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.843333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-05T15:40:52.164847Z","caller":"traceutil/trace.go:171","msg":"trace[93273] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5274; }","duration":"157.930035ms","start":"2026-05-05T15:40:52.006904Z","end":"2026-05-05T15:40:52.164834Z","steps":["trace[93273] 'agreement among raft nodes before linearized reading' (duration: 157.793242ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:40:52.308161Z","caller":"traceutil/trace.go:171","msg":"trace[912001777] linearizableReadLoop","detail":"{readStateIndex:5472; appliedIndex:5471; }","duration":"138.963913ms","start":"2026-05-05T15:40:52.169173Z","end":"2026-05-05T15:40:52.308137Z","steps":["trace[912001777] 'read index received' (duration: 112.812269ms)","trace[912001777] 'applied index is now lower than readState.Index' (duration: 26.150494ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:40:52.308227Z","caller":"traceutil/trace.go:171","msg":"trace[1944061127] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"139.142797ms","start":"2026-05-05T15:40:52.169056Z","end":"2026-05-05T15:40:52.308199Z","steps":["trace[1944061127] 'process raft request' (duration: 112.897302ms)","trace[1944061127] 'compare' (duration: 26.089262ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T15:40:52.308368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.119276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T15:40:52.308435Z","caller":"traceutil/trace.go:171","msg":"trace[1843000582] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:5275; }","duration":"139.252379ms","start":"2026-05-05T15:40:52.16915Z","end":"2026-05-05T15:40:52.308403Z","steps":["trace[1843000582] 'agreement among raft nodes before linearized reading' (duration: 139.098226ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:40:52.308626Z","caller":"traceutil/trace.go:171","msg":"trace[280386955] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"138.126193ms","start":"2026-05-05T15:40:52.17047Z","end":"2026-05-05T15:40:52.308597Z","steps":["trace[280386955] 'process raft request' (duration: 137.880627ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:40:52.30887Z","caller":"traceutil/trace.go:171","msg":"trace[1813516898] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"137.479548ms","start":"2026-05-05T15:40:52.171374Z","end":"2026-05-05T15:40:52.308854Z","steps":["trace[1813516898] 'process raft request' (duration: 137.13616ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:40:52.308877Z","caller":"traceutil/trace.go:171","msg":"trace[291929281] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"134.796484ms","start":"2026-05-05T15:40:52.174067Z","end":"2026-05-05T15:40:52.308863Z","steps":["trace[291929281] 'process raft request' (duration: 134.717482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:40:52.312856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.616771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18418"} {"level":"info","ts":"2026-05-05T15:40:52.31295Z","caller":"traceutil/trace.go:171","msg":"trace[849457954] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5279; }","duration":"142.749503ms","start":"2026-05-05T15:40:52.170174Z","end":"2026-05-05T15:40:52.312923Z","steps":["trace[849457954] 'agreement among raft nodes before linearized reading' (duration: 142.510548ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:41:03.237897Z","caller":"traceutil/trace.go:171","msg":"trace[137712994] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"140.349966ms","start":"2026-05-05T15:41:03.097509Z","end":"2026-05-05T15:41:03.237859Z","steps":["trace[137712994] 'process raft request' (duration: 63.561445ms)","trace[137712994] 'compare' (duration: 76.664039ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:42:38.65487Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3014} {"level":"info","ts":"2026-05-05T15:42:38.740323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3014,"took":"83.749701ms","hash":2172494253,"current-db-size-bytes":58273792,"current-db-size":"58 MB","current-db-size-in-use-bytes":32362496,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-05T15:42:38.740399Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2172494253,"revision":3014,"compact-revision":-1} {"level":"info","ts":"2026-05-05T15:47:07.140131Z","caller":"traceutil/trace.go:171","msg":"trace[464019413] transaction","detail":"{read_only:false; response_revision:8141; number_of_response:1; }","duration":"185.75818ms","start":"2026-05-05T15:47:06.954345Z","end":"2026-05-05T15:47:07.140103Z","steps":["trace[464019413] 'process raft request' (duration: 185.599206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:47:07.328704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.269338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T15:47:07.328942Z","caller":"traceutil/trace.go:171","msg":"trace[1922993487] transaction","detail":"{read_only:false; response_revision:8142; number_of_response:1; }","duration":"373.781426ms","start":"2026-05-05T15:47:06.955134Z","end":"2026-05-05T15:47:07.328915Z","steps":["trace[1922993487] 'process raft request' (duration: 252.797831ms)","trace[1922993487] 'compare' (duration: 119.845478ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T15:47:07.329068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T15:47:06.955103Z","time spent":"373.894988ms","remote":"127.0.0.1:35220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T15:47:07.329634Z","caller":"traceutil/trace.go:171","msg":"trace[1167135322] transaction","detail":"{read_only:false; response_revision:8143; number_of_response:1; }","duration":"295.228927ms","start":"2026-05-05T15:47:07.034382Z","end":"2026-05-05T15:47:07.32961Z","steps":["trace[1167135322] 'process raft request' (duration: 294.702704ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:47:08.173062Z","caller":"traceutil/trace.go:171","msg":"trace[996755261] transaction","detail":"{read_only:false; response_revision:8146; number_of_response:1; }","duration":"229.411125ms","start":"2026-05-05T15:47:07.943627Z","end":"2026-05-05T15:47:08.173038Z","steps":["trace[996755261] 'process raft request' (duration: 229.275002ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:47:08.236579Z","caller":"traceutil/trace.go:171","msg":"trace[1812713491] linearizableReadLoop","detail":"{readStateIndex:8435; appliedIndex:8432; }","duration":"162.462207ms","start":"2026-05-05T15:47:08.074093Z","end":"2026-05-05T15:47:08.236555Z","steps":["trace[1812713491] 'read index received' (duration: 98.793369ms)","trace[1812713491] 'applied index is now lower than readState.Index' (duration: 63.667908ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:47:08.236638Z","caller":"traceutil/trace.go:171","msg":"trace[1641618527] transaction","detail":"{read_only:false; response_revision:8147; number_of_response:1; }","duration":"275.10924ms","start":"2026-05-05T15:47:07.961493Z","end":"2026-05-05T15:47:08.236602Z","steps":["trace[1641618527] 'process raft request' (duration: 274.820233ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:47:08.236671Z","caller":"traceutil/trace.go:171","msg":"trace[1266117548] transaction","detail":"{read_only:false; response_revision:8148; number_of_response:1; }","duration":"267.858264ms","start":"2026-05-05T15:47:07.968783Z","end":"2026-05-05T15:47:08.236641Z","steps":["trace[1266117548] 'process raft request' (duration: 267.728131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:47:08.236856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.705093ms","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-05-05T15:47:08.236985Z","caller":"traceutil/trace.go:171","msg":"trace[1345385867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8148; }","duration":"162.925419ms","start":"2026-05-05T15:47:08.074039Z","end":"2026-05-05T15:47:08.236964Z","steps":["trace[1345385867] 'agreement among raft nodes before linearized reading' (duration: 162.626431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:47:33.881097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.749925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/rook-ceph/csi-nfsplugin-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T15:47:33.881194Z","caller":"traceutil/trace.go:171","msg":"trace[310095887] range","detail":"{range_begin:/registry/services/specs/rook-ceph/csi-nfsplugin-metrics; range_end:; response_count:0; response_revision:8340; }","duration":"100.888409ms","start":"2026-05-05T15:47:33.780286Z","end":"2026-05-05T15:47:33.881175Z","steps":["trace[310095887] 'range keys from in-memory index tree' (duration: 100.669743ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:47:37.11767Z","caller":"traceutil/trace.go:171","msg":"trace[1762922050] transaction","detail":"{read_only:false; response_revision:8356; number_of_response:1; }","duration":"232.113771ms","start":"2026-05-05T15:47:36.885524Z","end":"2026-05-05T15:47:37.117638Z","steps":["trace[1762922050] 'process raft request' (duration: 231.924037ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:47:37.341622Z","caller":"traceutil/trace.go:171","msg":"trace[183147303] transaction","detail":"{read_only:false; response_revision:8357; number_of_response:1; }","duration":"219.200209ms","start":"2026-05-05T15:47:37.122386Z","end":"2026-05-05T15:47:37.341587Z","steps":["trace[183147303] 'process raft request' (duration: 204.281228ms)","trace[183147303] 'compare' (duration: 14.556602ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:47:38.709206Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6193} {"level":"info","ts":"2026-05-05T15:47:38.999937Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6193,"took":"288.741929ms","hash":1388190724,"current-db-size-bytes":58273792,"current-db-size":"58 MB","current-db-size-in-use-bytes":23273472,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-05T15:47:38.999992Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1388190724,"revision":6193,"compact-revision":3014} {"level":"info","ts":"2026-05-05T15:47:49.397482Z","caller":"traceutil/trace.go:171","msg":"trace[1876984878] linearizableReadLoop","detail":"{readStateIndex:8763; appliedIndex:8761; }","duration":"120.736468ms","start":"2026-05-05T15:47:49.276712Z","end":"2026-05-05T15:47:49.397448Z","steps":["trace[1876984878] 'read index received' (duration: 14.649075ms)","trace[1876984878] 'applied index is now lower than readState.Index' (duration: 106.086193ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:47:49.397614Z","caller":"traceutil/trace.go:171","msg":"trace[227247565] transaction","detail":"{read_only:false; response_revision:8463; number_of_response:1; }","duration":"130.469384ms","start":"2026-05-05T15:47:49.26709Z","end":"2026-05-05T15:47:49.39756Z","steps":["trace[227247565] 'process raft request' (duration: 130.143555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:47:49.397803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.007286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T15:47:49.397926Z","caller":"traceutil/trace.go:171","msg":"trace[1680660378] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:8463; }","duration":"121.249172ms","start":"2026-05-05T15:47:49.276654Z","end":"2026-05-05T15:47:49.397903Z","steps":["trace[1680660378] 'agreement among raft nodes before linearized reading' (duration: 120.931355ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:47:49.928253Z","caller":"traceutil/trace.go:171","msg":"trace[1897354517] transaction","detail":"{read_only:false; response_revision:8469; number_of_response:1; }","duration":"161.839433ms","start":"2026-05-05T15:47:49.766318Z","end":"2026-05-05T15:47:49.928157Z","steps":["trace[1897354517] 'process raft request' (duration: 85.476387ms)","trace[1897354517] 'compare' (duration: 76.244513ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:47:50.83155Z","caller":"traceutil/trace.go:171","msg":"trace[1026226727] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"223.711567ms","start":"2026-05-05T15:47:50.607796Z","end":"2026-05-05T15:47:50.831508Z","steps":["trace[1026226727] 'process raft request' (duration: 223.507852ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:48:40.741786Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002457.wal"} {"level":"info","ts":"2026-05-05T15:49:05.095157Z","caller":"traceutil/trace.go:171","msg":"trace[1632617625] transaction","detail":"{read_only:false; response_revision:9212; number_of_response:1; }","duration":"225.645604ms","start":"2026-05-05T15:49:04.869478Z","end":"2026-05-05T15:49:05.095124Z","steps":["trace[1632617625] 'process raft request' (duration: 225.364377ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:50:10.094507Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T15:50:10.100623Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T15:50:10.100714Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T15:52:38.714831Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8367} {"level":"info","ts":"2026-05-05T15:52:38.796606Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8367,"took":"78.292304ms","hash":561105387,"current-db-size-bytes":58273792,"current-db-size":"58 MB","current-db-size-in-use-bytes":26619904,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-05T15:52:38.796666Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":561105387,"revision":8367,"compact-revision":6193} {"level":"info","ts":"2026-05-05T15:52:49.036896Z","caller":"traceutil/trace.go:171","msg":"trace[2081642806] linearizableReadLoop","detail":"{readStateIndex:11435; appliedIndex:11434; }","duration":"128.781593ms","start":"2026-05-05T15:52:48.908084Z","end":"2026-05-05T15:52:49.036866Z","steps":["trace[2081642806] 'read index received' (duration: 128.511847ms)","trace[2081642806] 'applied index is now lower than readState.Index' (duration: 268.756µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T15:52:49.037054Z","caller":"traceutil/trace.go:171","msg":"trace[443604253] transaction","detail":"{read_only:false; response_revision:11061; number_of_response:1; }","duration":"152.565982ms","start":"2026-05-05T15:52:48.884449Z","end":"2026-05-05T15:52:49.037015Z","steps":["trace[443604253] 'process raft request' (duration: 152.227264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T15:52:49.037114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.991778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T15:52:49.037175Z","caller":"traceutil/trace.go:171","msg":"trace[400720665] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:11061; }","duration":"129.104961ms","start":"2026-05-05T15:52:48.908053Z","end":"2026-05-05T15:52:49.037158Z","steps":["trace[400720665] 'agreement among raft nodes before linearized reading' (duration: 128.953118ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T15:57:38.721748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11001} {"level":"info","ts":"2026-05-05T15:57:38.805727Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11001,"took":"80.675966ms","hash":2313949051,"current-db-size-bytes":58273792,"current-db-size":"58 MB","current-db-size-in-use-bytes":28303360,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T15:57:38.805788Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2313949051,"revision":11001,"compact-revision":8367} {"level":"info","ts":"2026-05-05T16:02:38.727276Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13044} {"level":"info","ts":"2026-05-05T16:02:38.797878Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13044,"took":"68.194705ms","hash":541540711,"current-db-size-bytes":58273792,"current-db-size":"58 MB","current-db-size-in-use-bytes":24219648,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-05T16:02:38.797949Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":541540711,"revision":13044,"compact-revision":11001}