{"level":"warn","ts":"2026-04-08T00:27:49.039816Z","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-08T00:27:49.040045Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-08T00:27:49.04013Z","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-08T00:27:49.040138Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-04-08T00:27:49.040155Z","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-08T00:27:49.040936Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-04-08T00:27:49.04132Z","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":8,"max-cpu-available":8,"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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-08T00:27:49.046183Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.360638ms"} {"level":"info","ts":"2026-04-08T00:27:49.056223Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-04-08T00:27:49.056338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T00:27:49.056382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-04-08T00:27:49.05642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T00:27:49.056455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-04-08T00:27:49.056539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-04-08T00:27:49.062168Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T00:27:49.063825Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T00:27:49.065019Z","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-08T00:27:49.06771Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T00:27:49.067977Z","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-08T00:27:49.068151Z","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-08T00:27:49.068175Z","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-08T00:27:49.068259Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T00:27:49.069071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-04-08T00:27:49.069332Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-04-08T00:27:49.07415Z","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-08T00:27:49.074299Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-04-08T00:27:49.074328Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-04-08T00:27:49.074639Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T00:27:49.074722Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T00:27:49.257826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T00:27:49.257902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T00:27:49.257976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-04-08T00:27:49.25801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-04-08T00:27:49.258101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-04-08T00:27:49.258139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-04-08T00:27:49.258161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-04-08T00:27:49.259022Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:27:49.259477Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T00:27:49.259716Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:27:49.260245Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:27:49.26056Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T00:27:49.260624Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T00:27:49.263451Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-04-08T00:27:49.264095Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:27:49.264267Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:27:49.264525Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:27:49.264966Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-08T00:28:18.286985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.024926ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-08T00:28:20.331631Z","caller":"traceutil/trace.go:171","msg":"trace[1626466655] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"163.931254ms","start":"2026-04-08T00:28:20.167675Z","end":"2026-04-08T00:28:20.331607Z","steps":["trace[1626466655] 'process raft request' (duration: 163.802153ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:20.341108Z","caller":"traceutil/trace.go:171","msg":"trace[2077619243] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"171.558212ms","start":"2026-04-08T00:28:20.169531Z","end":"2026-04-08T00:28:20.341089Z","steps":["trace[2077619243] 'process raft request' (duration: 171.396062ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:20.341218Z","caller":"traceutil/trace.go:171","msg":"trace[2108863798] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"162.860136ms","start":"2026-04-08T00:28:20.178351Z","end":"2026-04-08T00:28:20.341212Z","steps":["trace[2108863798] 'process raft request' (duration: 162.669875ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:20.341405Z","caller":"traceutil/trace.go:171","msg":"trace[1079444591] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"162.967697ms","start":"2026-04-08T00:28:20.17843Z","end":"2026-04-08T00:28:20.341398Z","steps":["trace[1079444591] 'process raft request' (duration: 162.670636ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:20.341696Z","caller":"traceutil/trace.go:171","msg":"trace[1317251089] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"161.518208ms","start":"2026-04-08T00:28:20.180151Z","end":"2026-04-08T00:28:20.341669Z","steps":["trace[1317251089] 'process raft request' (duration: 161.270026ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:20.341813Z","caller":"traceutil/trace.go:171","msg":"trace[335353708] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"146.834764ms","start":"2026-04-08T00:28:20.194971Z","end":"2026-04-08T00:28:20.341806Z","steps":["trace[335353708] 'process raft request' (duration: 146.688304ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:20.341776Z","caller":"traceutil/trace.go:171","msg":"trace[954753052] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"157.903325ms","start":"2026-04-08T00:28:20.183821Z","end":"2026-04-08T00:28:20.341724Z","steps":["trace[954753052] 'process raft request' (duration: 157.736184ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:20.341989Z","caller":"traceutil/trace.go:171","msg":"trace[1336934622] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"141.841453ms","start":"2026-04-08T00:28:20.200135Z","end":"2026-04-08T00:28:20.341976Z","steps":["trace[1336934622] 'process raft request' (duration: 141.583271ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:20.342071Z","caller":"traceutil/trace.go:171","msg":"trace[1651112024] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"120.351737ms","start":"2026-04-08T00:28:20.221712Z","end":"2026-04-08T00:28:20.342064Z","steps":["trace[1651112024] 'process raft request' (duration: 120.093644ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:20.342417Z","caller":"traceutil/trace.go:171","msg":"trace[216752029] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"118.784307ms","start":"2026-04-08T00:28:20.223597Z","end":"2026-04-08T00:28:20.342382Z","steps":["trace[216752029] 'process raft request' (duration: 118.569296ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:20.342558Z","caller":"traceutil/trace.go:171","msg":"trace[1396417749] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"105.20899ms","start":"2026-04-08T00:28:20.237343Z","end":"2026-04-08T00:28:20.342552Z","steps":["trace[1396417749] 'process raft request' (duration: 104.981268ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:22.037403Z","caller":"traceutil/trace.go:171","msg":"trace[1308216948] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"133.538372ms","start":"2026-04-08T00:28:21.903841Z","end":"2026-04-08T00:28:22.037379Z","steps":["trace[1308216948] 'process raft request' (duration: 133.366421ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:26.983045Z","caller":"traceutil/trace.go:171","msg":"trace[1479476] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"256.97822ms","start":"2026-04-08T00:28:26.726041Z","end":"2026-04-08T00:28:26.98302Z","steps":["trace[1479476] 'process raft request' (duration: 256.479657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:27.792597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.366585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:28:27.793324Z","caller":"traceutil/trace.go:171","msg":"trace[993159534] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:639; }","duration":"614.420781ms","start":"2026-04-08T00:28:27.178836Z","end":"2026-04-08T00:28:27.793257Z","steps":["trace[993159534] 'read index received' (duration: 11.820961ms)","trace[993159534] 'applied index is now lower than readState.Index' (duration: 602.354778ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:28:27.793502Z","caller":"traceutil/trace.go:171","msg":"trace[1260410813] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"743.226893ms","start":"2026-04-08T00:28:27.050209Z","end":"2026-04-08T00:28:27.793436Z","steps":["trace[1260410813] 'process raft request' (duration: 742.513039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:27.793651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:28:27.050187Z","time spent":"743.402134ms","remote":"127.0.0.1:47650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:28:27.794209Z","caller":"traceutil/trace.go:171","msg":"trace[1525606478] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.056263893s","start":"2026-04-08T00:28:26.737871Z","end":"2026-04-08T00:28:27.794135Z","steps":["trace[1525606478] 'process raft request' (duration: 304.9756ms)","trace[1525606478] 'compare' (duration: 747.908483ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:28:27.794348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.838501ms","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-04-08T00:28:27.794426Z","caller":"traceutil/trace.go:171","msg":"trace[556414735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:620; }","duration":"467.925942ms","start":"2026-04-08T00:28:27.326468Z","end":"2026-04-08T00:28:27.794394Z","steps":["trace[556414735] 'agreement among raft nodes before linearized reading' (duration: 467.331368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:27.794472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:28:27.326455Z","time spent":"468.006512ms","remote":"127.0.0.1:47452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T00:28:27.794698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:28:26.737828Z","time spent":"1.056525544s","remote":"127.0.0.1:47398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:28:27.794938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.258389ms","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-04-08T00:28:27.795005Z","caller":"traceutil/trace.go:171","msg":"trace[1001294220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"319.33986ms","start":"2026-04-08T00:28:27.475635Z","end":"2026-04-08T00:28:27.794975Z","steps":["trace[1001294220] 'agreement among raft nodes before linearized reading' (duration: 318.500594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:27.795025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.185302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-08T00:28:27.795046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:28:27.475624Z","time spent":"319.41042ms","remote":"127.0.0.1:47452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T00:28:27.795076Z","caller":"traceutil/trace.go:171","msg":"trace[1546961150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"616.257972ms","start":"2026-04-08T00:28:27.178798Z","end":"2026-04-08T00:28:27.795056Z","steps":["trace[1546961150] 'agreement among raft nodes before linearized reading' (duration: 614.859924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:27.795108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:28:27.178784Z","time spent":"616.318262ms","remote":"127.0.0.1:47452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T00:28:27.795092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.076806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:28:27.795331Z","caller":"traceutil/trace.go:171","msg":"trace[1438140745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"396.338227ms","start":"2026-04-08T00:28:27.398929Z","end":"2026-04-08T00:28:27.795267Z","steps":["trace[1438140745] 'agreement among raft nodes before linearized reading' (duration: 395.731033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:27.795458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:28:27.398918Z","time spent":"396.522938ms","remote":"127.0.0.1:47214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T00:28:33.881487Z","caller":"traceutil/trace.go:171","msg":"trace[243791415] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"104.568854ms","start":"2026-04-08T00:28:33.776876Z","end":"2026-04-08T00:28:33.881445Z","steps":["trace[243791415] 'process raft request' (duration: 102.686732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:44.257615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.861216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-08T00:28:44.257669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.336209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2w8vw\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-08T00:28:44.257728Z","caller":"traceutil/trace.go:171","msg":"trace[961517331] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:785; }","duration":"313.033317ms","start":"2026-04-08T00:28:43.944664Z","end":"2026-04-08T00:28:44.257698Z","steps":["trace[961517331] 'range keys from in-memory index tree' (duration: 312.647236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:44.257756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.297556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-08T00:28:44.257669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.529995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-08T00:28:44.257792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:28:43.944643Z","time spent":"313.133947ms","remote":"127.0.0.1:47452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T00:28:44.257829Z","caller":"traceutil/trace.go:171","msg":"trace[1216518839] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"237.764486ms","start":"2026-04-08T00:28:44.020053Z","end":"2026-04-08T00:28:44.257817Z","steps":["trace[1216518839] 'range keys from in-memory index tree' (duration: 237.367194ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:44.257882Z","caller":"traceutil/trace.go:171","msg":"trace[2013273368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:785; }","duration":"243.408027ms","start":"2026-04-08T00:28:44.014407Z","end":"2026-04-08T00:28:44.257815Z","steps":["trace[2013273368] 'range keys from in-memory index tree' (duration: 243.137836ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:44.257777Z","caller":"traceutil/trace.go:171","msg":"trace[264696838] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2w8vw; range_end:; response_count:1; response_revision:785; }","duration":"454.514369ms","start":"2026-04-08T00:28:43.803236Z","end":"2026-04-08T00:28:44.25775Z","steps":["trace[264696838] 'range keys from in-memory index tree' (duration: 454.121047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:44.25798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:28:43.80321Z","time spent":"454.73063ms","remote":"127.0.0.1:47398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2w8vw\" "} {"level":"info","ts":"2026-04-08T00:29:23.297286Z","caller":"traceutil/trace.go:171","msg":"trace[1892326442] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"120.474138ms","start":"2026-04-08T00:29:23.176792Z","end":"2026-04-08T00:29:23.297267Z","steps":["trace[1892326442] 'process raft request' (duration: 120.360305ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:33.317016Z","caller":"traceutil/trace.go:171","msg":"trace[612669858] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"202.724125ms","start":"2026-04-08T00:29:33.114267Z","end":"2026-04-08T00:29:33.316992Z","steps":["trace[612669858] 'process raft request' (duration: 202.566321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:29:56.741655Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853969668939959928,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T00:29:56.742449Z","caller":"traceutil/trace.go:171","msg":"trace[391402127] linearizableReadLoop","detail":"{readStateIndex:1901; appliedIndex:1900; }","duration":"501.503189ms","start":"2026-04-08T00:29:56.240921Z","end":"2026-04-08T00:29:56.742424Z","steps":["trace[391402127] 'read index received' (duration: 501.152624ms)","trace[391402127] 'applied index is now lower than readState.Index' (duration: 349.485µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:29:56.742574Z","caller":"traceutil/trace.go:171","msg":"trace[1758858568] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"519.220955ms","start":"2026-04-08T00:29:56.223342Z","end":"2026-04-08T00:29:56.742563Z","steps":["trace[1758858568] 'process raft request' (duration: 518.872608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:29:56.742725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:29:56.223316Z","time spent":"519.304857ms","remote":"127.0.0.1:47398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10929,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:29:56.742916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.887622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:29:56.742893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.931907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:29:56.742962Z","caller":"traceutil/trace.go:171","msg":"trace[283112853] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1832; }","duration":"150.993124ms","start":"2026-04-08T00:29:56.591956Z","end":"2026-04-08T00:29:56.742949Z","steps":["trace[283112853] 'agreement among raft nodes before linearized reading' (duration: 150.923773ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:56.742998Z","caller":"traceutil/trace.go:171","msg":"trace[271104624] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1832; }","duration":"502.10111ms","start":"2026-04-08T00:29:56.240865Z","end":"2026-04-08T00:29:56.742966Z","steps":["trace[271104624] 'agreement among raft nodes before linearized reading' (duration: 501.883387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:29:56.743082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:29:56.240846Z","time spent":"502.215432ms","remote":"127.0.0.1:52036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":16,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-04-08T00:29:56.743135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.992689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-08T00:29:56.743177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.545057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:29:56.743143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.660815ms","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-08T00:29:56.743192Z","caller":"traceutil/trace.go:171","msg":"trace[814983578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1832; }","duration":"102.110651ms","start":"2026-04-08T00:29:56.641068Z","end":"2026-04-08T00:29:56.743179Z","steps":["trace[814983578] 'agreement among raft nodes before linearized reading' (duration: 101.897247ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:56.743213Z","caller":"traceutil/trace.go:171","msg":"trace[278171185] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1832; }","duration":"143.631647ms","start":"2026-04-08T00:29:56.599571Z","end":"2026-04-08T00:29:56.743203Z","steps":["trace[278171185] 'agreement among raft nodes before linearized reading' (duration: 143.576146ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:56.743246Z","caller":"traceutil/trace.go:171","msg":"trace[1162105956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1832; }","duration":"115.864319ms","start":"2026-04-08T00:29:56.627358Z","end":"2026-04-08T00:29:56.743223Z","steps":["trace[1162105956] 'agreement among raft nodes before linearized reading' (duration: 115.511393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:29:56.743483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.581654ms","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-04-08T00:29:56.743522Z","caller":"traceutil/trace.go:171","msg":"trace[170189706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1832; }","duration":"111.639365ms","start":"2026-04-08T00:29:56.63187Z","end":"2026-04-08T00:29:56.743509Z","steps":["trace[170189706] 'agreement among raft nodes before linearized reading' (duration: 111.537754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:29:56.743508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.132671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-08T00:29:56.743615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.349862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:29:56.743667Z","caller":"traceutil/trace.go:171","msg":"trace[274872357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1832; }","duration":"343.458944ms","start":"2026-04-08T00:29:56.400196Z","end":"2026-04-08T00:29:56.743655Z","steps":["trace[274872357] 'agreement among raft nodes before linearized reading' (duration: 342.64596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:29:56.743699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:29:56.400173Z","time spent":"343.518554ms","remote":"127.0.0.1:47214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T00:29:56.743592Z","caller":"traceutil/trace.go:171","msg":"trace[562035423] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1832; }","duration":"273.250112ms","start":"2026-04-08T00:29:56.470323Z","end":"2026-04-08T00:29:56.743573Z","steps":["trace[562035423] 'agreement among raft nodes before linearized reading' (duration: 273.058579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:29:56.743836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.09932ms","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-04-08T00:29:56.743946Z","caller":"traceutil/trace.go:171","msg":"trace[1629919990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1832; }","duration":"106.581379ms","start":"2026-04-08T00:29:56.637343Z","end":"2026-04-08T00:29:56.743925Z","steps":["trace[1629919990] 'agreement among raft nodes before linearized reading' (duration: 105.548931ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:56.868614Z","caller":"traceutil/trace.go:171","msg":"trace[1670014087] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"118.126418ms","start":"2026-04-08T00:29:56.75046Z","end":"2026-04-08T00:29:56.868586Z","steps":["trace[1670014087] 'process raft request' (duration: 109.207054ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:56.868713Z","caller":"traceutil/trace.go:171","msg":"trace[1538602741] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"117.429385ms","start":"2026-04-08T00:29:56.751256Z","end":"2026-04-08T00:29:56.868686Z","steps":["trace[1538602741] 'process raft request' (duration: 117.287153ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:56.868716Z","caller":"traceutil/trace.go:171","msg":"trace[853716910] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"117.863224ms","start":"2026-04-08T00:29:56.750818Z","end":"2026-04-08T00:29:56.868682Z","steps":["trace[853716910] 'process raft request' (duration: 117.647959ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:56.868795Z","caller":"traceutil/trace.go:171","msg":"trace[187984692] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"116.315966ms","start":"2026-04-08T00:29:56.752444Z","end":"2026-04-08T00:29:56.86876Z","steps":["trace[187984692] 'process raft request' (duration: 116.266345ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:56.869081Z","caller":"traceutil/trace.go:171","msg":"trace[1632077067] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"117.09817ms","start":"2026-04-08T00:29:56.75197Z","end":"2026-04-08T00:29:56.869068Z","steps":["trace[1632077067] 'process raft request' (duration: 116.666313ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:56.869141Z","caller":"traceutil/trace.go:171","msg":"trace[235284006] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"117.271553ms","start":"2026-04-08T00:29:56.751855Z","end":"2026-04-08T00:29:56.869127Z","steps":["trace[235284006] 'process raft request' (duration: 116.739304ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:56.869181Z","caller":"traceutil/trace.go:171","msg":"trace[1987949006] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"117.190952ms","start":"2026-04-08T00:29:56.751976Z","end":"2026-04-08T00:29:56.869167Z","steps":["trace[1987949006] 'process raft request' (duration: 116.697874ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:57.261686Z","caller":"traceutil/trace.go:171","msg":"trace[965934909] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"123.217078ms","start":"2026-04-08T00:29:57.138437Z","end":"2026-04-08T00:29:57.261654Z","steps":["trace[965934909] 'process raft request' (duration: 123.042775ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:30:09.045295Z","caller":"traceutil/trace.go:171","msg":"trace[1730263151] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"113.713117ms","start":"2026-04-08T00:30:08.93156Z","end":"2026-04-08T00:30:09.045273Z","steps":["trace[1730263151] 'process raft request' (duration: 113.591195ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:30:09.062597Z","caller":"traceutil/trace.go:171","msg":"trace[1183838659] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"130.256285ms","start":"2026-04-08T00:30:08.932314Z","end":"2026-04-08T00:30:09.06257Z","steps":["trace[1183838659] 'process raft request' (duration: 130.040971ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:30:09.062822Z","caller":"traceutil/trace.go:171","msg":"trace[678133212] linearizableReadLoop","detail":"{readStateIndex:2015; appliedIndex:2013; }","duration":"123.253064ms","start":"2026-04-08T00:30:08.939487Z","end":"2026-04-08T00:30:09.06274Z","steps":["trace[678133212] 'read index received' (duration: 105.929624ms)","trace[678133212] 'applied index is now lower than readState.Index' (duration: 17.32205ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:30:09.063045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.536769ms","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-04-08T00:30:09.063089Z","caller":"traceutil/trace.go:171","msg":"trace[1055271137] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1943; }","duration":"123.61065ms","start":"2026-04-08T00:30:08.939466Z","end":"2026-04-08T00:30:09.063077Z","steps":["trace[1055271137] 'agreement among raft nodes before linearized reading' (duration: 123.431707ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:30:09.063196Z","caller":"traceutil/trace.go:171","msg":"trace[90622505] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"129.722856ms","start":"2026-04-08T00:30:08.933455Z","end":"2026-04-08T00:30:09.063178Z","steps":["trace[90622505] 'process raft request' (duration: 129.052396ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:30:12.563903Z","caller":"traceutil/trace.go:171","msg":"trace[1077922204] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"234.368465ms","start":"2026-04-08T00:30:12.329512Z","end":"2026-04-08T00:30:12.56388Z","steps":["trace[1077922204] 'process raft request' (duration: 233.939518ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:30:46.012484Z","caller":"traceutil/trace.go:171","msg":"trace[742986916] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"370.774782ms","start":"2026-04-08T00:30:45.641679Z","end":"2026-04-08T00:30:46.012454Z","steps":["trace[742986916] 'process raft request' (duration: 370.63373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:30:46.012823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:30:45.641665Z","time spent":"370.999304ms","remote":"127.0.0.1:47452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:30:46.25646Z","caller":"traceutil/trace.go:171","msg":"trace[1146231162] linearizableReadLoop","detail":"{readStateIndex:2234; appliedIndex:2233; }","duration":"461.455334ms","start":"2026-04-08T00:30:45.794976Z","end":"2026-04-08T00:30:46.256431Z","steps":["trace[1146231162] 'read index received' (duration: 217.571552ms)","trace[1146231162] 'applied index is now lower than readState.Index' (duration: 243.882542ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:30:46.256957Z","caller":"traceutil/trace.go:171","msg":"trace[1277963188] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"505.199674ms","start":"2026-04-08T00:30:45.751738Z","end":"2026-04-08T00:30:46.256938Z","steps":["trace[1277963188] 'process raft request' (duration: 477.056369ms)","trace[1277963188] 'compare' (duration: 27.218003ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:30:46.25715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:30:45.751718Z","time spent":"505.278066ms","remote":"127.0.0.1:47452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:30:46.257534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.529627ms","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-04-08T00:30:46.257613Z","caller":"traceutil/trace.go:171","msg":"trace[1989570785] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2155; }","duration":"462.66731ms","start":"2026-04-08T00:30:45.794928Z","end":"2026-04-08T00:30:46.257596Z","steps":["trace[1989570785] 'agreement among raft nodes before linearized reading' (duration: 461.608486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:30:46.257665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:30:45.794905Z","time spent":"462.751ms","remote":"127.0.0.1:47452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T00:30:46.606173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.218432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:30:46.60627Z","caller":"traceutil/trace.go:171","msg":"trace[869614169] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2234; }","duration":"258.047106ms","start":"2026-04-08T00:30:46.348207Z","end":"2026-04-08T00:30:46.606254Z","steps":["trace[869614169] 'read index received' (duration: 112.658832ms)","trace[869614169] 'applied index is now lower than readState.Index' (duration: 145.387304ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:30:46.606351Z","caller":"traceutil/trace.go:171","msg":"trace[2020696152] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"344.769169ms","start":"2026-04-08T00:30:46.261568Z","end":"2026-04-08T00:30:46.606337Z","steps":["trace[2020696152] 'process raft request' (duration: 199.302624ms)","trace[2020696152] 'compare' (duration: 144.919168ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:30:46.606402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:30:46.261547Z","time spent":"344.82681ms","remote":"127.0.0.1:47452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:30:46.606607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.40893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-08T00:30:46.606647Z","caller":"traceutil/trace.go:171","msg":"trace[73800666] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2156; }","duration":"258.479771ms","start":"2026-04-08T00:30:46.348157Z","end":"2026-04-08T00:30:46.606636Z","steps":["trace[73800666] 'agreement among raft nodes before linearized reading' (duration: 258.361209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:30:46.606717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.05871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:30:46.606772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.865049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T00:30:46.606808Z","caller":"traceutil/trace.go:171","msg":"trace[586017500] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2156; }","duration":"118.920499ms","start":"2026-04-08T00:30:46.48788Z","end":"2026-04-08T00:30:46.606801Z","steps":["trace[586017500] 'agreement among raft nodes before linearized reading' (duration: 118.833218ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:30:46.606967Z","caller":"traceutil/trace.go:171","msg":"trace[827638847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2156; }","duration":"207.278433ms","start":"2026-04-08T00:30:46.39952Z","end":"2026-04-08T00:30:46.606798Z","steps":["trace[827638847] 'agreement among raft nodes before linearized reading' (duration: 207.000529ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:32:08.12047Z","caller":"traceutil/trace.go:171","msg":"trace[1279102037] linearizableReadLoop","detail":"{readStateIndex:2801; appliedIndex:2800; }","duration":"239.327367ms","start":"2026-04-08T00:32:07.881125Z","end":"2026-04-08T00:32:08.120452Z","steps":["trace[1279102037] 'read index received' (duration: 177.976599ms)","trace[1279102037] 'applied index is now lower than readState.Index' (duration: 61.349778ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:32:08.120957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.813031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-04-08T00:32:08.121086Z","caller":"traceutil/trace.go:171","msg":"trace[1111938659] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2702; }","duration":"239.966763ms","start":"2026-04-08T00:32:07.881098Z","end":"2026-04-08T00:32:08.121065Z","steps":["trace[1111938659] 'agreement among raft nodes before linearized reading' (duration: 239.73076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:32:08.121471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.877054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:32:08.12152Z","caller":"traceutil/trace.go:171","msg":"trace[769454990] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2702; }","duration":"100.960894ms","start":"2026-04-08T00:32:08.020548Z","end":"2026-04-08T00:32:08.121509Z","steps":["trace[769454990] 'agreement among raft nodes before linearized reading' (duration: 100.084807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:32:08.858085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.609101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:32:08.858228Z","caller":"traceutil/trace.go:171","msg":"trace[2076327814] transaction","detail":"{read_only:false; response_revision:2705; number_of_response:1; }","duration":"281.379344ms","start":"2026-04-08T00:32:08.57682Z","end":"2026-04-08T00:32:08.858199Z","steps":["trace[2076327814] 'process raft request' (duration: 87.480779ms)","trace[2076327814] 'compare' (duration: 193.4933ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:35:50.900795Z","caller":"traceutil/trace.go:171","msg":"trace[755313417] transaction","detail":"{read_only:false; response_revision:4421; number_of_response:1; }","duration":"137.322803ms","start":"2026-04-08T00:35:50.76345Z","end":"2026-04-08T00:35:50.900773Z","steps":["trace[755313417] 'process raft request' (duration: 137.16682ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:50.947182Z","caller":"traceutil/trace.go:171","msg":"trace[419581372] linearizableReadLoop","detail":"{readStateIndex:4590; appliedIndex:4588; }","duration":"131.334235ms","start":"2026-04-08T00:35:50.815827Z","end":"2026-04-08T00:35:50.947161Z","steps":["trace[419581372] 'read index received' (duration: 84.81677ms)","trace[419581372] 'applied index is now lower than readState.Index' (duration: 46.516565ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:35:50.947253Z","caller":"traceutil/trace.go:171","msg":"trace[1935081349] transaction","detail":"{read_only:false; response_revision:4422; number_of_response:1; }","duration":"141.368772ms","start":"2026-04-08T00:35:50.805853Z","end":"2026-04-08T00:35:50.947222Z","steps":["trace[1935081349] 'process raft request' (duration: 124.380042ms)","trace[1935081349] 'compare' (duration: 16.818598ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:35:50.947302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.454886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:35:50.947345Z","caller":"traceutil/trace.go:171","msg":"trace[2119778606] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:4422; }","duration":"131.552778ms","start":"2026-04-08T00:35:50.815782Z","end":"2026-04-08T00:35:50.947335Z","steps":["trace[2119778606] 'agreement among raft nodes before linearized reading' (duration: 131.457947ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:51.751592Z","caller":"traceutil/trace.go:171","msg":"trace[1860456364] linearizableReadLoop","detail":"{readStateIndex:4634; appliedIndex:4633; }","duration":"103.865496ms","start":"2026-04-08T00:35:51.647703Z","end":"2026-04-08T00:35:51.751568Z","steps":["trace[1860456364] 'read index received' (duration: 60.342276ms)","trace[1860456364] 'applied index is now lower than readState.Index' (duration: 43.52213ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:35:51.751711Z","caller":"traceutil/trace.go:171","msg":"trace[932315834] transaction","detail":"{read_only:false; response_revision:4466; number_of_response:1; }","duration":"105.198265ms","start":"2026-04-08T00:35:51.646501Z","end":"2026-04-08T00:35:51.751699Z","steps":["trace[932315834] 'process raft request' (duration: 61.631935ms)","trace[932315834] 'compare' (duration: 43.300217ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:35:51.752153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.431334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-08T00:35:51.752194Z","caller":"traceutil/trace.go:171","msg":"trace[1750687783] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4466; }","duration":"104.481825ms","start":"2026-04-08T00:35:51.647699Z","end":"2026-04-08T00:35:51.752181Z","steps":["trace[1750687783] 'agreement among raft nodes before linearized reading' (duration: 104.337033ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:54.053484Z","caller":"traceutil/trace.go:171","msg":"trace[455175180] linearizableReadLoop","detail":"{readStateIndex:4727; appliedIndex:4726; }","duration":"118.316448ms","start":"2026-04-08T00:35:53.935147Z","end":"2026-04-08T00:35:54.053464Z","steps":["trace[455175180] 'read index received' (duration: 118.233427ms)","trace[455175180] 'applied index is now lower than readState.Index' (duration: 82.061µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:35:54.053641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.47395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-gshzx.18a43a91329b4be0\" ","response":"range_response_count:1 size:832"} {"level":"info","ts":"2026-04-08T00:35:54.053677Z","caller":"traceutil/trace.go:171","msg":"trace[1355812467] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-gshzx.18a43a91329b4be0; range_end:; response_count:1; response_revision:4556; }","duration":"118.553632ms","start":"2026-04-08T00:35:53.935111Z","end":"2026-04-08T00:35:54.053665Z","steps":["trace[1355812467] 'agreement among raft nodes before linearized reading' (duration: 118.430609ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:54.053841Z","caller":"traceutil/trace.go:171","msg":"trace[335058611] transaction","detail":"{read_only:false; response_revision:4556; number_of_response:1; }","duration":"138.751287ms","start":"2026-04-08T00:35:53.915041Z","end":"2026-04-08T00:35:54.053793Z","steps":["trace[335058611] 'process raft request' (duration: 138.284959ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:58.210134Z","caller":"traceutil/trace.go:171","msg":"trace[1442966270] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"401.946275ms","start":"2026-04-08T00:35:57.80815Z","end":"2026-04-08T00:35:58.210096Z","steps":["trace[1442966270] 'process raft request' (duration: 401.752052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:58.210293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:57.808133Z","time spent":"402.080896ms","remote":"127.0.0.1:47452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:36:03.857661Z","caller":"traceutil/trace.go:171","msg":"trace[1658130492] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"178.299568ms","start":"2026-04-08T00:36:03.679344Z","end":"2026-04-08T00:36:03.857644Z","steps":["trace[1658130492] 'process raft request' (duration: 178.193297ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:04.389851Z","caller":"traceutil/trace.go:171","msg":"trace[293207718] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"146.312936ms","start":"2026-04-08T00:36:04.243509Z","end":"2026-04-08T00:36:04.389822Z","steps":["trace[293207718] 'process raft request' (duration: 146.190824ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:04.608782Z","caller":"traceutil/trace.go:171","msg":"trace[74168204] linearizableReadLoop","detail":"{readStateIndex:4904; appliedIndex:4902; }","duration":"211.19005ms","start":"2026-04-08T00:36:04.397565Z","end":"2026-04-08T00:36:04.608755Z","steps":["trace[74168204] 'read index received' (duration: 53.033256ms)","trace[74168204] 'applied index is now lower than readState.Index' (duration: 158.155634ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:04.609391Z","caller":"traceutil/trace.go:171","msg":"trace[2000198746] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"215.542282ms","start":"2026-04-08T00:36:04.393818Z","end":"2026-04-08T00:36:04.60936Z","steps":["trace[2000198746] 'process raft request' (duration: 151.869014ms)","trace[2000198746] 'compare' (duration: 62.918767ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:04.609665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.073483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:04.609715Z","caller":"traceutil/trace.go:171","msg":"trace[1974334666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4729; }","duration":"212.164135ms","start":"2026-04-08T00:36:04.397538Z","end":"2026-04-08T00:36:04.609703Z","steps":["trace[1974334666] 'agreement among raft nodes before linearized reading' (duration: 212.055293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:04.610332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.966403ms","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-08T00:36:04.610485Z","caller":"traceutil/trace.go:171","msg":"trace[916066427] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4729; }","duration":"189.179926ms","start":"2026-04-08T00:36:04.421289Z","end":"2026-04-08T00:36:04.610469Z","steps":["trace[916066427] 'agreement among raft nodes before linearized reading' (duration: 188.856202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:16.322037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.626273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-08T00:36:16.322108Z","caller":"traceutil/trace.go:171","msg":"trace[1312527502] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4887; }","duration":"171.754065ms","start":"2026-04-08T00:36:16.150337Z","end":"2026-04-08T00:36:16.322091Z","steps":["trace[1312527502] 'range keys from in-memory index tree' (duration: 171.285328ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:16.947272Z","caller":"traceutil/trace.go:171","msg":"trace[1037102400] transaction","detail":"{read_only:false; response_revision:4893; number_of_response:1; }","duration":"108.945893ms","start":"2026-04-08T00:36:16.838298Z","end":"2026-04-08T00:36:16.947244Z","steps":["trace[1037102400] 'process raft request' (duration: 80.879133ms)","trace[1037102400] 'compare' (duration: 27.769145ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:17.109882Z","caller":"traceutil/trace.go:171","msg":"trace[486568580] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"112.627841ms","start":"2026-04-08T00:36:16.997209Z","end":"2026-04-08T00:36:17.109837Z","steps":["trace[486568580] 'process raft request' (duration: 112.153814ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:50.897012Z","caller":"traceutil/trace.go:171","msg":"trace[1157797375] transaction","detail":"{read_only:false; response_revision:5214; number_of_response:1; }","duration":"142.22798ms","start":"2026-04-08T00:36:50.754758Z","end":"2026-04-08T00:36:50.896986Z","steps":["trace[1157797375] 'process raft request' (duration: 141.767355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:51.085344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.669796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:36:51.085574Z","caller":"traceutil/trace.go:171","msg":"trace[655929756] linearizableReadLoop","detail":"{readStateIndex:5406; appliedIndex:5403; }","duration":"105.031325ms","start":"2026-04-08T00:36:50.980525Z","end":"2026-04-08T00:36:51.085556Z","steps":["trace[655929756] 'read index received' (duration: 14.6361ms)","trace[655929756] 'applied index is now lower than readState.Index' (duration: 90.394455ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:51.085586Z","caller":"traceutil/trace.go:171","msg":"trace[2131938680] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"186.040039ms","start":"2026-04-08T00:36:50.89951Z","end":"2026-04-08T00:36:51.08555Z","steps":["trace[2131938680] 'process raft request' (duration: 76.07198ms)","trace[2131938680] 'compare' (duration: 109.523274ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:51.086054Z","caller":"traceutil/trace.go:171","msg":"trace[2104216606] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"184.485129ms","start":"2026-04-08T00:36:50.90155Z","end":"2026-04-08T00:36:51.086035Z","steps":["trace[2104216606] 'process raft request' (duration: 183.911922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:51.086075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.5105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-08T00:36:51.086199Z","caller":"traceutil/trace.go:171","msg":"trace[1511158390] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:5217; }","duration":"105.701973ms","start":"2026-04-08T00:36:50.980471Z","end":"2026-04-08T00:36:51.086173Z","steps":["trace[1511158390] 'agreement among raft nodes before linearized reading' (duration: 105.41227ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:51.086205Z","caller":"traceutil/trace.go:171","msg":"trace[1805239825] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"183.83875ms","start":"2026-04-08T00:36:50.902213Z","end":"2026-04-08T00:36:51.086052Z","steps":["trace[1805239825] 'process raft request' (duration: 183.309713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:51.08644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.051652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:124409"} {"level":"info","ts":"2026-04-08T00:36:51.086508Z","caller":"traceutil/trace.go:171","msg":"trace[2044708615] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5217; }","duration":"104.136574ms","start":"2026-04-08T00:36:50.982349Z","end":"2026-04-08T00:36:51.086486Z","steps":["trace[2044708615] 'agreement among raft nodes before linearized reading' (duration: 103.93333ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:17.399156Z","caller":"traceutil/trace.go:171","msg":"trace[1972339059] transaction","detail":"{read_only:false; response_revision:5431; number_of_response:1; }","duration":"156.560902ms","start":"2026-04-08T00:37:17.242548Z","end":"2026-04-08T00:37:17.399109Z","steps":["trace[1972339059] 'process raft request' (duration: 139.358017ms)","trace[1972339059] 'compare' (duration: 16.535006ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:37:49.697609Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2919} {"level":"info","ts":"2026-04-08T00:37:49.80922Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2919,"took":"110.116417ms","hash":1562024508,"current-db-size-bytes":54628352,"current-db-size":"55 MB","current-db-size-in-use-bytes":29888512,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-08T00:37:49.809292Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1562024508,"revision":2919,"compact-revision":-1} {"level":"info","ts":"2026-04-08T00:39:45.547949Z","caller":"traceutil/trace.go:171","msg":"trace[1048455942] transaction","detail":"{read_only:false; response_revision:6439; number_of_response:1; }","duration":"130.904356ms","start":"2026-04-08T00:39:45.417018Z","end":"2026-04-08T00:39:45.547923Z","steps":["trace[1048455942] 'process raft request' (duration: 130.487652ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:16.85933Z","caller":"traceutil/trace.go:171","msg":"trace[2115823348] linearizableReadLoop","detail":"{readStateIndex:7552; appliedIndex:7551; }","duration":"110.64585ms","start":"2026-04-08T00:42:16.748659Z","end":"2026-04-08T00:42:16.859305Z","steps":["trace[2115823348] 'read index received' (duration: 29.393652ms)","trace[2115823348] 'applied index is now lower than readState.Index' (duration: 81.251208ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:16.859472Z","caller":"traceutil/trace.go:171","msg":"trace[711704454] transaction","detail":"{read_only:false; response_revision:7287; number_of_response:1; }","duration":"111.986195ms","start":"2026-04-08T00:42:16.747471Z","end":"2026-04-08T00:42:16.859457Z","steps":["trace[711704454] 'process raft request' (duration: 30.613484ms)","trace[711704454] 'compare' (duration: 81.116545ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:42:16.859684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.002319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-after-finished-controller\" ","response":"range_response_count:1 size:791"} {"level":"info","ts":"2026-04-08T00:42:16.859759Z","caller":"traceutil/trace.go:171","msg":"trace[2082320057] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-after-finished-controller; range_end:; response_count:1; response_revision:7287; }","duration":"111.109722ms","start":"2026-04-08T00:42:16.748631Z","end":"2026-04-08T00:42:16.85974Z","steps":["trace[2082320057] 'agreement among raft nodes before linearized reading' (duration: 110.993068ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:16.996586Z","caller":"traceutil/trace.go:171","msg":"trace[676189909] transaction","detail":"{read_only:false; response_revision:7288; number_of_response:1; }","duration":"101.873497ms","start":"2026-04-08T00:42:16.894686Z","end":"2026-04-08T00:42:16.996559Z","steps":["trace[676189909] 'process raft request' (duration: 101.674002ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:17.0795Z","caller":"traceutil/trace.go:171","msg":"trace[429827841] transaction","detail":"{read_only:false; response_revision:7289; number_of_response:1; }","duration":"179.341021ms","start":"2026-04-08T00:42:16.900137Z","end":"2026-04-08T00:42:17.079478Z","steps":["trace[429827841] 'process raft request' (duration: 179.205377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:17.079576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.214343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-after-finished-controller\" ","response":"range_response_count:1 size:791"} {"level":"warn","ts":"2026-04-08T00:42:17.07958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.696806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-controller\" ","response":"range_response_count:1 size:731"} {"level":"info","ts":"2026-04-08T00:42:17.079636Z","caller":"traceutil/trace.go:171","msg":"trace[1535518207] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-controller; range_end:; response_count:1; response_revision:7289; }","duration":"125.783427ms","start":"2026-04-08T00:42:16.953837Z","end":"2026-04-08T00:42:17.07962Z","steps":["trace[1535518207] 'agreement among raft nodes before linearized reading' (duration: 125.678005ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:17.079826Z","caller":"traceutil/trace.go:171","msg":"trace[1930565002] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-after-finished-controller; range_end:; response_count:1; response_revision:7289; }","duration":"122.326755ms","start":"2026-04-08T00:42:16.957291Z","end":"2026-04-08T00:42:17.079618Z","steps":["trace[1930565002] 'agreement among raft nodes before linearized reading' (duration: 122.192282ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:23.250862Z","caller":"traceutil/trace.go:171","msg":"trace[1490512406] linearizableReadLoop","detail":"{readStateIndex:7588; appliedIndex:7587; }","duration":"156.732994ms","start":"2026-04-08T00:42:23.094078Z","end":"2026-04-08T00:42:23.250811Z","steps":["trace[1490512406] 'read index received' (duration: 156.63363ms)","trace[1490512406] 'applied index is now lower than readState.Index' (duration: 98.184µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:23.250993Z","caller":"traceutil/trace.go:171","msg":"trace[604721055] transaction","detail":"{read_only:false; response_revision:7321; number_of_response:1; }","duration":"159.875177ms","start":"2026-04-08T00:42:23.091085Z","end":"2026-04-08T00:42:23.25096Z","steps":["trace[604721055] 'process raft request' (duration: 159.572928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:23.251082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.98297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/keycloak.199-204-45-116.nip.io-tls\" ","response":"range_response_count:1 size:4372"} {"level":"info","ts":"2026-04-08T00:42:23.251129Z","caller":"traceutil/trace.go:171","msg":"trace[993947380] range","detail":"{range_begin:/registry/secrets/auth-system/keycloak.199-204-45-116.nip.io-tls; range_end:; response_count:1; response_revision:7321; }","duration":"157.071973ms","start":"2026-04-08T00:42:23.094045Z","end":"2026-04-08T00:42:23.251117Z","steps":["trace[993947380] 'agreement among raft nodes before linearized reading' (duration: 156.925169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:23.251262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.205406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/envoy-gateway-system/\" range_end:\"/registry/statefulsets/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:42:23.251323Z","caller":"traceutil/trace.go:171","msg":"trace[1980465686] range","detail":"{range_begin:/registry/statefulsets/envoy-gateway-system/; range_end:/registry/statefulsets/envoy-gateway-system0; response_count:0; response_revision:7321; }","duration":"144.305398ms","start":"2026-04-08T00:42:23.106998Z","end":"2026-04-08T00:42:23.251303Z","steps":["trace[1980465686] 'agreement among raft nodes before linearized reading' (duration: 144.200366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:23.251342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.1019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-08T00:42:23.251354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.150853ms","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-04-08T00:42:23.251396Z","caller":"traceutil/trace.go:171","msg":"trace[1611847022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7321; }","duration":"124.186962ms","start":"2026-04-08T00:42:23.127195Z","end":"2026-04-08T00:42:23.251382Z","steps":["trace[1611847022] 'agreement among raft nodes before linearized reading' (duration: 123.868434ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:23.251434Z","caller":"traceutil/trace.go:171","msg":"trace[985167184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7321; }","duration":"126.279926ms","start":"2026-04-08T00:42:23.125135Z","end":"2026-04-08T00:42:23.251414Z","steps":["trace[985167184] 'agreement among raft nodes before linearized reading' (duration: 126.02377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:23.251995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.614286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/sh.helm.release.v1.keycloak.v1\" ","response":"range_response_count:1 size:72904"} {"level":"info","ts":"2026-04-08T00:42:23.252057Z","caller":"traceutil/trace.go:171","msg":"trace[1501811372] range","detail":"{range_begin:/registry/secrets/auth-system/sh.helm.release.v1.keycloak.v1; range_end:; response_count:1; response_revision:7321; }","duration":"157.691958ms","start":"2026-04-08T00:42:23.094347Z","end":"2026-04-08T00:42:23.252039Z","steps":["trace[1501811372] 'agreement among raft nodes before linearized reading' (duration: 156.659772ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:49.702918Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5654} {"level":"info","ts":"2026-04-08T00:42:49.781062Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5654,"took":"77.057503ms","hash":3455702781,"current-db-size-bytes":54628352,"current-db-size":"55 MB","current-db-size-in-use-bytes":18178048,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-08T00:42:49.781114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3455702781,"revision":5654,"compact-revision":2919}