{"level":"warn","ts":"2026-04-12T18:24:41.590159Z","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-12T18:24:41.590247Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-12T18:24:41.59032Z","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-12T18:24:41.590328Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-12T18:24:41.590347Z","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-12T18:24:41.591051Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-04-12T18:24:41.591147Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-12T18:24:41.59893Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.572258ms"} {"level":"info","ts":"2026-04-12T18:24:41.606199Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-04-12T18:24:41.606331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-04-12T18:24:41.60637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-04-12T18:24:41.606413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-12T18:24:41.606432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-04-12T18:24:41.606514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-04-12T18:24:41.612758Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-12T18:24:41.61437Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-12T18:24:41.615682Z","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-12T18:24:41.617858Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-12T18:24:41.618041Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-12T18:24:41.618074Z","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-12T18:24:41.618118Z","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-12T18:24:41.618161Z","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-12T18:24:41.619387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-04-12T18:24:41.619554Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-12T18:24:41.625718Z","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-12T18:24:41.62592Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-12T18:24:41.626295Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-12T18:24:41.626378Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-12T18:24:41.625955Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-12T18:24:42.007756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-04-12T18:24:42.007945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-12T18:24:42.008035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-04-12T18:24:42.008066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-04-12T18:24:42.008141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-04-12T18:24:42.008199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-04-12T18:24:42.008222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-04-12T18:24:42.030662Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-04-12T18:24:42.030722Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T18:24:42.030715Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T18:24:42.030675Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T18:24:42.031112Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-12T18:24:42.03115Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-12T18:24:42.03174Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T18:24:42.031882Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T18:24:42.031924Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T18:24:42.035636Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-12T18:24:42.036476Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-04-12T18:25:10.668204Z","caller":"traceutil/trace.go:171","msg":"trace[2145848864] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"104.400899ms","start":"2026-04-12T18:25:10.563717Z","end":"2026-04-12T18:25:10.668118Z","steps":["trace[2145848864] 'process raft request' (duration: 104.034995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:25:15.851029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.276959ms","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-04-12T18:25:15.851157Z","caller":"traceutil/trace.go:171","msg":"trace[1924351726] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:554; }","duration":"179.446791ms","start":"2026-04-12T18:25:15.671689Z","end":"2026-04-12T18:25:15.851136Z","steps":["trace[1924351726] 'range keys from in-memory index tree' (duration: 179.133537ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:25:19.303375Z","caller":"traceutil/trace.go:171","msg":"trace[11322671] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"332.547829ms","start":"2026-04-12T18:25:18.970797Z","end":"2026-04-12T18:25:19.303345Z","steps":["trace[11322671] 'read index received' (duration: 332.398097ms)","trace[11322671] 'applied index is now lower than readState.Index' (duration: 148.722µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:25:19.303497Z","caller":"traceutil/trace.go:171","msg":"trace[436973121] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"371.911394ms","start":"2026-04-12T18:25:18.931548Z","end":"2026-04-12T18:25:19.30346Z","steps":["trace[436973121] 'process raft request' (duration: 371.626151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:25:19.303648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.283812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:25:19.303721Z","caller":"traceutil/trace.go:171","msg":"trace[975211760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"291.413024ms","start":"2026-04-12T18:25:19.012288Z","end":"2026-04-12T18:25:19.303702Z","steps":["trace[975211760] 'agreement among raft nodes before linearized reading' (duration: 291.279343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:25:19.303668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.852822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-srrjr\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-12T18:25:19.303897Z","caller":"traceutil/trace.go:171","msg":"trace[1261219218] range","detail":"{range_begin:/registry/pods/kube-system/cilium-srrjr; range_end:; response_count:1; response_revision:579; }","duration":"333.081844ms","start":"2026-04-12T18:25:18.97075Z","end":"2026-04-12T18:25:19.303832Z","steps":["trace[1261219218] 'agreement among raft nodes before linearized reading' (duration: 332.74629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:25:19.304024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:25:18.93153Z","time spent":"372.060386ms","remote":"127.0.0.1:51786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T18:25:19.304166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:25:18.970726Z","time spent":"333.291496ms","remote":"127.0.0.1:51746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-srrjr\" "} {"level":"warn","ts":"2026-04-12T18:25:43.298003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.657531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:25:43.298164Z","caller":"traceutil/trace.go:171","msg":"trace[653065380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"137.861073ms","start":"2026-04-12T18:25:43.160278Z","end":"2026-04-12T18:25:43.298139Z","steps":["trace[653065380] 'range keys from in-memory index tree' (duration: 137.579621ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:27:09.617859Z","caller":"traceutil/trace.go:171","msg":"trace[1362704978] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"105.028931ms","start":"2026-04-12T18:27:09.512785Z","end":"2026-04-12T18:27:09.617814Z","steps":["trace[1362704978] 'process raft request' (duration: 99.427529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:27:10.021302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.280006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T18:27:10.021527Z","caller":"traceutil/trace.go:171","msg":"trace[1880336674] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"396.188604ms","start":"2026-04-12T18:27:09.625319Z","end":"2026-04-12T18:27:10.021508Z","steps":["trace[1880336674] 'process raft request' (duration: 396.077221ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:27:10.02152Z","caller":"traceutil/trace.go:171","msg":"trace[1706406338] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"396.365798ms","start":"2026-04-12T18:27:09.625105Z","end":"2026-04-12T18:27:10.021471Z","steps":["trace[1706406338] 'process raft request' (duration: 176.557419ms)","trace[1706406338] 'compare' (duration: 219.147862ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T18:27:10.021601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:27:09.6253Z","time spent":"396.267595ms","remote":"127.0.0.1:51726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T18:27:10.02173Z","caller":"traceutil/trace.go:171","msg":"trace[1180137488] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1950; }","duration":"263.519176ms","start":"2026-04-12T18:27:09.758194Z","end":"2026-04-12T18:27:10.021713Z","steps":["trace[1180137488] 'read index received' (duration: 43.525012ms)","trace[1180137488] 'applied index is now lower than readState.Index' (duration: 219.993464ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:27:10.021822Z","caller":"traceutil/trace.go:171","msg":"trace[714180133] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"393.74033ms","start":"2026-04-12T18:27:09.628071Z","end":"2026-04-12T18:27:10.021811Z","steps":["trace[714180133] 'process raft request' (duration: 393.563576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:27:10.0219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:27:09.628064Z","time spent":"393.784171ms","remote":"127.0.0.1:51746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T18:27:10.021957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.57648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T18:27:10.021995Z","caller":"traceutil/trace.go:171","msg":"trace[860742453] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1884; }","duration":"184.655552ms","start":"2026-04-12T18:27:09.837328Z","end":"2026-04-12T18:27:10.021984Z","steps":["trace[860742453] 'agreement among raft nodes before linearized reading' (duration: 184.57443ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:27:10.02206Z","caller":"traceutil/trace.go:171","msg":"trace[1838095305] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"396.682736ms","start":"2026-04-12T18:27:09.62533Z","end":"2026-04-12T18:27:10.022013Z","steps":["trace[1838095305] 'process raft request' (duration: 396.130002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:27:10.022163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:27:09.625319Z","time spent":"396.80673ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T18:27:10.022236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.04763ms","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-04-12T18:27:10.022276Z","caller":"traceutil/trace.go:171","msg":"trace[1584319591] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1884; }","duration":"264.113602ms","start":"2026-04-12T18:27:09.758151Z","end":"2026-04-12T18:27:10.022265Z","steps":["trace[1584319591] 'agreement among raft nodes before linearized reading' (duration: 264.001009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:27:10.02271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:27:09.625092Z","time spent":"396.511371ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T18:27:10.589068Z","caller":"traceutil/trace.go:171","msg":"trace[2003234668] linearizableReadLoop","detail":"{readStateIndex:1956; appliedIndex:1955; }","duration":"201.345351ms","start":"2026-04-12T18:27:10.387698Z","end":"2026-04-12T18:27:10.589044Z","steps":["trace[2003234668] 'read index received' (duration: 111.047524ms)","trace[2003234668] 'applied index is now lower than readState.Index' (duration: 90.296957ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T18:27:10.589197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.480974ms","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-04-12T18:27:10.589282Z","caller":"traceutil/trace.go:171","msg":"trace[1786922696] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1885; }","duration":"201.631079ms","start":"2026-04-12T18:27:10.387631Z","end":"2026-04-12T18:27:10.589263Z","steps":["trace[1786922696] 'agreement among raft nodes before linearized reading' (duration: 201.494245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:27:10.613326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.468194ms","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-04-12T18:27:10.613613Z","caller":"traceutil/trace.go:171","msg":"trace[290138038] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1887; }","duration":"199.787113ms","start":"2026-04-12T18:27:10.413807Z","end":"2026-04-12T18:27:10.613594Z","steps":["trace[290138038] 'agreement among raft nodes before linearized reading' (duration: 199.361072ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:27:49.973902Z","caller":"traceutil/trace.go:171","msg":"trace[937233782] linearizableReadLoop","detail":"{readStateIndex:2230; appliedIndex:2229; }","duration":"265.048859ms","start":"2026-04-12T18:27:49.708819Z","end":"2026-04-12T18:27:49.973868Z","steps":["trace[937233782] 'read index received' (duration: 264.905437ms)","trace[937233782] 'applied index is now lower than readState.Index' (duration: 142.002µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:27:49.974056Z","caller":"traceutil/trace.go:171","msg":"trace[1749997935] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"325.648251ms","start":"2026-04-12T18:27:49.648354Z","end":"2026-04-12T18:27:49.974002Z","steps":["trace[1749997935] 'process raft request' (duration: 325.329545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:27:49.974239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:27:49.648322Z","time spent":"325.821544ms","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T18:27:49.974995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.15656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-12T18:27:49.975058Z","caller":"traceutil/trace.go:171","msg":"trace[442836323] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2151; }","duration":"266.281404ms","start":"2026-04-12T18:27:49.708759Z","end":"2026-04-12T18:27:49.975041Z","steps":["trace[442836323] 'agreement among raft nodes before linearized reading' (duration: 265.269753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:27:50.012059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.516409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-04-12T18:27:50.012149Z","caller":"traceutil/trace.go:171","msg":"trace[70807754] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2152; }","duration":"143.663082ms","start":"2026-04-12T18:27:49.868467Z","end":"2026-04-12T18:27:50.01213Z","steps":["trace[70807754] 'agreement among raft nodes before linearized reading' (duration: 143.382957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:27:50.483586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.90137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T18:27:50.483748Z","caller":"traceutil/trace.go:171","msg":"trace[1112140896] linearizableReadLoop","detail":"{readStateIndex:2232; appliedIndex:2231; }","duration":"471.852561ms","start":"2026-04-12T18:27:50.011859Z","end":"2026-04-12T18:27:50.483711Z","steps":["trace[1112140896] 'read index received' (duration: 111.600703ms)","trace[1112140896] 'applied index is now lower than readState.Index' (duration: 360.250258ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T18:27:50.483955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.288611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T18:27:50.483938Z","caller":"traceutil/trace.go:171","msg":"trace[1727692890] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"504.504567ms","start":"2026-04-12T18:27:49.979384Z","end":"2026-04-12T18:27:50.483889Z","steps":["trace[1727692890] 'process raft request' (duration: 144.175019ms)","trace[1727692890] 'compare' (duration: 359.758087ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:27:50.484017Z","caller":"traceutil/trace.go:171","msg":"trace[211818099] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2153; }","duration":"508.387392ms","start":"2026-04-12T18:27:49.975615Z","end":"2026-04-12T18:27:50.484002Z","steps":["trace[211818099] 'agreement among raft nodes before linearized reading' (duration: 508.25256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:27:50.484075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:27:49.975602Z","time spent":"508.456393ms","remote":"127.0.0.1:51814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-12T18:27:50.484117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:27:49.979361Z","time spent":"504.66379ms","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T18:27:50.53328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.866264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:27:50.533403Z","caller":"traceutil/trace.go:171","msg":"trace[2074505396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2154; }","duration":"518.019557ms","start":"2026-04-12T18:27:50.015354Z","end":"2026-04-12T18:27:50.533373Z","steps":["trace[2074505396] 'agreement among raft nodes before linearized reading' (duration: 517.840304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:27:50.533457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:27:50.015337Z","time spent":"518.106169ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T18:27:50.53377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.515005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-12T18:27:50.533942Z","caller":"traceutil/trace.go:171","msg":"trace[176300323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2154; }","duration":"141.76028ms","start":"2026-04-12T18:27:50.392162Z","end":"2026-04-12T18:27:50.533922Z","steps":["trace[176300323] 'agreement among raft nodes before linearized reading' (duration: 141.434693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:27:50.533939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.667991ms","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-04-12T18:27:50.53411Z","caller":"traceutil/trace.go:171","msg":"trace[1549044374] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2154; }","duration":"218.840345ms","start":"2026-04-12T18:27:50.315185Z","end":"2026-04-12T18:27:50.534026Z","steps":["trace[1549044374] 'agreement among raft nodes before linearized reading' (duration: 218.494588ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:28:40.926747Z","caller":"traceutil/trace.go:171","msg":"trace[998567955] transaction","detail":"{read_only:false; response_revision:2531; number_of_response:1; }","duration":"142.084102ms","start":"2026-04-12T18:28:40.784568Z","end":"2026-04-12T18:28:40.926652Z","steps":["trace[998567955] 'process raft request' (duration: 141.890679ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:28:40.927185Z","caller":"traceutil/trace.go:171","msg":"trace[1899867507] linearizableReadLoop","detail":"{readStateIndex:2622; appliedIndex:2622; }","duration":"140.189622ms","start":"2026-04-12T18:28:40.786965Z","end":"2026-04-12T18:28:40.927155Z","steps":["trace[1899867507] 'read index received' (duration: 140.180551ms)","trace[1899867507] 'applied index is now lower than readState.Index' (duration: 7.331µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T18:28:40.927419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.431656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7462"} {"level":"info","ts":"2026-04-12T18:28:40.927458Z","caller":"traceutil/trace.go:171","msg":"trace[1729291757] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2531; }","duration":"140.529847ms","start":"2026-04-12T18:28:40.786917Z","end":"2026-04-12T18:28:40.927447Z","steps":["trace[1729291757] 'agreement among raft nodes before linearized reading' (duration: 140.343494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:29:07.735672Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842693495856211,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T18:29:08.115179Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.024788188s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-12T18:29:09.435386Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.319998727s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-12T18:29:09.440088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324633845s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T18:29:09.440394Z","caller":"traceutil/trace.go:171","msg":"trace[1565512411] transaction","detail":"{read_only:false; response_revision:2680; number_of_response:1; }","duration":"2.350032302s","start":"2026-04-12T18:29:07.090309Z","end":"2026-04-12T18:29:09.440341Z","steps":["trace[1565512411] 'process raft request' (duration: 1.025063942s)","trace[1565512411] 'compare' (duration: 1.323708532s)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:29:09.440466Z","caller":"traceutil/trace.go:171","msg":"trace[1497694157] linearizableReadLoop","detail":"{readStateIndex:2778; appliedIndex:2776; }","duration":"2.2058018s","start":"2026-04-12T18:29:07.234644Z","end":"2026-04-12T18:29:09.440446Z","steps":["trace[1497694157] 'read index received' (duration: 880.750229ms)","trace[1497694157] 'applied index is now lower than readState.Index' (duration: 1.325050231s)"],"step_count":2} {"level":"warn","ts":"2026-04-12T18:29:09.440584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:29:07.090287Z","time spent":"2.350190964s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T18:29:09.440692Z","caller":"traceutil/trace.go:171","msg":"trace[1184075260] transaction","detail":"{read_only:false; response_revision:2681; number_of_response:1; }","duration":"2.170482443s","start":"2026-04-12T18:29:07.270198Z","end":"2026-04-12T18:29:09.44068Z","steps":["trace[1184075260] 'process raft request' (duration: 2.170077357s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:29:09.440775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:29:07.270179Z","time spent":"2.170552533s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T18:29:09.440903Z","caller":"traceutil/trace.go:171","msg":"trace[739284335] transaction","detail":"{read_only:false; response_revision:2682; number_of_response:1; }","duration":"1.674450731s","start":"2026-04-12T18:29:07.766439Z","end":"2026-04-12T18:29:09.44089Z","steps":["trace[739284335] 'process raft request' (duration: 1.673924064s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:29:09.440982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:29:07.766416Z","time spent":"1.674529952s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T18:29:09.441132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.206483721s","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-04-12T18:29:09.441175Z","caller":"traceutil/trace.go:171","msg":"trace[1446620881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2682; }","duration":"2.206561581s","start":"2026-04-12T18:29:07.234603Z","end":"2026-04-12T18:29:09.441165Z","steps":["trace[1446620881] 'agreement among raft nodes before linearized reading' (duration: 2.206439479s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:29:09.441196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:29:07.234584Z","time spent":"2.206607063s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-12T18:29:09.475294Z","caller":"traceutil/trace.go:171","msg":"trace[1841758417] transaction","detail":"{read_only:false; response_revision:2683; number_of_response:1; }","duration":"1.232675181s","start":"2026-04-12T18:29:08.242585Z","end":"2026-04-12T18:29:09.475261Z","steps":["trace[1841758417] 'process raft request' (duration: 1.232483828s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:29:09.475608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.1723866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-12T18:29:09.475682Z","caller":"traceutil/trace.go:171","msg":"trace[433657651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2685; }","duration":"2.172505921s","start":"2026-04-12T18:29:07.303163Z","end":"2026-04-12T18:29:09.475669Z","steps":["trace[433657651] 'agreement among raft nodes before linearized reading' (duration: 2.172294428s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:29:09.475724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:29:07.303144Z","time spent":"2.172565181s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-12T18:29:09.475664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:29:08.242563Z","time spent":"1.232826263s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T18:29:09.47596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.227615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-04-12T18:29:09.475993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.068259294s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:29:09.476037Z","caller":"traceutil/trace.go:171","msg":"trace[1670402494] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:2685; }","duration":"2.068305705s","start":"2026-04-12T18:29:07.407712Z","end":"2026-04-12T18:29:09.476018Z","steps":["trace[1670402494] 'agreement among raft nodes before linearized reading' (duration: 2.068236374s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:29:09.476072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:29:07.407708Z","time spent":"2.068355165s","remote":"127.0.0.1:49122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"info","ts":"2026-04-12T18:29:09.476074Z","caller":"traceutil/trace.go:171","msg":"trace[994952249] transaction","detail":"{read_only:false; response_revision:2684; number_of_response:1; }","duration":"381.281247ms","start":"2026-04-12T18:29:09.094784Z","end":"2026-04-12T18:29:09.476065Z","steps":["trace[994952249] 'process raft request' (duration: 380.423855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:29:09.476306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.263143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-12T18:29:09.47637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.070722291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-12T18:29:09.476377Z","caller":"traceutil/trace.go:171","msg":"trace[347100719] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2685; }","duration":"637.348454ms","start":"2026-04-12T18:29:08.838996Z","end":"2026-04-12T18:29:09.476345Z","steps":["trace[347100719] 'agreement among raft nodes before linearized reading' (duration: 637.228673ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:29:09.476405Z","caller":"traceutil/trace.go:171","msg":"trace[2081566364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2685; }","duration":"2.070784061s","start":"2026-04-12T18:29:07.405613Z","end":"2026-04-12T18:29:09.476397Z","steps":["trace[2081566364] 'agreement among raft nodes before linearized reading' (duration: 2.07069694s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:29:09.47643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:29:07.405599Z","time spent":"2.070824962s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-12T18:29:09.476437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:29:08.838976Z","time spent":"637.446225ms","remote":"127.0.0.1:51902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":67,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-12T18:29:09.47633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:29:09.094735Z","time spent":"381.50837ms","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T18:29:09.476199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.047454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a5af71446058dd\" ","response":"range_response_count:1 size:781"} {"level":"warn","ts":"2026-04-12T18:29:09.476627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.461879549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:29:09.47666Z","caller":"traceutil/trace.go:171","msg":"trace[1173836399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2685; }","duration":"1.461981481s","start":"2026-04-12T18:29:08.01467Z","end":"2026-04-12T18:29:09.476651Z","steps":["trace[1173836399] 'agreement among raft nodes before linearized reading' (duration: 1.46193383s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:29:09.476685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:29:08.014651Z","time spent":"1.462028671s","remote":"127.0.0.1:51562","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-12T18:29:09.476797Z","caller":"traceutil/trace.go:171","msg":"trace[915028793] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a5af71446058dd; range_end:; response_count:1; response_revision:2685; }","duration":"394.48226ms","start":"2026-04-12T18:29:09.082114Z","end":"2026-04-12T18:29:09.476596Z","steps":["trace[915028793] 'agreement among raft nodes before linearized reading' (duration: 393.944042ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:29:09.476031Z","caller":"traceutil/trace.go:171","msg":"trace[1466908673] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2685; }","duration":"696.336287ms","start":"2026-04-12T18:29:08.779678Z","end":"2026-04-12T18:29:09.476014Z","steps":["trace[1466908673] 'agreement among raft nodes before linearized reading' (duration: 696.128454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:29:09.47692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:29:08.779659Z","time spent":"697.250359ms","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-12T18:29:09.476932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.674866386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-12T18:29:09.476982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.861053601s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T18:29:09.47699Z","caller":"traceutil/trace.go:171","msg":"trace[1072310732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2685; }","duration":"1.674942317s","start":"2026-04-12T18:29:07.802034Z","end":"2026-04-12T18:29:09.476977Z","steps":["trace[1072310732] 'agreement among raft nodes before linearized reading' (duration: 1.674802525s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:29:09.476873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:29:09.082101Z","time spent":"394.751524ms","remote":"127.0.0.1:51622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":803,"request content":"key:\"/registry/events/auth-system/keycloak-0.18a5af71446058dd\" "} {"level":"info","ts":"2026-04-12T18:29:09.477039Z","caller":"traceutil/trace.go:171","msg":"trace[479475837] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2685; }","duration":"1.861155532s","start":"2026-04-12T18:29:07.61587Z","end":"2026-04-12T18:29:09.477026Z","steps":["trace[479475837] 'agreement among raft nodes before linearized reading' (duration: 1.861047341s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:29:09.477053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:29:07.802022Z","time spent":"1.675016208s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-12T18:29:09.477086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:29:07.615841Z","time spent":"1.861228864s","remote":"127.0.0.1:51844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-04-12T18:29:09.476029Z","caller":"traceutil/trace.go:171","msg":"trace[967917244] transaction","detail":"{read_only:false; response_revision:2685; number_of_response:1; }","duration":"101.903185ms","start":"2026-04-12T18:29:09.374106Z","end":"2026-04-12T18:29:09.476009Z","steps":["trace[967917244] 'process raft request' (duration: 101.275206ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:29:15.32105Z","caller":"traceutil/trace.go:171","msg":"trace[949060055] transaction","detail":"{read_only:false; response_revision:2712; number_of_response:1; }","duration":"130.866434ms","start":"2026-04-12T18:29:15.190154Z","end":"2026-04-12T18:29:15.321021Z","steps":["trace[949060055] 'process raft request' (duration: 120.959742ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:29:48.656937Z","caller":"traceutil/trace.go:171","msg":"trace[843263999] transaction","detail":"{read_only:false; response_revision:2890; number_of_response:1; }","duration":"225.496084ms","start":"2026-04-12T18:29:48.431415Z","end":"2026-04-12T18:29:48.656911Z","steps":["trace[843263999] 'process raft request' (duration: 225.309482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:38.390454Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842693495857517,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T18:30:38.891084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842693495857517,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T18:30:39.391697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842693495857517,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T18:30:39.925752Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842693495857517,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T18:30:40.016097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000652287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-12T18:30:40.016195Z","caller":"traceutil/trace.go:171","msg":"trace[2076523264] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000837725s","start":"2026-04-12T18:30:38.015335Z","end":"2026-04-12T18:30:40.016173Z","steps":["trace[2076523264] 'agreement among raft nodes before linearized reading' (duration: 2.00070605s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:40.0163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:38.015321Z","time spent":"2.000964509s","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T18:30:40.426379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842693495857517,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T18:30:40.927303Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842693495857517,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T18:30:41.353728Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.568705443s","expected-duration":"1s"} {"level":"info","ts":"2026-04-12T18:30:41.354035Z","caller":"traceutil/trace.go:171","msg":"trace[2053871292] transaction","detail":"{read_only:false; response_revision:3145; number_of_response:1; }","duration":"3.569091498s","start":"2026-04-12T18:30:37.784921Z","end":"2026-04-12T18:30:41.354013Z","steps":["trace[2053871292] 'process raft request' (duration: 3.568956793s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.354174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:37.784894Z","time spent":"3.569214723s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T18:30:41.405149Z","caller":"traceutil/trace.go:171","msg":"trace[262188824] transaction","detail":"{read_only:false; response_revision:3147; number_of_response:1; }","duration":"3.618675701s","start":"2026-04-12T18:30:37.786449Z","end":"2026-04-12T18:30:41.405125Z","steps":["trace[262188824] 'process raft request' (duration: 3.618607299s)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:30:41.405218Z","caller":"traceutil/trace.go:171","msg":"trace[479878787] transaction","detail":"{read_only:false; response_revision:3146; number_of_response:1; }","duration":"3.620226961s","start":"2026-04-12T18:30:37.784951Z","end":"2026-04-12T18:30:41.405177Z","steps":["trace[479878787] 'process raft request' (duration: 3.619953351s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.405291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:37.786429Z","time spent":"3.618793116s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T18:30:41.405413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:37.784923Z","time spent":"3.620374647s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T18:30:41.405613Z","caller":"traceutil/trace.go:171","msg":"trace[659242791] transaction","detail":"{read_only:false; response_revision:3148; number_of_response:1; }","duration":"3.618361559s","start":"2026-04-12T18:30:37.787239Z","end":"2026-04-12T18:30:41.4056Z","steps":["trace[659242791] 'process raft request' (duration: 3.61785347s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.405679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:37.787229Z","time spent":"3.618418841s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T18:30:41.417155Z","caller":"traceutil/trace.go:171","msg":"trace[304592536] linearizableReadLoop","detail":"{readStateIndex:3265; appliedIndex:3260; }","duration":"3.527031745s","start":"2026-04-12T18:30:37.890107Z","end":"2026-04-12T18:30:41.417139Z","steps":["trace[304592536] 'read index received' (duration: 3.463801695s)","trace[304592536] 'applied index is now lower than readState.Index' (duration: 63.21701ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T18:30:41.41731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.527198192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-12T18:30:41.417341Z","caller":"traceutil/trace.go:171","msg":"trace[1569383926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3149; }","duration":"3.527278974s","start":"2026-04-12T18:30:37.890054Z","end":"2026-04-12T18:30:41.417333Z","steps":["trace[1569383926] 'agreement among raft nodes before linearized reading' (duration: 3.527174311s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.417366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:37.890037Z","time spent":"3.527322556s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-12T18:30:41.417455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.526498435s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-12T18:30:41.417472Z","caller":"traceutil/trace.go:171","msg":"trace[587977021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3149; }","duration":"3.526557907s","start":"2026-04-12T18:30:37.89091Z","end":"2026-04-12T18:30:41.417468Z","steps":["trace[587977021] 'agreement among raft nodes before linearized reading' (duration: 3.526509926s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.417485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:37.890889Z","time spent":"3.526593918s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-12T18:30:41.417646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.066902355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:30:41.417671Z","caller":"traceutil/trace.go:171","msg":"trace[173296724] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:3149; }","duration":"3.066975598s","start":"2026-04-12T18:30:38.350689Z","end":"2026-04-12T18:30:41.417664Z","steps":["trace[173296724] 'agreement among raft nodes before linearized reading' (duration: 3.066940247s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.417685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:38.350643Z","time spent":"3.06703771s","remote":"127.0.0.1:51782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-12T18:30:41.417794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.196014422s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-04-12T18:30:41.41777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.639664094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-04-12T18:30:41.417851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.431504748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-12T18:30:41.417869Z","caller":"traceutil/trace.go:171","msg":"trace[838016828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3149; }","duration":"3.43156202s","start":"2026-04-12T18:30:37.986303Z","end":"2026-04-12T18:30:41.417865Z","steps":["trace[838016828] 'agreement among raft nodes before linearized reading' (duration: 3.431523459s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.41788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:37.986282Z","time spent":"3.431595502s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-12T18:30:41.417868Z","caller":"traceutil/trace.go:171","msg":"trace[1687094137] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3149; }","duration":"2.63982083s","start":"2026-04-12T18:30:38.778018Z","end":"2026-04-12T18:30:41.417839Z","steps":["trace[1687094137] 'agreement among raft nodes before linearized reading' (duration: 2.63954374s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.417921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:38.778006Z","time spent":"2.639903004s","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-12T18:30:41.417967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.486307957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-12T18:30:41.417979Z","caller":"traceutil/trace.go:171","msg":"trace[326455210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3149; }","duration":"3.486347029s","start":"2026-04-12T18:30:37.931628Z","end":"2026-04-12T18:30:41.417975Z","steps":["trace[326455210] 'agreement among raft nodes before linearized reading' (duration: 3.486316027s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.417993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:37.931612Z","time spent":"3.486375179s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-12T18:30:41.417816Z","caller":"traceutil/trace.go:171","msg":"trace[1773861771] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3149; }","duration":"3.196065884s","start":"2026-04-12T18:30:38.221742Z","end":"2026-04-12T18:30:41.417807Z","steps":["trace[1773861771] 'agreement among raft nodes before linearized reading' (duration: 3.196007042s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.418162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487809812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:30:41.418178Z","caller":"traceutil/trace.go:171","msg":"trace[952374332] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:3149; }","duration":"1.487857594s","start":"2026-04-12T18:30:39.930316Z","end":"2026-04-12T18:30:41.418173Z","steps":["trace[952374332] 'agreement among raft nodes before linearized reading' (duration: 1.487829383s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.418192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:39.930298Z","time spent":"1.487890005s","remote":"127.0.0.1:51956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-04-12T18:30:41.418189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:38.221722Z","time spent":"3.196453828s","remote":"127.0.0.1:51746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-12T18:30:41.418262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.469077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:30:41.418275Z","caller":"traceutil/trace.go:171","msg":"trace[17400424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3149; }","duration":"571.504579ms","start":"2026-04-12T18:30:40.846767Z","end":"2026-04-12T18:30:41.418271Z","steps":["trace[17400424] 'agreement among raft nodes before linearized reading' (duration: 571.485648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.418286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:40.846756Z","time spent":"571.528489ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T18:30:41.418358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487955838s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T18:30:41.418373Z","caller":"traceutil/trace.go:171","msg":"trace[113204072] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:3149; }","duration":"1.488000909s","start":"2026-04-12T18:30:39.930366Z","end":"2026-04-12T18:30:41.418367Z","steps":["trace[113204072] 'agreement among raft nodes before linearized reading' (duration: 1.487978378s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.418384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:39.930349Z","time spent":"1.488032001s","remote":"127.0.0.1:51844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-12T18:30:41.417937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.473683321s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-12T18:30:41.418512Z","caller":"traceutil/trace.go:171","msg":"trace[688583091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3149; }","duration":"3.474274813s","start":"2026-04-12T18:30:37.944222Z","end":"2026-04-12T18:30:41.418496Z","steps":["trace[688583091] 'agreement among raft nodes before linearized reading' (duration: 3.473690691s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.418543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488141626s","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-12T18:30:41.418563Z","caller":"traceutil/trace.go:171","msg":"trace[276631585] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3149; }","duration":"1.488222138s","start":"2026-04-12T18:30:39.930334Z","end":"2026-04-12T18:30:41.418556Z","steps":["trace[276631585] 'agreement among raft nodes before linearized reading' (duration: 1.488194386s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.41856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:37.944209Z","time spent":"3.474336917s","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-12T18:30:41.41858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:39.930306Z","time spent":"1.48826901s","remote":"127.0.0.1:49090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-12T18:33:10.211783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.434288ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:33:10.212287Z","caller":"traceutil/trace.go:171","msg":"trace[954906305] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4696; }","duration":"227.95758ms","start":"2026-04-12T18:33:09.98432Z","end":"2026-04-12T18:33:10.212278Z","steps":["trace[954906305] 'range keys from in-memory index tree' (duration: 227.408838ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:33:10.212185Z","caller":"traceutil/trace.go:171","msg":"trace[1955147901] transaction","detail":"{read_only:false; response_revision:4697; number_of_response:1; }","duration":"258.991758ms","start":"2026-04-12T18:33:09.953174Z","end":"2026-04-12T18:33:10.212166Z","steps":["trace[1955147901] 'process raft request' (duration: 250.846293ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:33:10.357Z","caller":"traceutil/trace.go:171","msg":"trace[747846643] transaction","detail":"{read_only:false; response_revision:4698; number_of_response:1; }","duration":"401.571119ms","start":"2026-04-12T18:33:09.955406Z","end":"2026-04-12T18:33:10.356977Z","steps":["trace[747846643] 'process raft request' (duration: 362.739264ms)","trace[747846643] 'compare' (duration: 38.549378ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:33:10.357185Z","caller":"traceutil/trace.go:171","msg":"trace[795172043] transaction","detail":"{read_only:false; response_revision:4699; number_of_response:1; }","duration":"285.427907ms","start":"2026-04-12T18:33:10.071731Z","end":"2026-04-12T18:33:10.357159Z","steps":["trace[795172043] 'process raft request' (duration: 285.057839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:33:10.357189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:33:09.955378Z","time spent":"401.689402ms","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T18:33:10.357768Z","caller":"traceutil/trace.go:171","msg":"trace[756157579] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"265.395712ms","start":"2026-04-12T18:33:10.092343Z","end":"2026-04-12T18:33:10.357739Z","steps":["trace[756157579] 'process raft request' (duration: 264.48394ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:33:10.358053Z","caller":"traceutil/trace.go:171","msg":"trace[1927041612] linearizableReadLoop","detail":"{readStateIndex:4872; appliedIndex:4870; }","duration":"343.941612ms","start":"2026-04-12T18:33:10.014086Z","end":"2026-04-12T18:33:10.358027Z","steps":["trace[1927041612] 'read index received' (duration: 189.944291ms)","trace[1927041612] 'applied index is now lower than readState.Index' (duration: 153.994801ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T18:33:10.426453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.339742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:33:10.426558Z","caller":"traceutil/trace.go:171","msg":"trace[940170226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4700; }","duration":"412.499755ms","start":"2026-04-12T18:33:10.01403Z","end":"2026-04-12T18:33:10.42653Z","steps":["trace[940170226] 'agreement among raft nodes before linearized reading' (duration: 344.055505ms)","trace[940170226] 'range keys from in-memory index tree' (duration: 68.284977ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T18:33:10.42661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:33:10.014011Z","time spent":"412.588667ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T18:33:10.426625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.660571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-12T18:33:10.42675Z","caller":"traceutil/trace.go:171","msg":"trace[1948272374] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4700; }","duration":"159.807554ms","start":"2026-04-12T18:33:10.266913Z","end":"2026-04-12T18:33:10.42672Z","steps":["trace[1948272374] 'agreement among raft nodes before linearized reading' (duration: 159.494107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:33:10.426836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.434954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ff078176-8476-4920-b358-3455b2dfef06\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-12T18:33:10.42696Z","caller":"traceutil/trace.go:171","msg":"trace[1552971857] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ff078176-8476-4920-b358-3455b2dfef06; range_end:; response_count:1; response_revision:4700; }","duration":"355.642679ms","start":"2026-04-12T18:33:10.071298Z","end":"2026-04-12T18:33:10.42694Z","steps":["trace[1552971857] 'agreement among raft nodes before linearized reading' (duration: 355.197339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:33:10.427009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.806603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-6cc848b45f-64xfq.18a5afadd1faf5db\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-04-12T18:33:10.427066Z","caller":"traceutil/trace.go:171","msg":"trace[1106342952] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-6cc848b45f-64xfq.18a5afadd1faf5db; range_end:; response_count:1; response_revision:4700; }","duration":"259.885356ms","start":"2026-04-12T18:33:10.167171Z","end":"2026-04-12T18:33:10.427056Z","steps":["trace[1106342952] 'agreement among raft nodes before linearized reading' (duration: 259.668651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:33:10.427063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.073989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"warn","ts":"2026-04-12T18:33:10.427036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:33:10.071278Z","time spent":"355.745922ms","remote":"127.0.0.1:51746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ff078176-8476-4920-b358-3455b2dfef06\" "} {"level":"info","ts":"2026-04-12T18:33:10.427129Z","caller":"traceutil/trace.go:171","msg":"trace[1216350821] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4700; }","duration":"260.177392ms","start":"2026-04-12T18:33:10.166934Z","end":"2026-04-12T18:33:10.427111Z","steps":["trace[1216350821] 'agreement among raft nodes before linearized reading' (duration: 260.044489ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:33:10.67676Z","caller":"traceutil/trace.go:171","msg":"trace[1971546942] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"244.110937ms","start":"2026-04-12T18:33:10.432617Z","end":"2026-04-12T18:33:10.676728Z","steps":["trace[1971546942] 'process raft request' (duration: 166.941357ms)","trace[1971546942] 'compare' (duration: 76.738369ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:33:10.67685Z","caller":"traceutil/trace.go:171","msg":"trace[673637640] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"244.140098ms","start":"2026-04-12T18:33:10.432698Z","end":"2026-04-12T18:33:10.676838Z","steps":["trace[673637640] 'process raft request' (duration: 243.885631ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:33:10.676792Z","caller":"traceutil/trace.go:171","msg":"trace[1314412803] transaction","detail":"{read_only:false; response_revision:4704; number_of_response:1; }","duration":"244.065266ms","start":"2026-04-12T18:33:10.432705Z","end":"2026-04-12T18:33:10.67677Z","steps":["trace[1314412803] 'process raft request' (duration: 243.965554ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:33:10.677047Z","caller":"traceutil/trace.go:171","msg":"trace[1611489833] transaction","detail":"{read_only:false; response_revision:4705; number_of_response:1; }","duration":"244.173338ms","start":"2026-04-12T18:33:10.432859Z","end":"2026-04-12T18:33:10.677032Z","steps":["trace[1611489833] 'process raft request' (duration: 243.868151ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:33:10.677353Z","caller":"traceutil/trace.go:171","msg":"trace[1805155470] linearizableReadLoop","detail":"{readStateIndex:4880; appliedIndex:4875; }","duration":"244.465515ms","start":"2026-04-12T18:33:10.432866Z","end":"2026-04-12T18:33:10.677332Z","steps":["trace[1805155470] 'read index received' (duration: 51.626018ms)","trace[1805155470] 'applied index is now lower than readState.Index' (duration: 192.838397ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:33:10.677413Z","caller":"traceutil/trace.go:171","msg":"trace[1499283892] transaction","detail":"{read_only:false; response_revision:4706; number_of_response:1; }","duration":"243.981833ms","start":"2026-04-12T18:33:10.433419Z","end":"2026-04-12T18:33:10.677401Z","steps":["trace[1499283892] 'process raft request' (duration: 243.557034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:33:10.677581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.686529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2694193b-b22f-447e-9c54-ecc0eda09c3f\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-04-12T18:33:10.677622Z","caller":"traceutil/trace.go:171","msg":"trace[1010912107] transaction","detail":"{read_only:false; response_revision:4707; number_of_response:1; }","duration":"239.760087ms","start":"2026-04-12T18:33:10.437849Z","end":"2026-04-12T18:33:10.677609Z","steps":["trace[1010912107] 'process raft request' (duration: 239.376778ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:33:10.677643Z","caller":"traceutil/trace.go:171","msg":"trace[1453434311] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2694193b-b22f-447e-9c54-ecc0eda09c3f; range_end:; response_count:1; response_revision:4707; }","duration":"244.792722ms","start":"2026-04-12T18:33:10.432833Z","end":"2026-04-12T18:33:10.677625Z","steps":["trace[1453434311] 'agreement among raft nodes before linearized reading' (duration: 244.577127ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:33:10.828677Z","caller":"traceutil/trace.go:171","msg":"trace[181226018] transaction","detail":"{read_only:false; response_revision:4709; number_of_response:1; }","duration":"143.778859ms","start":"2026-04-12T18:33:10.68487Z","end":"2026-04-12T18:33:10.828649Z","steps":["trace[181226018] 'process raft request' (duration: 143.570534ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:33:10.828953Z","caller":"traceutil/trace.go:171","msg":"trace[674572173] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"143.016861ms","start":"2026-04-12T18:33:10.685915Z","end":"2026-04-12T18:33:10.828932Z","steps":["trace[674572173] 'process raft request' (duration: 142.93369ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:33:10.828933Z","caller":"traceutil/trace.go:171","msg":"trace[1783476744] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"143.770638ms","start":"2026-04-12T18:33:10.685133Z","end":"2026-04-12T18:33:10.828904Z","steps":["trace[1783476744] 'process raft request' (duration: 143.452851ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:33:10.829014Z","caller":"traceutil/trace.go:171","msg":"trace[542257510] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"143.471381ms","start":"2026-04-12T18:33:10.685533Z","end":"2026-04-12T18:33:10.829005Z","steps":["trace[542257510] 'process raft request' (duration: 143.227316ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:33:10.879502Z","caller":"traceutil/trace.go:171","msg":"trace[1420390966] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"147.441332ms","start":"2026-04-12T18:33:10.732009Z","end":"2026-04-12T18:33:10.87945Z","steps":["trace[1420390966] 'process raft request' (duration: 147.31428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:33:24.55828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.834426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T18:33:24.558515Z","caller":"traceutil/trace.go:171","msg":"trace[1622658610] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"153.584204ms","start":"2026-04-12T18:33:24.404913Z","end":"2026-04-12T18:33:24.558497Z","steps":["trace[1622658610] 'process raft request' (duration: 153.537663ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:33:24.558772Z","caller":"traceutil/trace.go:171","msg":"trace[2034851040] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"156.206202ms","start":"2026-04-12T18:33:24.40254Z","end":"2026-04-12T18:33:24.558746Z","steps":["trace[2034851040] 'process raft request' (duration: 155.854134ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:33:24.559002Z","caller":"traceutil/trace.go:171","msg":"trace[882979540] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"160.237681ms","start":"2026-04-12T18:33:24.398608Z","end":"2026-04-12T18:33:24.558845Z","steps":["trace[882979540] 'process raft request' (duration: 21.754151ms)","trace[882979540] 'compare' (duration: 137.703033ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:33:24.776228Z","caller":"traceutil/trace.go:171","msg":"trace[962172842] transaction","detail":"{read_only:false; response_revision:4885; number_of_response:1; }","duration":"107.20385ms","start":"2026-04-12T18:33:24.669Z","end":"2026-04-12T18:33:24.776204Z","steps":["trace[962172842] 'process raft request' (duration: 107.029076ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:34:42.271453Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2858} {"level":"info","ts":"2026-04-12T18:34:42.366239Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2858,"took":"93.527988ms","hash":2193027914,"current-db-size-bytes":53243904,"current-db-size":"53 MB","current-db-size-in-use-bytes":29110272,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-12T18:34:42.366302Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2193027914,"revision":2858,"compact-revision":-1} {"level":"info","ts":"2026-04-12T18:37:08.16688Z","caller":"traceutil/trace.go:171","msg":"trace[1912743248] linearizableReadLoop","detail":"{readStateIndex:6734; appliedIndex:6733; }","duration":"105.591114ms","start":"2026-04-12T18:37:08.061244Z","end":"2026-04-12T18:37:08.166835Z","steps":["trace[1912743248] 'read index received' (duration: 45.824053ms)","trace[1912743248] 'applied index is now lower than readState.Index' (duration: 59.765631ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:37:08.166906Z","caller":"traceutil/trace.go:171","msg":"trace[1856933235] transaction","detail":"{read_only:false; response_revision:6501; number_of_response:1; }","duration":"107.565357ms","start":"2026-04-12T18:37:08.059275Z","end":"2026-04-12T18:37:08.16684Z","steps":["trace[1856933235] 'process raft request' (duration: 47.885198ms)","trace[1856933235] 'compare' (duration: 59.397894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T18:37:08.167404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.78695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-12T18:37:08.167618Z","caller":"traceutil/trace.go:171","msg":"trace[1022071230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6501; }","duration":"105.021904ms","start":"2026-04-12T18:37:08.062569Z","end":"2026-04-12T18:37:08.167591Z","steps":["trace[1022071230] 'agreement among raft nodes before linearized reading' (duration: 104.655638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:37:08.167905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.626592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-12T18:37:08.167958Z","caller":"traceutil/trace.go:171","msg":"trace[1936484949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6501; }","duration":"106.722314ms","start":"2026-04-12T18:37:08.061227Z","end":"2026-04-12T18:37:08.167949Z","steps":["trace[1936484949] 'agreement among raft nodes before linearized reading' (duration: 105.747326ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:39:42.289128Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5507} {"level":"info","ts":"2026-04-12T18:39:42.495455Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5507,"took":"205.062714ms","hash":2887173015,"current-db-size-bytes":53243904,"current-db-size":"53 MB","current-db-size-in-use-bytes":18386944,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-12T18:39:42.495509Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2887173015,"revision":5507,"compact-revision":2858}