{"level":"warn","ts":"2026-04-17T18:35:48.868273Z","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-17T18:35:48.868371Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-17T18:35:48.868447Z","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-17T18:35:48.868458Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-17T18:35:48.868517Z","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-17T18:35:48.869443Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-04-17T18:35:48.869593Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-17T18:35:48.934569Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"64.672937ms"} {"level":"info","ts":"2026-04-17T18:35:49.191466Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-04-17T18:35:49.191577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T18:35:49.191597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-04-17T18:35:49.191605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T18:35:49.191611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-04-17T18:35:49.191643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-04-17T18:35:49.593373Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T18:35:49.595501Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T18:35:49.596955Z","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-17T18:35:49.599755Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T18:35:49.599844Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T18:35:49.60167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-04-17T18:35:49.601854Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-17T18:35:49.600069Z","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-17T18:35:49.601954Z","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-17T18:35:49.60197Z","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-17T18:35:49.604877Z","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-17T18:35:49.605053Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T18:35:49.605082Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T18:35:49.605134Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-17T18:35:49.605151Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-17T18:35:50.012148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T18:35:50.012225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T18:35:50.01225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-04-17T18:35:50.012264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-04-17T18:35:50.012291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-17T18:35:50.012307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-04-17T18:35:50.012381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-17T18:35:50.014261Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:35:50.015067Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T18:35:50.01522Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T18:35:50.01548Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:35:50.015595Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:35:50.015631Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:35:50.015802Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T18:35:50.017554Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T18:35:50.017609Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T18:35:50.018476Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-04-17T18:35:50.018628Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T18:36:15.62364Z","caller":"traceutil/trace.go:171","msg":"trace[795738432] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"261.442972ms","start":"2026-04-17T18:36:15.362164Z","end":"2026-04-17T18:36:15.623607Z","steps":["trace[795738432] 'read index received' (duration: 261.142021ms)","trace[795738432] 'applied index is now lower than readState.Index' (duration: 299.951µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:36:15.624048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.824905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T18:36:15.624213Z","caller":"traceutil/trace.go:171","msg":"trace[316872443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:422; }","duration":"262.071276ms","start":"2026-04-17T18:36:15.362118Z","end":"2026-04-17T18:36:15.624189Z","steps":["trace[316872443] 'agreement among raft nodes before linearized reading' (duration: 261.734924ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:36:15.624207Z","caller":"traceutil/trace.go:171","msg":"trace[58064923] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"321.875512ms","start":"2026-04-17T18:36:15.302303Z","end":"2026-04-17T18:36:15.624178Z","steps":["trace[58064923] 'process raft request' (duration: 321.101157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:36:15.625049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:36:15.302284Z","time spent":"321.993632ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:36:20.250301Z","caller":"traceutil/trace.go:171","msg":"trace[1495408036] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"248.762964ms","start":"2026-04-17T18:36:20.001505Z","end":"2026-04-17T18:36:20.250268Z","steps":["trace[1495408036] 'process raft request' (duration: 243.628375ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:36:20.250471Z","caller":"traceutil/trace.go:171","msg":"trace[1090054084] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"244.554752ms","start":"2026-04-17T18:36:20.00588Z","end":"2026-04-17T18:36:20.250435Z","steps":["trace[1090054084] 'process raft request' (duration: 244.295421ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:36:20.25122Z","caller":"traceutil/trace.go:171","msg":"trace[739271366] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"243.473706ms","start":"2026-04-17T18:36:20.007728Z","end":"2026-04-17T18:36:20.251201Z","steps":["trace[739271366] 'process raft request' (duration: 243.419886ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:36:20.251425Z","caller":"traceutil/trace.go:171","msg":"trace[558242031] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"245.070735ms","start":"2026-04-17T18:36:20.00633Z","end":"2026-04-17T18:36:20.251401Z","steps":["trace[558242031] 'process raft request' (duration: 244.710983ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:36:20.252408Z","caller":"traceutil/trace.go:171","msg":"trace[46962862] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"168.755913ms","start":"2026-04-17T18:36:20.083631Z","end":"2026-04-17T18:36:20.252387Z","steps":["trace[46962862] 'process raft request' (duration: 168.588182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:36:21.227405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.152191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:36:21.227499Z","caller":"traceutil/trace.go:171","msg":"trace[1987524965] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"332.569067ms","start":"2026-04-17T18:36:20.894915Z","end":"2026-04-17T18:36:21.227484Z","steps":["trace[1987524965] 'read index received' (duration: 42.85µs)","trace[1987524965] 'applied index is now lower than readState.Index' (duration: 332.524817ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:36:21.227571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.662327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:36:21.227595Z","caller":"traceutil/trace.go:171","msg":"trace[738336651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"332.717887ms","start":"2026-04-17T18:36:20.894869Z","end":"2026-04-17T18:36:21.227587Z","steps":["trace[738336651] 'agreement among raft nodes before linearized reading' (duration: 332.656437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:36:21.227623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:36:20.894851Z","time spent":"332.764979ms","remote":"127.0.0.1:57752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T18:36:21.227883Z","caller":"traceutil/trace.go:171","msg":"trace[42798316] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"376.490399ms","start":"2026-04-17T18:36:20.851347Z","end":"2026-04-17T18:36:21.227837Z","steps":["trace[42798316] 'process raft request' (duration: 31.743254ms)","trace[42798316] 'compare' (duration: 343.802ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:36:21.228058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:36:20.851324Z","time spent":"376.64415ms","remote":"127.0.0.1:57798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:36:21.237024Z","caller":"traceutil/trace.go:171","msg":"trace[1541734423] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"317.902416ms","start":"2026-04-17T18:36:20.919101Z","end":"2026-04-17T18:36:21.237003Z","steps":["trace[1541734423] 'process raft request' (duration: 316.98958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:36:21.237107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:36:20.919074Z","time spent":"317.991036ms","remote":"127.0.0.1:57798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:36:26.480161Z","caller":"traceutil/trace.go:171","msg":"trace[1353676211] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"102.52634ms","start":"2026-04-17T18:36:26.377578Z","end":"2026-04-17T18:36:26.480104Z","steps":["trace[1353676211] 'process raft request' (duration: 101.491855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:36:27.040173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.765737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:36:27.040276Z","caller":"traceutil/trace.go:171","msg":"trace[983303834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"147.900047ms","start":"2026-04-17T18:36:26.892355Z","end":"2026-04-17T18:36:27.040255Z","steps":["trace[983303834] 'agreement among raft nodes before linearized reading' (duration: 147.719326ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:36:27.040188Z","caller":"traceutil/trace.go:171","msg":"trace[1599083949] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"127.410629ms","start":"2026-04-17T18:36:26.912752Z","end":"2026-04-17T18:36:27.040163Z","steps":["trace[1599083949] 'process raft request' (duration: 126.718626ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:36:27.075928Z","caller":"traceutil/trace.go:171","msg":"trace[1136146305] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"117.894618ms","start":"2026-04-17T18:36:26.95801Z","end":"2026-04-17T18:36:27.075905Z","steps":["trace[1136146305] 'process raft request' (duration: 117.748977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:36:27.650427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.434386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:36:27.650533Z","caller":"traceutil/trace.go:171","msg":"trace[955204033] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"560.495121ms","start":"2026-04-17T18:36:27.090019Z","end":"2026-04-17T18:36:27.650514Z","steps":["trace[955204033] 'process raft request' (duration: 65.905934ms)","trace[955204033] 'compare' (duration: 494.310936ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:36:27.650585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:36:27.089999Z","time spent":"560.566721ms","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:36:42.617185Z","caller":"traceutil/trace.go:171","msg":"trace[1646482379] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"380.353268ms","start":"2026-04-17T18:36:42.2368Z","end":"2026-04-17T18:36:42.617154Z","steps":["trace[1646482379] 'process raft request' (duration: 380.141087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:36:42.617363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:36:42.236775Z","time spent":"380.503768ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:37:11.058645Z","caller":"traceutil/trace.go:171","msg":"trace[1732051618] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1172; }","duration":"163.862636ms","start":"2026-04-17T18:37:10.894718Z","end":"2026-04-17T18:37:11.058581Z","steps":["trace[1732051618] 'read index received' (duration: 163.769023ms)","trace[1732051618] 'applied index is now lower than readState.Index' (duration: 92.093µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:37:11.058655Z","caller":"traceutil/trace.go:171","msg":"trace[413740493] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"253.646969ms","start":"2026-04-17T18:37:10.804962Z","end":"2026-04-17T18:37:11.058609Z","steps":["trace[413740493] 'process raft request' (duration: 253.468563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:11.058786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.017391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:37:11.059114Z","caller":"traceutil/trace.go:171","msg":"trace[1708342394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1143; }","duration":"164.428785ms","start":"2026-04-17T18:37:10.894675Z","end":"2026-04-17T18:37:11.059104Z","steps":["trace[1708342394] 'agreement among raft nodes before linearized reading' (duration: 164.00719ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:37:33.502813Z","caller":"traceutil/trace.go:171","msg":"trace[24877684] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1440; }","duration":"188.527792ms","start":"2026-04-17T18:37:33.314262Z","end":"2026-04-17T18:37:33.50279Z","steps":["trace[24877684] 'read index received' (duration: 188.375668ms)","trace[24877684] 'applied index is now lower than readState.Index' (duration: 150.904µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:37:33.502873Z","caller":"traceutil/trace.go:171","msg":"trace[1487017364] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"195.587802ms","start":"2026-04-17T18:37:33.307248Z","end":"2026-04-17T18:37:33.502835Z","steps":["trace[1487017364] 'process raft request' (duration: 195.383687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:33.502994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.720217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T18:37:33.503029Z","caller":"traceutil/trace.go:171","msg":"trace[1581957457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1402; }","duration":"188.791709ms","start":"2026-04-17T18:37:33.314226Z","end":"2026-04-17T18:37:33.503018Z","steps":["trace[1581957457] 'agreement among raft nodes before linearized reading' (duration: 188.659656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:58.555916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.743885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ccc53810-a493-4913-a636-961ece4ed724\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-17T18:37:58.556072Z","caller":"traceutil/trace.go:171","msg":"trace[597521484] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ccc53810-a493-4913-a636-961ece4ed724; range_end:; response_count:1; response_revision:1814; }","duration":"344.893197ms","start":"2026-04-17T18:37:58.211138Z","end":"2026-04-17T18:37:58.556031Z","steps":["trace[597521484] 'range keys from in-memory index tree' (duration: 344.559102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:58.557047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:37:58.211123Z","time spent":"345.903579ms","remote":"127.0.0.1:57928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ccc53810-a493-4913-a636-961ece4ed724\" "} {"level":"warn","ts":"2026-04-17T18:37:58.55711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.942887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-17T18:37:58.55717Z","caller":"traceutil/trace.go:171","msg":"trace[776629505] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1814; }","duration":"354.035329ms","start":"2026-04-17T18:37:58.203119Z","end":"2026-04-17T18:37:58.557154Z","steps":["trace[776629505] 'range keys from in-memory index tree' (duration: 353.789734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:58.557152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.68692ms","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":"warn","ts":"2026-04-17T18:37:58.557211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:37:58.203098Z","time spent":"354.10225ms","remote":"127.0.0.1:58026","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":"info","ts":"2026-04-17T18:37:58.55724Z","caller":"traceutil/trace.go:171","msg":"trace[1059073903] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1814; }","duration":"363.817142ms","start":"2026-04-17T18:37:58.193398Z","end":"2026-04-17T18:37:58.557215Z","steps":["trace[1059073903] 'range keys from in-memory index tree' (duration: 363.446195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:58.55729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:37:58.193379Z","time spent":"363.900984ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-17T18:37:59.287546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.869286ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-17T18:37:59.287729Z","caller":"traceutil/trace.go:171","msg":"trace[1432169893] linearizableReadLoop","detail":"{readStateIndex:1881; appliedIndex:1880; }","duration":"1.011004353s","start":"2026-04-17T18:37:58.276713Z","end":"2026-04-17T18:37:59.287717Z","steps":["trace[1432169893] 'read index received' (duration: 278.963944ms)","trace[1432169893] 'applied index is now lower than readState.Index' (duration: 732.039689ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:37:59.287856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011145016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T18:37:59.287879Z","caller":"traceutil/trace.go:171","msg":"trace[1603287086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1814; }","duration":"1.011209637s","start":"2026-04-17T18:37:58.276663Z","end":"2026-04-17T18:37:59.287872Z","steps":["trace[1603287086] 'agreement among raft nodes before linearized reading' (duration: 1.011114675s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:59.287902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:37:58.276643Z","time spent":"1.011253518s","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T18:37:59.288098Z","caller":"traceutil/trace.go:171","msg":"trace[1611058821] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"829.407834ms","start":"2026-04-17T18:37:58.458667Z","end":"2026-04-17T18:37:59.288075Z","steps":["trace[1611058821] 'process raft request' (duration: 828.963105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:59.28823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:37:58.458644Z","time spent":"829.521285ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:37:59.432189Z","caller":"traceutil/trace.go:171","msg":"trace[45031179] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"871.333695ms","start":"2026-04-17T18:37:58.56083Z","end":"2026-04-17T18:37:59.432164Z","steps":["trace[45031179] 'process raft request' (duration: 867.404679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:59.432387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:37:58.560805Z","time spent":"871.504199ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:37:59.43837Z","caller":"traceutil/trace.go:171","msg":"trace[1048537490] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"187.499952ms","start":"2026-04-17T18:37:59.250849Z","end":"2026-04-17T18:37:59.438349Z","steps":["trace[1048537490] 'process raft request' (duration: 187.323679ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:37:59.43858Z","caller":"traceutil/trace.go:171","msg":"trace[1184214513] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1882; }","duration":"150.799113ms","start":"2026-04-17T18:37:59.287753Z","end":"2026-04-17T18:37:59.438552Z","steps":["trace[1184214513] 'read index received' (duration: 140.496692ms)","trace[1184214513] 'applied index is now lower than readState.Index' (duration: 10.301101ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:37:59.438791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111333109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:37:59.438833Z","caller":"traceutil/trace.go:171","msg":"trace[1542715863] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1818; }","duration":"1.111457572s","start":"2026-04-17T18:37:58.327366Z","end":"2026-04-17T18:37:59.438823Z","steps":["trace[1542715863] 'agreement among raft nodes before linearized reading' (duration: 1.111285837s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:59.438875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:37:58.327345Z","time spent":"1.111520243s","remote":"127.0.0.1:58106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":90,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:37:59.438864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.436873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-17T18:37:59.43906Z","caller":"traceutil/trace.go:171","msg":"trace[117009519] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"146.114003ms","start":"2026-04-17T18:37:59.292933Z","end":"2026-04-17T18:37:59.439047Z","steps":["trace[117009519] 'process raft request' (duration: 145.369528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:59.439057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.801642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:37:59.43914Z","caller":"traceutil/trace.go:171","msg":"trace[1144889581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1818; }","duration":"543.936193ms","start":"2026-04-17T18:37:58.895186Z","end":"2026-04-17T18:37:59.439123Z","steps":["trace[1144889581] 'agreement among raft nodes before linearized reading' (duration: 543.804631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:59.439185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:37:58.895165Z","time spent":"544.010655ms","remote":"127.0.0.1:57752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:37:59.439175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.602735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a738d77b7e1b9c\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-04-17T18:37:59.439094Z","caller":"traceutil/trace.go:171","msg":"trace[1043965176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1818; }","duration":"159.678898ms","start":"2026-04-17T18:37:59.279404Z","end":"2026-04-17T18:37:59.439083Z","steps":["trace[1043965176] 'agreement among raft nodes before linearized reading' (duration: 159.31934ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:37:59.439302Z","caller":"traceutil/trace.go:171","msg":"trace[622334861] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a738d77b7e1b9c; range_end:; response_count:1; response_revision:1818; }","duration":"635.796679ms","start":"2026-04-17T18:37:58.803454Z","end":"2026-04-17T18:37:59.439251Z","steps":["trace[622334861] 'agreement among raft nodes before linearized reading' (duration: 635.436252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:59.439298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.264529ms","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-17T18:37:59.43944Z","caller":"traceutil/trace.go:171","msg":"trace[473261050] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1818; }","duration":"845.446343ms","start":"2026-04-17T18:37:58.593975Z","end":"2026-04-17T18:37:59.439422Z","steps":["trace[473261050] 'agreement among raft nodes before linearized reading' (duration: 845.033185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:59.439524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:37:58.593935Z","time spent":"845.575536ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-17T18:37:59.439634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109659986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10667"} {"level":"info","ts":"2026-04-17T18:37:59.439675Z","caller":"traceutil/trace.go:171","msg":"trace[936200889] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1818; }","duration":"1.109742558s","start":"2026-04-17T18:37:58.329922Z","end":"2026-04-17T18:37:59.439665Z","steps":["trace[936200889] 'agreement among raft nodes before linearized reading' (duration: 1.109599905s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:59.439701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:37:58.32982Z","time spent":"1.10987471s","remote":"127.0.0.1:57928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10691,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-04-17T18:37:59.43938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:37:58.803435Z","time spent":"635.931191ms","remote":"127.0.0.1:57820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1006,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a738d77b7e1b9c\" "} {"level":"info","ts":"2026-04-17T18:37:59.732447Z","caller":"traceutil/trace.go:171","msg":"trace[1034664708] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1886; }","duration":"170.466359ms","start":"2026-04-17T18:37:59.561959Z","end":"2026-04-17T18:37:59.732425Z","steps":["trace[1034664708] 'read index received' (duration: 162.970323ms)","trace[1034664708] 'applied index is now lower than readState.Index' (duration: 7.495146ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:37:59.732559Z","caller":"traceutil/trace.go:171","msg":"trace[677326139] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"288.273546ms","start":"2026-04-17T18:37:59.444273Z","end":"2026-04-17T18:37:59.732547Z","steps":["trace[677326139] 'process raft request' (duration: 280.459434ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:37:59.732978Z","caller":"traceutil/trace.go:171","msg":"trace[1880422611] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"283.876571ms","start":"2026-04-17T18:37:59.449075Z","end":"2026-04-17T18:37:59.732951Z","steps":["trace[1880422611] 'process raft request' (duration: 283.257308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:59.733032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.061261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ccc53810-a493-4913-a636-961ece4ed724\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-17T18:37:59.733237Z","caller":"traceutil/trace.go:171","msg":"trace[1797085481] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ccc53810-a493-4913-a636-961ece4ed724; range_end:; response_count:1; response_revision:1821; }","duration":"171.168763ms","start":"2026-04-17T18:37:59.5619Z","end":"2026-04-17T18:37:59.733068Z","steps":["trace[1797085481] 'agreement among raft nodes before linearized reading' (duration: 170.983919ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:37:59.738366Z","caller":"traceutil/trace.go:171","msg":"trace[1678632084] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"112.175007ms","start":"2026-04-17T18:37:59.626179Z","end":"2026-04-17T18:37:59.738354Z","steps":["trace[1678632084] 'process raft request' (duration: 112.105405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:37:59.739075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.838024ms","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-17T18:37:59.739108Z","caller":"traceutil/trace.go:171","msg":"trace[935843793] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1822; }","duration":"173.897676ms","start":"2026-04-17T18:37:59.565202Z","end":"2026-04-17T18:37:59.7391Z","steps":["trace[935843793] 'agreement among raft nodes before linearized reading' (duration: 173.813254ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:00.050692Z","caller":"traceutil/trace.go:171","msg":"trace[994391038] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"311.672224ms","start":"2026-04-17T18:37:59.738988Z","end":"2026-04-17T18:38:00.05066Z","steps":["trace[994391038] 'process raft request' (duration: 311.45354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:00.050901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:37:59.738961Z","time spent":"311.844098ms","remote":"127.0.0.1:57908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:38:00.052201Z","caller":"traceutil/trace.go:171","msg":"trace[1203460854] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"312.971179ms","start":"2026-04-17T18:37:59.739201Z","end":"2026-04-17T18:38:00.052173Z","steps":["trace[1203460854] 'process raft request' (duration: 311.41201ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:00.052338Z","caller":"traceutil/trace.go:171","msg":"trace[1340493278] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"312.823947ms","start":"2026-04-17T18:37:59.739458Z","end":"2026-04-17T18:38:00.052282Z","steps":["trace[1340493278] 'process raft request' (duration: 312.551221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:00.052526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:37:59.739193Z","time spent":"313.085662ms","remote":"127.0.0.1:57908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:38:00.052328Z","caller":"traceutil/trace.go:171","msg":"trace[1409109532] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"309.264358ms","start":"2026-04-17T18:37:59.743033Z","end":"2026-04-17T18:38:00.052297Z","steps":["trace[1409109532] 'process raft request' (duration: 309.200435ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:00.052504Z","caller":"traceutil/trace.go:171","msg":"trace[67166940] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"309.495762ms","start":"2026-04-17T18:37:59.742995Z","end":"2026-04-17T18:38:00.05249Z","steps":["trace[67166940] 'process raft request' (duration: 309.170605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:00.05294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:37:59.743017Z","time spent":"309.786098ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:38:00.053111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:37:59.742981Z","time spent":"310.052323ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:38:00.053279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:37:59.739451Z","time spent":"313.136343ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:38:00.078364Z","caller":"traceutil/trace.go:171","msg":"trace[1908054264] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"255.644082ms","start":"2026-04-17T18:37:59.822691Z","end":"2026-04-17T18:38:00.078335Z","steps":["trace[1908054264] 'process raft request' (duration: 255.380547ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:11.472641Z","caller":"traceutil/trace.go:171","msg":"trace[46675105] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"145.614563ms","start":"2026-04-17T18:38:11.327Z","end":"2026-04-17T18:38:11.472615Z","steps":["trace[46675105] 'process raft request' (duration: 145.46202ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:12.929904Z","caller":"traceutil/trace.go:171","msg":"trace[13897219] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"121.291616ms","start":"2026-04-17T18:38:12.808394Z","end":"2026-04-17T18:38:12.929686Z","steps":["trace[13897219] 'process raft request' (duration: 45.712807ms)","trace[13897219] 'compare' (duration: 75.351445ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:12.957842Z","caller":"traceutil/trace.go:171","msg":"trace[233399726] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"147.671456ms","start":"2026-04-17T18:38:12.81015Z","end":"2026-04-17T18:38:12.957822Z","steps":["trace[233399726] 'process raft request' (duration: 147.506733ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:26.293246Z","caller":"traceutil/trace.go:171","msg":"trace[859504253] linearizableReadLoop","detail":"{readStateIndex:2091; appliedIndex:2090; }","duration":"345.20171ms","start":"2026-04-17T18:38:25.948018Z","end":"2026-04-17T18:38:26.29322Z","steps":["trace[859504253] 'read index received' (duration: 345.033447ms)","trace[859504253] 'applied index is now lower than readState.Index' (duration: 167.103µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:26.293314Z","caller":"traceutil/trace.go:171","msg":"trace[1013034236] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"382.712248ms","start":"2026-04-17T18:38:25.910569Z","end":"2026-04-17T18:38:26.293281Z","steps":["trace[1013034236] 'process raft request' (duration: 382.484534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:26.293464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:25.910557Z","time spent":"382.82782ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:38:26.293532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.461243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T18:38:26.293613Z","caller":"traceutil/trace.go:171","msg":"trace[448355935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2019; }","duration":"345.638596ms","start":"2026-04-17T18:38:25.947959Z","end":"2026-04-17T18:38:26.293597Z","steps":["trace[448355935] 'agreement among raft nodes before linearized reading' (duration: 345.410883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:26.293747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:25.947939Z","time spent":"345.741678ms","remote":"127.0.0.1:58026","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-17T18:38:26.298109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.353685ms","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-17T18:38:26.298181Z","caller":"traceutil/trace.go:171","msg":"trace[1406767902] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2021; }","duration":"236.450786ms","start":"2026-04-17T18:38:26.061712Z","end":"2026-04-17T18:38:26.298163Z","steps":["trace[1406767902] 'agreement among raft nodes before linearized reading' (duration: 236.271953ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:26.298575Z","caller":"traceutil/trace.go:171","msg":"trace[1662271470] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"104.821455ms","start":"2026-04-17T18:38:26.193707Z","end":"2026-04-17T18:38:26.298529Z","steps":["trace[1662271470] 'process raft request' (duration: 104.120783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:26.299733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.997598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-17T18:38:26.299982Z","caller":"traceutil/trace.go:171","msg":"trace[100683002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2021; }","duration":"107.308293ms","start":"2026-04-17T18:38:26.192658Z","end":"2026-04-17T18:38:26.299966Z","steps":["trace[100683002] 'agreement among raft nodes before linearized reading' (duration: 105.84265ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:33.267184Z","caller":"traceutil/trace.go:171","msg":"trace[2033867130] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"110.207331ms","start":"2026-04-17T18:38:33.156951Z","end":"2026-04-17T18:38:33.267158Z","steps":["trace[2033867130] 'process raft request' (duration: 110.047508ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:33.902968Z","caller":"traceutil/trace.go:171","msg":"trace[500378233] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"234.443631ms","start":"2026-04-17T18:38:33.668503Z","end":"2026-04-17T18:38:33.902946Z","steps":["trace[500378233] 'process raft request' (duration: 234.298569ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:33.91237Z","caller":"traceutil/trace.go:171","msg":"trace[1173543297] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"242.902666ms","start":"2026-04-17T18:38:33.669442Z","end":"2026-04-17T18:38:33.912344Z","steps":["trace[1173543297] 'process raft request' (duration: 242.752003ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:34.520307Z","caller":"traceutil/trace.go:171","msg":"trace[1370150106] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"203.284179ms","start":"2026-04-17T18:38:34.316996Z","end":"2026-04-17T18:38:34.520281Z","steps":["trace[1370150106] 'process raft request' (duration: 203.118245ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:34.668323Z","caller":"traceutil/trace.go:171","msg":"trace[1256348851] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"343.090622ms","start":"2026-04-17T18:38:34.3252Z","end":"2026-04-17T18:38:34.668291Z","steps":["trace[1256348851] 'process raft request' (duration: 342.793038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:34.668586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:34.325178Z","time spent":"343.225735ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:38:34.66915Z","caller":"traceutil/trace.go:171","msg":"trace[1633203282] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"340.957962ms","start":"2026-04-17T18:38:34.328182Z","end":"2026-04-17T18:38:34.66914Z","steps":["trace[1633203282] 'process raft request' (duration: 340.033137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:34.669263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:34.328154Z","time spent":"341.027893ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:38:34.669102Z","caller":"traceutil/trace.go:171","msg":"trace[2095063562] linearizableReadLoop","detail":"{readStateIndex:2139; appliedIndex:2136; }","duration":"329.2395ms","start":"2026-04-17T18:38:34.339846Z","end":"2026-04-17T18:38:34.669085Z","steps":["trace[2095063562] 'read index received' (duration: 180.343901ms)","trace[2095063562] 'applied index is now lower than readState.Index' (duration: 148.894449ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:38:34.669654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.784168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T18:38:34.669801Z","caller":"traceutil/trace.go:171","msg":"trace[1306641863] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2065; }","duration":"329.966391ms","start":"2026-04-17T18:38:34.339812Z","end":"2026-04-17T18:38:34.669779Z","steps":["trace[1306641863] 'agreement among raft nodes before linearized reading' (duration: 329.417993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:34.669862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:34.3398Z","time spent":"330.045942ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T18:38:34.66997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.294217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:38:34.670507Z","caller":"traceutil/trace.go:171","msg":"trace[235857017] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2065; }","duration":"158.45299ms","start":"2026-04-17T18:38:34.511588Z","end":"2026-04-17T18:38:34.670041Z","steps":["trace[235857017] 'agreement among raft nodes before linearized reading' (duration: 158.337168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:34.670515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.919277ms","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-17T18:38:34.670582Z","caller":"traceutil/trace.go:171","msg":"trace[1004409065] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2065; }","duration":"321.005948ms","start":"2026-04-17T18:38:34.349558Z","end":"2026-04-17T18:38:34.670564Z","steps":["trace[1004409065] 'agreement among raft nodes before linearized reading' (duration: 320.840616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:34.670618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:34.349544Z","time spent":"321.065539ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-17T18:38:34.671243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.176098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T18:38:34.671307Z","caller":"traceutil/trace.go:171","msg":"trace[125857613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2065; }","duration":"331.271859ms","start":"2026-04-17T18:38:34.340022Z","end":"2026-04-17T18:38:34.671294Z","steps":["trace[125857613] 'agreement among raft nodes before linearized reading' (duration: 329.825809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:34.671207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.088351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-17T18:38:34.671378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:34.340007Z","time spent":"331.360481ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T18:38:34.671389Z","caller":"traceutil/trace.go:171","msg":"trace[1725725815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2065; }","duration":"330.296784ms","start":"2026-04-17T18:38:34.34108Z","end":"2026-04-17T18:38:34.671376Z","steps":["trace[1725725815] 'agreement among raft nodes before linearized reading' (duration: 329.3534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:34.67143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:34.341068Z","time spent":"330.352184ms","remote":"127.0.0.1:58026","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-17T18:38:36.119452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.340682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:38:36.119554Z","caller":"traceutil/trace.go:171","msg":"trace[1872174610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2070; }","duration":"224.486574ms","start":"2026-04-17T18:38:35.895038Z","end":"2026-04-17T18:38:36.119525Z","steps":["trace[1872174610] 'range keys from in-memory index tree' (duration: 224.259791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:36.119768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.389377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:38:36.119858Z","caller":"traceutil/trace.go:171","msg":"trace[1540550001] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2070; }","duration":"286.549881ms","start":"2026-04-17T18:38:35.83329Z","end":"2026-04-17T18:38:36.11984Z","steps":["trace[1540550001] 'count revisions from in-memory index tree' (duration: 286.258096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:36.119853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.234511ms","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-17T18:38:36.119975Z","caller":"traceutil/trace.go:171","msg":"trace[178860597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2070; }","duration":"213.413334ms","start":"2026-04-17T18:38:35.90655Z","end":"2026-04-17T18:38:36.119963Z","steps":["trace[178860597] 'range keys from in-memory index tree' (duration: 213.090159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:36.119937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.527009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T18:38:36.120049Z","caller":"traceutil/trace.go:171","msg":"trace[429252082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2070; }","duration":"203.665641ms","start":"2026-04-17T18:38:35.916358Z","end":"2026-04-17T18:38:36.120024Z","steps":["trace[429252082] 'range keys from in-memory index tree' (duration: 203.302896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:36.120108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.048852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:38:36.120221Z","caller":"traceutil/trace.go:171","msg":"trace[935177918] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2070; }","duration":"118.365487ms","start":"2026-04-17T18:38:36.001795Z","end":"2026-04-17T18:38:36.120161Z","steps":["trace[935177918] 'count revisions from in-memory index tree' (duration: 117.91521ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:36.298324Z","caller":"traceutil/trace.go:171","msg":"trace[918572841] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"174.280876ms","start":"2026-04-17T18:38:36.124026Z","end":"2026-04-17T18:38:36.298307Z","steps":["trace[918572841] 'process raft request' (duration: 174.172414ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:36.299618Z","caller":"traceutil/trace.go:171","msg":"trace[1373031926] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"174.470429ms","start":"2026-04-17T18:38:36.125136Z","end":"2026-04-17T18:38:36.299606Z","steps":["trace[1373031926] 'process raft request' (duration: 174.412828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:39.188699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095269950188461,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:38:39.689727Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095269950188461,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:38:40.190766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095269950188461,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:38:40.691806Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095269950188461,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:38:40.897421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.003566457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-17T18:38:40.897662Z","caller":"traceutil/trace.go:171","msg":"trace[323229021] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.004813034s","start":"2026-04-17T18:38:38.892803Z","end":"2026-04-17T18:38:40.897616Z","steps":["trace[323229021] 'agreement among raft nodes before linearized reading' (duration: 2.003565097s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:40.897839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:38.892773Z","time spent":"2.005054908s","remote":"127.0.0.1:57752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/17 18:38:40 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-17T18:38:40.939325Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.631605444s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T18:38:40.939812Z","caller":"traceutil/trace.go:171","msg":"trace[2007011152] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"2.632212073s","start":"2026-04-17T18:38:38.307579Z","end":"2026-04-17T18:38:40.939791Z","steps":["trace[2007011152] 'process raft request' (duration: 2.632048481s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:40.939938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:38.307547Z","time spent":"2.632320314s","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:38:41.010135Z","caller":"traceutil/trace.go:171","msg":"trace[1789572501] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"2.701867453s","start":"2026-04-17T18:38:38.308239Z","end":"2026-04-17T18:38:41.010106Z","steps":["trace[1789572501] 'process raft request' (duration: 2.700005528s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:41.010336Z","caller":"traceutil/trace.go:171","msg":"trace[1445804391] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"2.699823204s","start":"2026-04-17T18:38:38.310488Z","end":"2026-04-17T18:38:41.010311Z","steps":["trace[1445804391] 'process raft request' (duration: 2.69954419s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:41.010504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:38.310445Z","time spent":"2.699988957s","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:38:41.01051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:38.308203Z","time spent":"2.702016176s","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:38:41.124256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.041177ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-17T18:38:41.124933Z","caller":"traceutil/trace.go:171","msg":"trace[1155709365] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"2.595327388s","start":"2026-04-17T18:38:38.529585Z","end":"2026-04-17T18:38:41.124912Z","steps":["trace[1155709365] 'process raft request' (duration: 2.59477417s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:41.125094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:38.529561Z","time spent":"2.595431049s","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:38:41.15305Z","caller":"traceutil/trace.go:171","msg":"trace[1253107177] linearizableReadLoop","detail":"{readStateIndex:2163; appliedIndex:2155; }","duration":"2.464901826s","start":"2026-04-17T18:38:38.688121Z","end":"2026-04-17T18:38:41.153023Z","steps":["trace[1253107177] 'read index received' (duration: 2.251517792s)","trace[1253107177] 'applied index is now lower than readState.Index' (duration: 213.382314ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:41.153263Z","caller":"traceutil/trace.go:171","msg":"trace[792505202] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"1.383472775s","start":"2026-04-17T18:38:39.769755Z","end":"2026-04-17T18:38:41.153227Z","steps":["trace[792505202] 'process raft request' (duration: 1.383214752s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:41.153405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:39.769729Z","time spent":"1.383600746s","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:38:41.153453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.465314442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T18:38:41.15342Z","caller":"traceutil/trace.go:171","msg":"trace[814871200] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"2.47436181s","start":"2026-04-17T18:38:38.679037Z","end":"2026-04-17T18:38:41.153399Z","steps":["trace[814871200] 'process raft request' (duration: 2.445864721s)","trace[814871200] 'compare' (duration: 27.541716ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:41.153511Z","caller":"traceutil/trace.go:171","msg":"trace[1541866877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2088; }","duration":"2.465413113s","start":"2026-04-17T18:38:38.688082Z","end":"2026-04-17T18:38:41.153495Z","steps":["trace[1541866877] 'agreement among raft nodes before linearized reading' (duration: 2.46523992s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:41.153556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:38.688049Z","time spent":"2.465495614s","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T18:38:41.153631Z","caller":"traceutil/trace.go:171","msg":"trace[623006772] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"2.472469624s","start":"2026-04-17T18:38:38.681012Z","end":"2026-04-17T18:38:41.153482Z","steps":["trace[623006772] 'process raft request' (duration: 2.471846195s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:41.153667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:38.679013Z","time spent":"2.474511622s","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:38:41.153739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.963800702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:38:41.153759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.448903417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T18:38:41.153803Z","caller":"traceutil/trace.go:171","msg":"trace[1139588642] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2088; }","duration":"2.448954259s","start":"2026-04-17T18:38:38.704835Z","end":"2026-04-17T18:38:41.153789Z","steps":["trace[1139588642] 'agreement among raft nodes before linearized reading' (duration: 2.448861157s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:41.153801Z","caller":"traceutil/trace.go:171","msg":"trace[317821524] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2088; }","duration":"1.963944035s","start":"2026-04-17T18:38:39.189841Z","end":"2026-04-17T18:38:41.153785Z","steps":["trace[317821524] 'agreement among raft nodes before linearized reading' (duration: 1.963858523s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:41.153887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:38.704829Z","time spent":"2.4490471s","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T18:38:41.153908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:39.1898Z","time spent":"1.964093667s","remote":"127.0.0.1:53222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:38:41.153806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:38.681003Z","time spent":"2.472710747s","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:38:41.153863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.37980903s","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":"warn","ts":"2026-04-17T18:38:41.153952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.918148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:38:41.153993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.328043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:38:41.154026Z","caller":"traceutil/trace.go:171","msg":"trace[1345868478] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2088; }","duration":"257.640868ms","start":"2026-04-17T18:38:40.896374Z","end":"2026-04-17T18:38:41.154015Z","steps":["trace[1345868478] 'agreement among raft nodes before linearized reading' (duration: 257.593127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:41.153807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.338103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:38:41.154104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.841843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:38:41.154027Z","caller":"traceutil/trace.go:171","msg":"trace[889905753] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2088; }","duration":"2.380077964s","start":"2026-04-17T18:38:38.773938Z","end":"2026-04-17T18:38:41.154016Z","steps":["trace[889905753] 'agreement among raft nodes before linearized reading' (duration: 2.379781779s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:41.153674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.449955421s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T18:38:41.154026Z","caller":"traceutil/trace.go:171","msg":"trace[1396824540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2088; }","duration":"256.999889ms","start":"2026-04-17T18:38:40.897013Z","end":"2026-04-17T18:38:41.154013Z","steps":["trace[1396824540] 'agreement among raft nodes before linearized reading' (duration: 256.902478ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:41.154161Z","caller":"traceutil/trace.go:171","msg":"trace[1460009542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2088; }","duration":"2.45047098s","start":"2026-04-17T18:38:38.703676Z","end":"2026-04-17T18:38:41.154147Z","steps":["trace[1460009542] 'agreement among raft nodes before linearized reading' (duration: 2.449912861s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:41.154173Z","caller":"traceutil/trace.go:171","msg":"trace[392977726] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2088; }","duration":"211.881723ms","start":"2026-04-17T18:38:40.942249Z","end":"2026-04-17T18:38:41.154131Z","steps":["trace[392977726] 'agreement among raft nodes before linearized reading' (duration: 211.796152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:41.154148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:38.773909Z","time spent":"2.380230226s","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-17T18:38:41.15424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:38.703661Z","time spent":"2.450563981s","remote":"127.0.0.1:58026","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":"info","ts":"2026-04-17T18:38:41.154277Z","caller":"traceutil/trace.go:171","msg":"trace[1657748511] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2088; }","duration":"257.81641ms","start":"2026-04-17T18:38:40.896297Z","end":"2026-04-17T18:38:41.154113Z","steps":["trace[1657748511] 'agreement among raft nodes before linearized reading' (duration: 257.349333ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:47.523094Z","caller":"traceutil/trace.go:171","msg":"trace[1525944777] transaction","detail":"{read_only:false; response_revision:2530; number_of_response:1; }","duration":"127.676606ms","start":"2026-04-17T18:39:47.395401Z","end":"2026-04-17T18:39:47.523078Z","steps":["trace[1525944777] 'process raft request' (duration: 127.539405ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:40:17.386445Z","caller":"traceutil/trace.go:171","msg":"trace[278192725] transaction","detail":"{read_only:false; response_revision:2708; number_of_response:1; }","duration":"206.376163ms","start":"2026-04-17T18:40:17.180025Z","end":"2026-04-17T18:40:17.386401Z","steps":["trace[278192725] 'process raft request' (duration: 206.155681ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:41:54.091288Z","caller":"traceutil/trace.go:171","msg":"trace[485868782] transaction","detail":"{read_only:false; response_revision:3214; number_of_response:1; }","duration":"101.511382ms","start":"2026-04-17T18:41:53.98975Z","end":"2026-04-17T18:41:54.091262Z","steps":["trace[485868782] 'process raft request' (duration: 59.731369ms)","trace[485868782] 'compare' (duration: 41.33016ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:43:52.871053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.55694ms","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-17T18:43:52.87112Z","caller":"traceutil/trace.go:171","msg":"trace[657605006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4035; }","duration":"110.667352ms","start":"2026-04-17T18:43:52.76044Z","end":"2026-04-17T18:43:52.871107Z","steps":["trace[657605006] 'range keys from in-memory index tree' (duration: 110.412958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:43:52.871119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.473803ms","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-17T18:43:52.871404Z","caller":"traceutil/trace.go:171","msg":"trace[177680304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4035; }","duration":"108.791518ms","start":"2026-04-17T18:43:52.762593Z","end":"2026-04-17T18:43:52.871384Z","steps":["trace[177680304] 'range keys from in-memory index tree' (duration: 108.31254ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:53.072275Z","caller":"traceutil/trace.go:171","msg":"trace[1107577344] linearizableReadLoop","detail":"{readStateIndex:4199; appliedIndex:4195; }","duration":"186.577367ms","start":"2026-04-17T18:43:52.88568Z","end":"2026-04-17T18:43:53.072257Z","steps":["trace[1107577344] 'read index received' (duration: 10.612015ms)","trace[1107577344] 'applied index is now lower than readState.Index' (duration: 175.964432ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:43:53.072321Z","caller":"traceutil/trace.go:171","msg":"trace[2042551765] transaction","detail":"{read_only:false; response_revision:4038; number_of_response:1; }","duration":"195.814555ms","start":"2026-04-17T18:43:52.876475Z","end":"2026-04-17T18:43:53.072289Z","steps":["trace[2042551765] 'process raft request' (duration: 195.724323ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:53.07234Z","caller":"traceutil/trace.go:171","msg":"trace[1714882490] transaction","detail":"{read_only:false; response_revision:4037; number_of_response:1; }","duration":"195.832585ms","start":"2026-04-17T18:43:52.876477Z","end":"2026-04-17T18:43:53.07231Z","steps":["trace[1714882490] 'process raft request' (duration: 195.612351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:43:53.07258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.882953ms","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-17T18:43:53.072616Z","caller":"traceutil/trace.go:171","msg":"trace[1485535789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4039; }","duration":"186.949194ms","start":"2026-04-17T18:43:52.885657Z","end":"2026-04-17T18:43:53.072606Z","steps":["trace[1485535789] 'agreement among raft nodes before linearized reading' (duration: 186.694569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:43:53.072598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.065925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-create-2hdx6\" ","response":"range_response_count:1 size:4356"} {"level":"info","ts":"2026-04-17T18:43:53.072687Z","caller":"traceutil/trace.go:171","msg":"trace[1287616746] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-create-2hdx6; range_end:; response_count:1; response_revision:4039; }","duration":"170.205588ms","start":"2026-04-17T18:43:52.902459Z","end":"2026-04-17T18:43:53.072665Z","steps":["trace[1287616746] 'agreement among raft nodes before linearized reading' (duration: 169.940123ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:53.073175Z","caller":"traceutil/trace.go:171","msg":"trace[1827657615] transaction","detail":"{read_only:false; response_revision:4039; number_of_response:1; }","duration":"196.171092ms","start":"2026-04-17T18:43:52.876934Z","end":"2026-04-17T18:43:53.073105Z","steps":["trace[1827657615] 'process raft request' (duration: 195.293376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:43:53.073178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.038812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-17T18:43:53.073439Z","caller":"traceutil/trace.go:171","msg":"trace[1761182011] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:4039; }","duration":"131.413438ms","start":"2026-04-17T18:43:52.941833Z","end":"2026-04-17T18:43:53.073246Z","steps":["trace[1761182011] 'agreement among raft nodes before linearized reading' (duration: 130.838448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:03.637467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.43633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:21 size:489625"} {"level":"info","ts":"2026-04-17T18:44:03.63761Z","caller":"traceutil/trace.go:171","msg":"trace[675009412] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:21; response_revision:4521; }","duration":"155.573683ms","start":"2026-04-17T18:44:03.481975Z","end":"2026-04-17T18:44:03.637548Z","steps":["trace[675009412] 'range keys from in-memory index tree' (duration: 155.031742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:03.638145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.159623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193158"} {"level":"info","ts":"2026-04-17T18:44:03.638272Z","caller":"traceutil/trace.go:171","msg":"trace[1300005436] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4521; }","duration":"152.308246ms","start":"2026-04-17T18:44:03.485944Z","end":"2026-04-17T18:44:03.638253Z","steps":["trace[1300005436] 'range keys from in-memory index tree' (duration: 151.093643ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:12.903253Z","caller":"traceutil/trace.go:171","msg":"trace[1287984864] transaction","detail":"{read_only:false; response_revision:4705; number_of_response:1; }","duration":"250.887939ms","start":"2026-04-17T18:44:12.652337Z","end":"2026-04-17T18:44:12.903225Z","steps":["trace[1287984864] 'process raft request' (duration: 184.592695ms)","trace[1287984864] 'compare' (duration: 66.02149ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:44:12.957886Z","caller":"traceutil/trace.go:171","msg":"trace[803012284] transaction","detail":"{read_only:false; response_revision:4706; number_of_response:1; }","duration":"305.223126ms","start":"2026-04-17T18:44:12.652633Z","end":"2026-04-17T18:44:12.957856Z","steps":["trace[803012284] 'process raft request' (duration: 305.025173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:12.958048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:44:12.652608Z","time spent":"305.358718ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:44:12.958282Z","caller":"traceutil/trace.go:171","msg":"trace[370699878] linearizableReadLoop","detail":"{readStateIndex:4880; appliedIndex:4877; }","duration":"185.812066ms","start":"2026-04-17T18:44:12.772446Z","end":"2026-04-17T18:44:12.958258Z","steps":["trace[370699878] 'read index received' (duration: 64.495772ms)","trace[370699878] 'applied index is now lower than readState.Index' (duration: 121.315134ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:44:12.9584Z","caller":"traceutil/trace.go:171","msg":"trace[853254349] transaction","detail":"{read_only:false; response_revision:4708; number_of_response:1; }","duration":"138.235856ms","start":"2026-04-17T18:44:12.820142Z","end":"2026-04-17T18:44:12.958378Z","steps":["trace[853254349] 'process raft request' (duration: 138.012832ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:12.95829Z","caller":"traceutil/trace.go:171","msg":"trace[580166082] transaction","detail":"{read_only:false; response_revision:4707; number_of_response:1; }","duration":"301.315829ms","start":"2026-04-17T18:44:12.656945Z","end":"2026-04-17T18:44:12.95826Z","steps":["trace[580166082] 'process raft request' (duration: 300.86382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:12.958632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:44:12.656929Z","time spent":"301.610144ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:44:12.95891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.446747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e818d0aa-5f85-4577-becd-cc90d50c11ae\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-17T18:44:12.959603Z","caller":"traceutil/trace.go:171","msg":"trace[918333329] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e818d0aa-5f85-4577-becd-cc90d50c11ae; range_end:; response_count:1; response_revision:4708; }","duration":"187.180979ms","start":"2026-04-17T18:44:12.772397Z","end":"2026-04-17T18:44:12.959577Z","steps":["trace[918333329] 'agreement among raft nodes before linearized reading' (duration: 186.03301ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:14.992666Z","caller":"traceutil/trace.go:171","msg":"trace[1604282411] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"131.185071ms","start":"2026-04-17T18:44:14.861459Z","end":"2026-04-17T18:44:14.992644Z","steps":["trace[1604282411] 'process raft request' (duration: 131.011748ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:15.029842Z","caller":"traceutil/trace.go:171","msg":"trace[781438378] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"144.936266ms","start":"2026-04-17T18:44:14.884872Z","end":"2026-04-17T18:44:15.029808Z","steps":["trace[781438378] 'process raft request' (duration: 144.643302ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:15.030551Z","caller":"traceutil/trace.go:171","msg":"trace[1506237582] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"122.46891ms","start":"2026-04-17T18:44:14.908018Z","end":"2026-04-17T18:44:15.030487Z","steps":["trace[1506237582] 'process raft request' (duration: 121.714297ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:15.03057Z","caller":"traceutil/trace.go:171","msg":"trace[1789053903] linearizableReadLoop","detail":"{readStateIndex:4909; appliedIndex:4908; }","duration":"138.20783ms","start":"2026-04-17T18:44:14.892337Z","end":"2026-04-17T18:44:15.030545Z","steps":["trace[1789053903] 'read index received' (duration: 100.414673ms)","trace[1789053903] 'applied index is now lower than readState.Index' (duration: 37.791987ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:44:15.030775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.397693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:44:15.030809Z","caller":"traceutil/trace.go:171","msg":"trace[60584766] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:4737; }","duration":"138.486055ms","start":"2026-04-17T18:44:14.892311Z","end":"2026-04-17T18:44:15.030797Z","steps":["trace[60584766] 'agreement among raft nodes before linearized reading' (duration: 138.395934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:15.04325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.522991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:44:15.043359Z","caller":"traceutil/trace.go:171","msg":"trace[526563261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4738; }","duration":"150.660244ms","start":"2026-04-17T18:44:14.892677Z","end":"2026-04-17T18:44:15.043337Z","steps":["trace[526563261] 'agreement among raft nodes before linearized reading' (duration: 150.497981ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:15.183568Z","caller":"traceutil/trace.go:171","msg":"trace[568464371] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"136.274433ms","start":"2026-04-17T18:44:15.046633Z","end":"2026-04-17T18:44:15.182907Z","steps":["trace[568464371] 'process raft request' (duration: 135.957287ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:17.159078Z","caller":"traceutil/trace.go:171","msg":"trace[1141529554] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"123.422751ms","start":"2026-04-17T18:44:17.035541Z","end":"2026-04-17T18:44:17.158964Z","steps":["trace[1141529554] 'process raft request' (duration: 123.284938ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:17.160348Z","caller":"traceutil/trace.go:171","msg":"trace[492431059] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"124.698943ms","start":"2026-04-17T18:44:17.035639Z","end":"2026-04-17T18:44:17.160338Z","steps":["trace[492431059] 'process raft request' (duration: 124.463628ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:17.160364Z","caller":"traceutil/trace.go:171","msg":"trace[108741681] linearizableReadLoop","detail":"{readStateIndex:4939; appliedIndex:4936; }","duration":"107.229976ms","start":"2026-04-17T18:44:17.053109Z","end":"2026-04-17T18:44:17.160339Z","steps":["trace[108741681] 'read index received' (duration: 105.7429ms)","trace[108741681] 'applied index is now lower than readState.Index' (duration: 1.486266ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:44:17.16053Z","caller":"traceutil/trace.go:171","msg":"trace[791133310] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"124.322457ms","start":"2026-04-17T18:44:17.036192Z","end":"2026-04-17T18:44:17.160514Z","steps":["trace[791133310] 'process raft request' (duration: 124.011961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:17.160578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.453369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e818d0aa-5f85-4577-becd-cc90d50c11ae\" ","response":"range_response_count:1 size:3950"} {"level":"info","ts":"2026-04-17T18:44:17.160628Z","caller":"traceutil/trace.go:171","msg":"trace[1375470520] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e818d0aa-5f85-4577-becd-cc90d50c11ae; range_end:; response_count:1; response_revision:4764; }","duration":"107.542091ms","start":"2026-04-17T18:44:17.053074Z","end":"2026-04-17T18:44:17.160616Z","steps":["trace[1375470520] 'agreement among raft nodes before linearized reading' (duration: 107.356028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:27.866758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.856311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-17T18:44:27.866778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.808417ms","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-17T18:44:27.866824Z","caller":"traceutil/trace.go:171","msg":"trace[1123827745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4894; }","duration":"455.934069ms","start":"2026-04-17T18:44:27.410876Z","end":"2026-04-17T18:44:27.86681Z","steps":["trace[1123827745] 'range keys from in-memory index tree' (duration: 455.504022ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:27.866824Z","caller":"traceutil/trace.go:171","msg":"trace[123509004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4894; }","duration":"428.983332ms","start":"2026-04-17T18:44:27.437822Z","end":"2026-04-17T18:44:27.866806Z","steps":["trace[123509004] 'range keys from in-memory index tree' (duration: 428.651287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:27.866899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:44:27.437802Z","time spent":"429.085485ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T18:44:27.866958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.384612ms","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-17T18:44:27.867114Z","caller":"traceutil/trace.go:171","msg":"trace[1412721819] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4894; }","duration":"428.766729ms","start":"2026-04-17T18:44:27.438322Z","end":"2026-04-17T18:44:27.867089Z","steps":["trace[1412721819] 'range keys from in-memory index tree' (duration: 428.192029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:27.867167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.505824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:44:27.867231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.163083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-17T18:44:27.867249Z","caller":"traceutil/trace.go:171","msg":"trace[1966629743] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4894; }","duration":"215.625275ms","start":"2026-04-17T18:44:27.651607Z","end":"2026-04-17T18:44:27.867233Z","steps":["trace[1966629743] 'count revisions from in-memory index tree' (duration: 215.445582ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:27.867275Z","caller":"traceutil/trace.go:171","msg":"trace[766696073] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4894; }","duration":"275.243345ms","start":"2026-04-17T18:44:27.592019Z","end":"2026-04-17T18:44:27.867262Z","steps":["trace[766696073] 'range keys from in-memory index tree' (duration: 274.891569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:27.867224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:44:27.438311Z","time spent":"428.888451ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T18:44:27.866896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:44:27.410856Z","time spent":"456.02799ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T18:44:27.866744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.524868ms","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-17T18:44:27.868753Z","caller":"traceutil/trace.go:171","msg":"trace[1932328867] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4894; }","duration":"429.555301ms","start":"2026-04-17T18:44:27.439181Z","end":"2026-04-17T18:44:27.868736Z","steps":["trace[1932328867] 'range keys from in-memory index tree' (duration: 427.371826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:27.869696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:44:27.439171Z","time spent":"430.441837ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T18:44:28.480328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.75934ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-17T18:44:28.480442Z","caller":"traceutil/trace.go:171","msg":"trace[118837303] linearizableReadLoop","detail":"{readStateIndex:5085; appliedIndex:5084; }","duration":"151.605078ms","start":"2026-04-17T18:44:28.328815Z","end":"2026-04-17T18:44:28.48042Z","steps":["trace[118837303] 'read index received' (duration: 8.708504ms)","trace[118837303] 'applied index is now lower than readState.Index' (duration: 142.893984ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:44:28.480811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.980454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16550"} {"level":"info","ts":"2026-04-17T18:44:28.480866Z","caller":"traceutil/trace.go:171","msg":"trace[312463643] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4904; }","duration":"152.069056ms","start":"2026-04-17T18:44:28.32878Z","end":"2026-04-17T18:44:28.480849Z","steps":["trace[312463643] 'agreement among raft nodes before linearized reading' (duration: 151.6972ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:07.427333Z","caller":"traceutil/trace.go:171","msg":"trace[1694001156] transaction","detail":"{read_only:false; response_revision:5246; number_of_response:1; }","duration":"102.638653ms","start":"2026-04-17T18:45:07.324668Z","end":"2026-04-17T18:45:07.427306Z","steps":["trace[1694001156] 'process raft request' (duration: 102.418819ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:50.040226Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2888} {"level":"info","ts":"2026-04-17T18:45:50.170528Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2888,"took":"128.950677ms","hash":319036206,"current-db-size-bytes":53063680,"current-db-size":"53 MB","current-db-size-in-use-bytes":29020160,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-17T18:45:50.170602Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":319036206,"revision":2888,"compact-revision":-1} {"level":"info","ts":"2026-04-17T18:50:50.047336Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5557} {"level":"info","ts":"2026-04-17T18:50:50.14041Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5557,"took":"91.321191ms","hash":1460615761,"current-db-size-bytes":53063680,"current-db-size":"53 MB","current-db-size-in-use-bytes":18411520,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-17T18:50:50.140463Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1460615761,"revision":5557,"compact-revision":2888}