{"level":"warn","ts":"2026-05-05T07:38:37.641842Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T07:38:37.641929Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T07:38:37.642013Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T07:38:37.642025Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-05-05T07:38:37.64206Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T07:38:37.642694Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-05-05T07:38:37.642863Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T07:38:37.647251Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.076082ms"} {"level":"info","ts":"2026-05-05T07:38:37.653252Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-05-05T07:38:37.653346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T07:38:37.653376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-05-05T07:38:37.653389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T07:38:37.653427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-05-05T07:38:37.653482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-05-05T07:38:37.660576Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T07:38:37.662487Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T07:38:37.66393Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T07:38:37.666815Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T07:38:37.667021Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T07:38:37.667288Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T07:38:37.667367Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T07:38:37.667383Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T07:38:37.667882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-05-05T07:38:37.66798Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-05-05T07:38:37.672359Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T07:38:37.672424Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-05-05T07:38:37.672458Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-05-05T07:38:37.672651Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T07:38:37.672685Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T07:38:38.254059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T07:38:38.254119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T07:38:38.254159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-05-05T07:38:38.25418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-05-05T07:38:38.254192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-05-05T07:38:38.254215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-05-05T07:38:38.25423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-05-05T07:38:38.255486Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T07:38:38.255531Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T07:38:38.255778Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T07:38:38.255817Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T07:38:38.255927Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T07:38:38.256048Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T07:38:38.259001Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-05-05T07:38:38.25954Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T07:38:38.261165Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T07:38:38.261287Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T07:38:38.261327Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T07:38:58.996556Z","caller":"traceutil/trace.go:171","msg":"trace[1209439727] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"136.108806ms","start":"2026-05-05T07:38:58.860418Z","end":"2026-05-05T07:38:58.996527Z","steps":["trace[1209439727] 'process raft request' (duration: 135.931902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:38:59.208647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.829163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T07:38:59.208762Z","caller":"traceutil/trace.go:171","msg":"trace[203912103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:456; }","duration":"153.032488ms","start":"2026-05-05T07:38:59.05571Z","end":"2026-05-05T07:38:59.208742Z","steps":["trace[203912103] 'range keys from in-memory index tree' (duration: 152.70612ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:39:01.355097Z","caller":"traceutil/trace.go:171","msg":"trace[556046508] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"257.285331ms","start":"2026-05-05T07:39:01.097785Z","end":"2026-05-05T07:39:01.35507Z","steps":["trace[556046508] 'process raft request' (duration: 257.164098ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:39:01.362983Z","caller":"traceutil/trace.go:171","msg":"trace[692542575] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"257.535477ms","start":"2026-05-05T07:39:01.10541Z","end":"2026-05-05T07:39:01.362945Z","steps":["trace[692542575] 'process raft request' (duration: 257.249399ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:39:01.363036Z","caller":"traceutil/trace.go:171","msg":"trace[1322058512] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"249.541437ms","start":"2026-05-05T07:39:01.113437Z","end":"2026-05-05T07:39:01.362979Z","steps":["trace[1322058512] 'process raft request' (duration: 249.363253ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:39:01.363269Z","caller":"traceutil/trace.go:171","msg":"trace[841476714] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"230.451221ms","start":"2026-05-05T07:39:01.13278Z","end":"2026-05-05T07:39:01.363232Z","steps":["trace[841476714] 'process raft request' (duration: 230.156463ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:39:01.363393Z","caller":"traceutil/trace.go:171","msg":"trace[1944959276] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"221.301762ms","start":"2026-05-05T07:39:01.142079Z","end":"2026-05-05T07:39:01.363381Z","steps":["trace[1944959276] 'process raft request' (duration: 220.970874ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:39:01.363374Z","caller":"traceutil/trace.go:171","msg":"trace[861943475] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"232.060601ms","start":"2026-05-05T07:39:01.131284Z","end":"2026-05-05T07:39:01.363344Z","steps":["trace[861943475] 'process raft request' (duration: 231.569068ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:39:01.363609Z","caller":"traceutil/trace.go:171","msg":"trace[236648684] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"214.207666ms","start":"2026-05-05T07:39:01.149387Z","end":"2026-05-05T07:39:01.363595Z","steps":["trace[236648684] 'process raft request' (duration: 213.852847ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:39:01.364331Z","caller":"traceutil/trace.go:171","msg":"trace[202113205] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"206.436202ms","start":"2026-05-05T07:39:01.157839Z","end":"2026-05-05T07:39:01.364275Z","steps":["trace[202113205] 'process raft request' (duration: 205.517299ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:39:01.36479Z","caller":"traceutil/trace.go:171","msg":"trace[1587690447] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"192.296999ms","start":"2026-05-05T07:39:01.172331Z","end":"2026-05-05T07:39:01.364628Z","steps":["trace[1587690447] 'process raft request' (duration: 191.16108ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:39:01.3642Z","caller":"traceutil/trace.go:171","msg":"trace[1220711038] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"224.78693ms","start":"2026-05-05T07:39:01.138728Z","end":"2026-05-05T07:39:01.363515Z","steps":["trace[1220711038] 'process raft request' (duration: 224.274207ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:39:01.365284Z","caller":"traceutil/trace.go:171","msg":"trace[1217809652] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"220.916643ms","start":"2026-05-05T07:39:01.143333Z","end":"2026-05-05T07:39:01.36425Z","steps":["trace[1217809652] 'process raft request' (duration: 219.762544ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:39:01.370597Z","caller":"traceutil/trace.go:171","msg":"trace[1159488539] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:510; }","duration":"158.025693ms","start":"2026-05-05T07:39:01.212513Z","end":"2026-05-05T07:39:01.370539Z","steps":["trace[1159488539] 'read index received' (duration: 142.544247ms)","trace[1159488539] 'applied index is now lower than readState.Index' (duration: 15.479526ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:39:01.381999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.463649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T07:39:01.382871Z","caller":"traceutil/trace.go:171","msg":"trace[653452180] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:509; }","duration":"170.390553ms","start":"2026-05-05T07:39:01.212465Z","end":"2026-05-05T07:39:01.382856Z","steps":["trace[653452180] 'agreement among raft nodes before linearized reading' (duration: 158.396383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:39:01.383239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.401223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T07:39:01.383283Z","caller":"traceutil/trace.go:171","msg":"trace[975226689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"164.455294ms","start":"2026-05-05T07:39:01.218816Z","end":"2026-05-05T07:39:01.383272Z","steps":["trace[975226689] 'agreement among raft nodes before linearized reading' (duration: 164.346941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:39:05.202998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828437948684724,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T07:39:05.385612Z","caller":"traceutil/trace.go:171","msg":"trace[2068340795] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"683.5539ms","start":"2026-05-05T07:39:04.702031Z","end":"2026-05-05T07:39:05.385585Z","steps":["trace[2068340795] 'read index received' (duration: 682.75427ms)","trace[2068340795] 'applied index is now lower than readState.Index' (duration: 798.75µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:39:05.385735Z","caller":"traceutil/trace.go:171","msg":"trace[1132310035] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"754.50199ms","start":"2026-05-05T07:39:04.631225Z","end":"2026-05-05T07:39:05.385727Z","steps":["trace[1132310035] 'process raft request' (duration: 753.801013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:39:05.386029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.98809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:39:05.386103Z","caller":"traceutil/trace.go:171","msg":"trace[830166419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"684.069192ms","start":"2026-05-05T07:39:04.702016Z","end":"2026-05-05T07:39:05.386085Z","steps":["trace[830166419] 'agreement among raft nodes before linearized reading' (duration: 683.949329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:39:05.386124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.100897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T07:39:05.386216Z","caller":"traceutil/trace.go:171","msg":"trace[339682209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"366.236501ms","start":"2026-05-05T07:39:05.019957Z","end":"2026-05-05T07:39:05.386193Z","steps":["trace[339682209] 'agreement among raft nodes before linearized reading' (duration: 365.958954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:39:05.386263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:39:05.019941Z","time spent":"366.312612ms","remote":"127.0.0.1:38492","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":"warn","ts":"2026-05-05T07:39:05.386147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:39:04.701955Z","time spent":"684.181015ms","remote":"127.0.0.1:38214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T07:39:05.386379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:39:04.631195Z","time spent":"754.581942ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:39:08.222645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.727678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T07:39:08.222809Z","caller":"traceutil/trace.go:171","msg":"trace[1403494934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"129.936293ms","start":"2026-05-05T07:39:08.09285Z","end":"2026-05-05T07:39:08.222786Z","steps":["trace[1403494934] 'range keys from in-memory index tree' (duration: 129.544574ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:39:08.633557Z","caller":"traceutil/trace.go:171","msg":"trace[1075554022] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"403.660555ms","start":"2026-05-05T07:39:08.229873Z","end":"2026-05-05T07:39:08.633533Z","steps":["trace[1075554022] 'process raft request' (duration: 402.199448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:39:08.63372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:39:08.229839Z","time spent":"403.828249ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:39:09.127276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.85034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:39:09.127407Z","caller":"traceutil/trace.go:171","msg":"trace[1213414224] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"436.129466ms","start":"2026-05-05T07:39:08.691254Z","end":"2026-05-05T07:39:09.127384Z","steps":["trace[1213414224] 'process raft request' (duration: 27.909936ms)","trace[1213414224] 'compare' (duration: 407.649036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:39:09.127477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:39:08.691206Z","time spent":"436.240889ms","remote":"127.0.0.1:38460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T07:39:09.375859Z","caller":"traceutil/trace.go:171","msg":"trace[1988794350] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"233.55732ms","start":"2026-05-05T07:39:09.142277Z","end":"2026-05-05T07:39:09.375834Z","steps":["trace[1988794350] 'process raft request' (duration: 228.812602ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:39:24.606497Z","caller":"traceutil/trace.go:171","msg":"trace[1782172459] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"194.21772ms","start":"2026-05-05T07:39:24.412258Z","end":"2026-05-05T07:39:24.606476Z","steps":["trace[1782172459] 'process raft request' (duration: 194.087967ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:12.648086Z","caller":"traceutil/trace.go:171","msg":"trace[1728372995] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"108.437802ms","start":"2026-05-05T07:41:12.539623Z","end":"2026-05-05T07:41:12.648061Z","steps":["trace[1728372995] 'process raft request' (duration: 108.274688ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:22.300072Z","caller":"traceutil/trace.go:171","msg":"trace[1240138309] transaction","detail":"{read_only:false; response_revision:1636; number_of_response:1; }","duration":"181.143692ms","start":"2026-05-05T07:41:22.118892Z","end":"2026-05-05T07:41:22.300036Z","steps":["trace[1240138309] 'process raft request' (duration: 123.31522ms)","trace[1240138309] 'compare' (duration: 57.619937ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:41:26.960863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.805572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:41:26.96099Z","caller":"traceutil/trace.go:171","msg":"trace[429239629] linearizableReadLoop","detail":"{readStateIndex:1720; appliedIndex:1719; }","duration":"161.633222ms","start":"2026-05-05T07:41:26.799339Z","end":"2026-05-05T07:41:26.960972Z","steps":["trace[429239629] 'read index received' (duration: 13.556163ms)","trace[429239629] 'applied index is now lower than readState.Index' (duration: 148.075169ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:26.961042Z","caller":"traceutil/trace.go:171","msg":"trace[202051296] transaction","detail":"{read_only:false; response_revision:1673; number_of_response:1; }","duration":"163.400943ms","start":"2026-05-05T07:41:26.79761Z","end":"2026-05-05T07:41:26.961011Z","steps":["trace[202051296] 'process raft request' (duration: 15.329984ms)","trace[202051296] 'compare' (duration: 147.411023ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:41:26.961118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.848896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:41:26.961148Z","caller":"traceutil/trace.go:171","msg":"trace[211723196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1673; }","duration":"161.877697ms","start":"2026-05-05T07:41:26.799261Z","end":"2026-05-05T07:41:26.961139Z","steps":["trace[211723196] 'agreement among raft nodes before linearized reading' (duration: 161.816635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:26.961238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.295875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T07:41:26.961315Z","caller":"traceutil/trace.go:171","msg":"trace[200777457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1673; }","duration":"139.404458ms","start":"2026-05-05T07:41:26.821894Z","end":"2026-05-05T07:41:26.961299Z","steps":["trace[200777457] 'agreement among raft nodes before linearized reading' (duration: 139.220904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:47.436897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.69414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:41:47.43706Z","caller":"traceutil/trace.go:171","msg":"trace[716317652] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2212; }","duration":"161.047399ms","start":"2026-05-05T07:41:47.275999Z","end":"2026-05-05T07:41:47.437046Z","steps":["trace[716317652] 'process raft request' (duration: 160.991218ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:47.437208Z","caller":"traceutil/trace.go:171","msg":"trace[2096238706] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"162.009031ms","start":"2026-05-05T07:41:47.275152Z","end":"2026-05-05T07:41:47.437161Z","steps":["trace[2096238706] 'process raft request' (duration: 30.995593ms)","trace[2096238706] 'compare' (duration: 130.577987ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:47.447715Z","caller":"traceutil/trace.go:171","msg":"trace[768792995] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"127.382733ms","start":"2026-05-05T07:41:47.320299Z","end":"2026-05-05T07:41:47.447682Z","steps":["trace[768792995] 'process raft request' (duration: 127.178958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:47.880014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.794969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:41:47.880458Z","caller":"traceutil/trace.go:171","msg":"trace[1743661256] linearizableReadLoop","detail":"{readStateIndex:2299; appliedIndex:2298; }","duration":"221.738805ms","start":"2026-05-05T07:41:47.658695Z","end":"2026-05-05T07:41:47.880433Z","steps":["trace[1743661256] 'read index received' (duration: 68.432µs)","trace[1743661256] 'applied index is now lower than readState.Index' (duration: 221.668193ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:47.880583Z","caller":"traceutil/trace.go:171","msg":"trace[885554576] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"366.511464ms","start":"2026-05-05T07:41:47.51403Z","end":"2026-05-05T07:41:47.880542Z","steps":["trace[885554576] 'process raft request' (duration: 56.1108ms)","trace[885554576] 'compare' (duration: 309.605525ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:41:47.880793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.063066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T07:41:47.880861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:47.514012Z","time spent":"366.604665ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:41:47.880935Z","caller":"traceutil/trace.go:171","msg":"trace[808429980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2216; }","duration":"178.219879ms","start":"2026-05-05T07:41:47.70265Z","end":"2026-05-05T07:41:47.88087Z","steps":["trace[808429980] 'agreement among raft nodes before linearized reading' (duration: 177.945393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:47.880795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.104393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9c5aaec-0877-4b0c-8bc9-16abc67332e4\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-05T07:41:47.881145Z","caller":"traceutil/trace.go:171","msg":"trace[182569736] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e9c5aaec-0877-4b0c-8bc9-16abc67332e4; range_end:; response_count:1; response_revision:2216; }","duration":"222.492882ms","start":"2026-05-05T07:41:47.658627Z","end":"2026-05-05T07:41:47.88112Z","steps":["trace[182569736] 'agreement among raft nodes before linearized reading' (duration: 222.006321ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:48.081277Z","caller":"traceutil/trace.go:171","msg":"trace[2124315015] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"192.137905ms","start":"2026-05-05T07:41:47.889102Z","end":"2026-05-05T07:41:48.08124Z","steps":["trace[2124315015] 'process raft request' (duration: 157.888476ms)","trace[2124315015] 'compare' (duration: 33.689506ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:42:04.708049Z","caller":"traceutil/trace.go:171","msg":"trace[1563713790] transaction","detail":"{read_only:false; response_revision:2361; number_of_response:1; }","duration":"314.33625ms","start":"2026-05-05T07:42:04.393697Z","end":"2026-05-05T07:42:04.708033Z","steps":["trace[1563713790] 'process raft request' (duration: 314.228768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:04.708162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:42:04.393675Z","time spent":"314.428843ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:42:04.717217Z","caller":"traceutil/trace.go:171","msg":"trace[1725472978] transaction","detail":"{read_only:false; response_revision:2362; number_of_response:1; }","duration":"278.959109ms","start":"2026-05-05T07:42:04.438234Z","end":"2026-05-05T07:42:04.717193Z","steps":["trace[1725472978] 'process raft request' (duration: 278.762354ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:04.717413Z","caller":"traceutil/trace.go:171","msg":"trace[801827878] linearizableReadLoop","detail":"{readStateIndex:2449; appliedIndex:2447; }","duration":"156.287185ms","start":"2026-05-05T07:42:04.561106Z","end":"2026-05-05T07:42:04.717393Z","steps":["trace[801827878] 'read index received' (duration: 146.842972ms)","trace[801827878] 'applied index is now lower than readState.Index' (duration: 9.442433ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:42:04.717506Z","caller":"traceutil/trace.go:171","msg":"trace[1450047313] transaction","detail":"{read_only:false; response_revision:2363; number_of_response:1; }","duration":"120.490483ms","start":"2026-05-05T07:42:04.59697Z","end":"2026-05-05T07:42:04.717461Z","steps":["trace[1450047313] 'process raft request' (duration: 120.144395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:04.717673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.557341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T07:42:04.717734Z","caller":"traceutil/trace.go:171","msg":"trace[956550874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2363; }","duration":"156.676034ms","start":"2026-05-05T07:42:04.56104Z","end":"2026-05-05T07:42:04.717716Z","steps":["trace[956550874] 'agreement among raft nodes before linearized reading' (duration: 156.50514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:04.717721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.201148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T07:42:04.717794Z","caller":"traceutil/trace.go:171","msg":"trace[769040421] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2363; }","duration":"146.311941ms","start":"2026-05-05T07:42:04.571463Z","end":"2026-05-05T07:42:04.717775Z","steps":["trace[769040421] 'agreement among raft nodes before linearized reading' (duration: 146.076905ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:16.923877Z","caller":"traceutil/trace.go:171","msg":"trace[1219062642] transaction","detail":"{read_only:false; response_revision:2442; number_of_response:1; }","duration":"115.840776ms","start":"2026-05-05T07:42:16.808002Z","end":"2026-05-05T07:42:16.923842Z","steps":["trace[1219062642] 'process raft request' (duration: 115.595041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:16.923939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.31979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T07:42:16.924017Z","caller":"traceutil/trace.go:171","msg":"trace[5319689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2442; }","duration":"111.449273ms","start":"2026-05-05T07:42:16.812547Z","end":"2026-05-05T07:42:16.923996Z","steps":["trace[5319689] 'agreement among raft nodes before linearized reading' (duration: 111.214867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:16.924137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.841206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T07:42:16.924233Z","caller":"traceutil/trace.go:171","msg":"trace[1591731326] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2442; }","duration":"101.978899ms","start":"2026-05-05T07:42:16.822231Z","end":"2026-05-05T07:42:16.92421Z","steps":["trace[1591731326] 'agreement among raft nodes before linearized reading' (duration: 101.661562ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:17.014676Z","caller":"traceutil/trace.go:171","msg":"trace[1249542474] transaction","detail":"{read_only:false; response_revision:2443; number_of_response:1; }","duration":"117.26718ms","start":"2026-05-05T07:42:16.897384Z","end":"2026-05-05T07:42:17.014651Z","steps":["trace[1249542474] 'process raft request' (duration: 105.685387ms)","trace[1249542474] 'compare' (duration: 11.383779ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:42:17.249007Z","caller":"traceutil/trace.go:171","msg":"trace[2044584060] transaction","detail":"{read_only:false; response_revision:2446; number_of_response:1; }","duration":"110.284056ms","start":"2026-05-05T07:42:17.138698Z","end":"2026-05-05T07:42:17.248982Z","steps":["trace[2044584060] 'process raft request' (duration: 110.116522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:18.167117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.038909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T07:42:18.16722Z","caller":"traceutil/trace.go:171","msg":"trace[331734633] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2449; }","duration":"236.193273ms","start":"2026-05-05T07:42:17.931001Z","end":"2026-05-05T07:42:18.167194Z","steps":["trace[331734633] 'range keys from in-memory index tree' (duration: 235.520847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:18.167555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.206751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T07:42:18.167629Z","caller":"traceutil/trace.go:171","msg":"trace[2063505841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2449; }","duration":"147.323302ms","start":"2026-05-05T07:42:18.020291Z","end":"2026-05-05T07:42:18.167615Z","steps":["trace[2063505841] 'range keys from in-memory index tree' (duration: 146.74837ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:18.655744Z","caller":"traceutil/trace.go:171","msg":"trace[448879682] transaction","detail":"{read_only:false; response_revision:2453; number_of_response:1; }","duration":"154.342629ms","start":"2026-05-05T07:42:18.501275Z","end":"2026-05-05T07:42:18.655617Z","steps":["trace[448879682] 'process raft request' (duration: 55.481802ms)","trace[448879682] 'compare' (duration: 98.54761ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:42:19.277617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.540412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:42:19.277725Z","caller":"traceutil/trace.go:171","msg":"trace[96589845] linearizableReadLoop","detail":"{readStateIndex:2547; appliedIndex:2546; }","duration":"250.570886ms","start":"2026-05-05T07:42:19.027137Z","end":"2026-05-05T07:42:19.277708Z","steps":["trace[96589845] 'read index received' (duration: 93.797899ms)","trace[96589845] 'applied index is now lower than readState.Index' (duration: 156.771887ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:42:19.27785Z","caller":"traceutil/trace.go:171","msg":"trace[197450302] transaction","detail":"{read_only:false; response_revision:2456; number_of_response:1; }","duration":"253.616708ms","start":"2026-05-05T07:42:19.024207Z","end":"2026-05-05T07:42:19.277824Z","steps":["trace[197450302] 'process raft request' (duration: 96.774269ms)","trace[197450302] 'compare' (duration: 156.336907ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:42:19.278086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.941225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T07:42:19.278141Z","caller":"traceutil/trace.go:171","msg":"trace[1096316863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2456; }","duration":"251.008446ms","start":"2026-05-05T07:42:19.027115Z","end":"2026-05-05T07:42:19.278123Z","steps":["trace[1096316863] 'agreement among raft nodes before linearized reading' (duration: 250.813881ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:19.338241Z","caller":"traceutil/trace.go:171","msg":"trace[541418233] transaction","detail":"{read_only:false; response_revision:2457; number_of_response:1; }","duration":"310.402171ms","start":"2026-05-05T07:42:19.027811Z","end":"2026-05-05T07:42:19.338214Z","steps":["trace[541418233] 'process raft request' (duration: 310.237847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:19.338494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:42:19.027801Z","time spent":"310.526064ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:42:19.342256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.393025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T07:42:19.342407Z","caller":"traceutil/trace.go:171","msg":"trace[2069174194] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2459; }","duration":"166.521748ms","start":"2026-05-05T07:42:19.175835Z","end":"2026-05-05T07:42:19.342357Z","steps":["trace[2069174194] 'agreement among raft nodes before linearized reading' (duration: 166.218321ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:50.675326Z","caller":"traceutil/trace.go:171","msg":"trace[569002055] linearizableReadLoop","detail":"{readStateIndex:2742; appliedIndex:2741; }","duration":"107.783854ms","start":"2026-05-05T07:42:50.567507Z","end":"2026-05-05T07:42:50.675291Z","steps":["trace[569002055] 'read index received' (duration: 107.479177ms)","trace[569002055] 'applied index is now lower than readState.Index' (duration: 303.827µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:42:50.675565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.03383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T07:42:50.675613Z","caller":"traceutil/trace.go:171","msg":"trace[1858000352] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2645; }","duration":"108.121442ms","start":"2026-05-05T07:42:50.567466Z","end":"2026-05-05T07:42:50.675587Z","steps":["trace[1858000352] 'agreement among raft nodes before linearized reading' (duration: 107.926778ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:50.676074Z","caller":"traceutil/trace.go:171","msg":"trace[1484947683] transaction","detail":"{read_only:false; response_revision:2645; number_of_response:1; }","duration":"225.923215ms","start":"2026-05-05T07:42:50.450056Z","end":"2026-05-05T07:42:50.675979Z","steps":["trace[1484947683] 'process raft request' (duration: 225.028193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:43:39.543023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.49511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-05T07:43:39.543128Z","caller":"traceutil/trace.go:171","msg":"trace[408622061] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3018; }","duration":"394.668875ms","start":"2026-05-05T07:43:39.148436Z","end":"2026-05-05T07:43:39.543105Z","steps":["trace[408622061] 'range keys from in-memory index tree' (duration: 394.264255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:43:39.543135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.061865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ed9bcf34-bbfc-4ecb-8f70-46176d632c82\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-05T07:43:39.543165Z","caller":"traceutil/trace.go:171","msg":"trace[1697773828] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ed9bcf34-bbfc-4ecb-8f70-46176d632c82; range_end:; response_count:1; response_revision:3018; }","duration":"280.128317ms","start":"2026-05-05T07:43:39.263028Z","end":"2026-05-05T07:43:39.543156Z","steps":["trace[1697773828] 'range keys from in-memory index tree' (duration: 279.953743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:43:39.543169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:43:39.148408Z","time spent":"394.752656ms","remote":"127.0.0.1:38390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-05-05T07:43:39.543681Z","caller":"traceutil/trace.go:171","msg":"trace[923910606] transaction","detail":"{read_only:false; response_revision:3019; number_of_response:1; }","duration":"263.113227ms","start":"2026-05-05T07:43:39.280555Z","end":"2026-05-05T07:43:39.543668Z","steps":["trace[923910606] 'process raft request' (duration: 232.198039ms)","trace[923910606] 'compare' (duration: 29.591866ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:43:39.618751Z","caller":"traceutil/trace.go:171","msg":"trace[83936235] linearizableReadLoop","detail":"{readStateIndex:3128; appliedIndex:3126; }","duration":"188.215927ms","start":"2026-05-05T07:43:39.430521Z","end":"2026-05-05T07:43:39.618737Z","steps":["trace[83936235] 'read index received' (duration: 82.247637ms)","trace[83936235] 'applied index is now lower than readState.Index' (duration: 105.96779ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:43:39.618994Z","caller":"traceutil/trace.go:171","msg":"trace[935823634] transaction","detail":"{read_only:false; response_revision:3020; number_of_response:1; }","duration":"245.45964ms","start":"2026-05-05T07:43:39.373527Z","end":"2026-05-05T07:43:39.618987Z","steps":["trace[935823634] 'process raft request' (duration: 245.118922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:43:39.619117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.597857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:43:39.619139Z","caller":"traceutil/trace.go:171","msg":"trace[1460901103] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:3020; }","duration":"188.692718ms","start":"2026-05-05T07:43:39.43044Z","end":"2026-05-05T07:43:39.619133Z","steps":["trace[1460901103] 'agreement among raft nodes before linearized reading' (duration: 188.645197ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:43:39.872436Z","caller":"traceutil/trace.go:171","msg":"trace[1220089023] transaction","detail":"{read_only:false; response_revision:3021; number_of_response:1; }","duration":"308.513443ms","start":"2026-05-05T07:43:39.563901Z","end":"2026-05-05T07:43:39.872414Z","steps":["trace[1220089023] 'process raft request' (duration: 307.836037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:43:39.872556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:43:39.563875Z","time spent":"308.618365ms","remote":"127.0.0.1:38382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:43:39.873001Z","caller":"traceutil/trace.go:171","msg":"trace[1710600739] transaction","detail":"{read_only:false; response_revision:3022; number_of_response:1; }","duration":"257.745077ms","start":"2026-05-05T07:43:39.615247Z","end":"2026-05-05T07:43:39.872992Z","steps":["trace[1710600739] 'process raft request' (duration: 256.600189ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:43:39.87331Z","caller":"traceutil/trace.go:171","msg":"trace[1014534149] linearizableReadLoop","detail":"{readStateIndex:3130; appliedIndex:3130; }","duration":"254.492048ms","start":"2026-05-05T07:43:39.618811Z","end":"2026-05-05T07:43:39.873303Z","steps":["trace[1014534149] 'read index received' (duration: 254.487968ms)","trace[1014534149] 'applied index is now lower than readState.Index' (duration: 3.42µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:43:39.873558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.138467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-05-05T07:43:39.873586Z","caller":"traceutil/trace.go:171","msg":"trace[1637870621] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:3022; }","duration":"258.189577ms","start":"2026-05-05T07:43:39.615389Z","end":"2026-05-05T07:43:39.873579Z","steps":["trace[1637870621] 'agreement among raft nodes before linearized reading' (duration: 258.072814ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:43:39.892044Z","caller":"traceutil/trace.go:171","msg":"trace[570277624] transaction","detail":"{read_only:false; response_revision:3023; number_of_response:1; }","duration":"211.135561ms","start":"2026-05-05T07:43:39.680886Z","end":"2026-05-05T07:43:39.892022Z","steps":["trace[570277624] 'process raft request' (duration: 210.860614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:43:39.892245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.30095ms","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":"info","ts":"2026-05-05T07:43:39.892275Z","caller":"traceutil/trace.go:171","msg":"trace[338509754] 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:3023; }","duration":"104.391832ms","start":"2026-05-05T07:43:39.787875Z","end":"2026-05-05T07:43:39.892267Z","steps":["trace[338509754] 'agreement among raft nodes before linearized reading' (duration: 104.334141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:43:39.892429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.172987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T07:43:39.892452Z","caller":"traceutil/trace.go:171","msg":"trace[1342654830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3023; }","duration":"145.204388ms","start":"2026-05-05T07:43:39.747242Z","end":"2026-05-05T07:43:39.892447Z","steps":["trace[1342654830] 'agreement among raft nodes before linearized reading' (duration: 145.125106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:43:39.892934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.454283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-05T07:43:39.893078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.007503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T07:43:39.893112Z","caller":"traceutil/trace.go:171","msg":"trace[1241022038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3023; }","duration":"157.066284ms","start":"2026-05-05T07:43:39.736037Z","end":"2026-05-05T07:43:39.893104Z","steps":["trace[1241022038] 'agreement among raft nodes before linearized reading' (duration: 156.980212ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:43:39.893238Z","caller":"traceutil/trace.go:171","msg":"trace[1550959853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3023; }","duration":"152.536915ms","start":"2026-05-05T07:43:39.740448Z","end":"2026-05-05T07:43:39.892985Z","steps":["trace[1550959853] 'agreement among raft nodes before linearized reading' (duration: 152.361001ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:43:41.731297Z","caller":"traceutil/trace.go:171","msg":"trace[1919944363] transaction","detail":"{read_only:false; response_revision:3033; number_of_response:1; }","duration":"105.669233ms","start":"2026-05-05T07:43:41.62558Z","end":"2026-05-05T07:43:41.731249Z","steps":["trace[1919944363] 'process raft request' (duration: 105.477568ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:43:52.029836Z","caller":"traceutil/trace.go:171","msg":"trace[712615960] transaction","detail":"{read_only:false; response_revision:3119; number_of_response:1; }","duration":"117.577556ms","start":"2026-05-05T07:43:51.912225Z","end":"2026-05-05T07:43:52.029803Z","steps":["trace[712615960] 'process raft request' (duration: 74.202336ms)","trace[712615960] 'compare' (duration: 43.146455ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:47:00.346488Z","caller":"traceutil/trace.go:171","msg":"trace[1915232547] transaction","detail":"{read_only:false; response_revision:4412; number_of_response:1; }","duration":"101.677783ms","start":"2026-05-05T07:47:00.24475Z","end":"2026-05-05T07:47:00.346428Z","steps":["trace[1915232547] 'process raft request' (duration: 101.430367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:47:39.332237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.606188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:47:39.332569Z","caller":"traceutil/trace.go:171","msg":"trace[408989549] transaction","detail":"{read_only:false; response_revision:4610; number_of_response:1; }","duration":"185.018102ms","start":"2026-05-05T07:47:39.147408Z","end":"2026-05-05T07:47:39.332426Z","steps":["trace[408989549] 'process raft request' (duration: 35.124146ms)","trace[408989549] 'compare' (duration: 149.492386ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:47:40.848773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.855823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:47:40.848883Z","caller":"traceutil/trace.go:171","msg":"trace[382783608] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4618; }","duration":"111.030997ms","start":"2026-05-05T07:47:40.737825Z","end":"2026-05-05T07:47:40.848856Z","steps":["trace[382783608] 'range keys from in-memory index tree' (duration: 110.728959ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:48:38.288676Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3013} {"level":"info","ts":"2026-05-05T07:48:38.39782Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3013,"took":"107.096663ms","hash":4069847130,"current-db-size-bytes":38060032,"current-db-size":"38 MB","current-db-size-in-use-bytes":11018240,"current-db-size-in-use":"11 MB"} {"level":"info","ts":"2026-05-05T07:48:38.39789Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4069847130,"revision":3013,"compact-revision":-1} {"level":"info","ts":"2026-05-05T07:49:54.166374Z","caller":"traceutil/trace.go:171","msg":"trace[1012555854] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"118.005033ms","start":"2026-05-05T07:49:54.048347Z","end":"2026-05-05T07:49:54.166352Z","steps":["trace[1012555854] 'process raft request' (duration: 117.808418ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:49:57.027663Z","caller":"traceutil/trace.go:171","msg":"trace[1445409850] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"115.962662ms","start":"2026-05-05T07:49:56.911638Z","end":"2026-05-05T07:49:57.0276Z","steps":["trace[1445409850] 'process raft request' (duration: 115.709967ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:49:57.59658Z","caller":"traceutil/trace.go:171","msg":"trace[872706361] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"152.805456ms","start":"2026-05-05T07:49:57.443745Z","end":"2026-05-05T07:49:57.596551Z","steps":["trace[872706361] 'process raft request' (duration: 152.657143ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:02.748588Z","caller":"traceutil/trace.go:171","msg":"trace[1026698191] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"155.522083ms","start":"2026-05-05T07:50:02.593046Z","end":"2026-05-05T07:50:02.748568Z","steps":["trace[1026698191] 'process raft request' (duration: 155.102764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:04.260846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.292934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:50:04.260952Z","caller":"traceutil/trace.go:171","msg":"trace[1977940583] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:5407; }","duration":"112.457028ms","start":"2026-05-05T07:50:04.148467Z","end":"2026-05-05T07:50:04.260924Z","steps":["trace[1977940583] 'count revisions from in-memory index tree' (duration: 112.161992ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:04.260966Z","caller":"traceutil/trace.go:171","msg":"trace[886161876] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"107.732317ms","start":"2026-05-05T07:50:04.153196Z","end":"2026-05-05T07:50:04.260928Z","steps":["trace[886161876] 'process raft request' (duration: 58.669791ms)","trace[886161876] 'compare' (duration: 48.894753ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:50:04.722757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.798854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:50:04.722987Z","caller":"traceutil/trace.go:171","msg":"trace[1477011807] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"369.305456ms","start":"2026-05-05T07:50:04.353635Z","end":"2026-05-05T07:50:04.72294Z","steps":["trace[1477011807] 'compare' (duration: 360.671791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:04.723328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:50:04.353593Z","time spent":"369.565651ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:50:06.846375Z","caller":"traceutil/trace.go:171","msg":"trace[1343759747] transaction","detail":"{read_only:false; response_revision:5420; number_of_response:1; }","duration":"115.905315ms","start":"2026-05-05T07:50:06.730404Z","end":"2026-05-05T07:50:06.846309Z","steps":["trace[1343759747] 'process raft request' (duration: 64.260802ms)","trace[1343759747] 'compare' (duration: 50.631671ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:50:09.597628Z","caller":"traceutil/trace.go:171","msg":"trace[147288665] linearizableReadLoop","detail":"{readStateIndex:5642; appliedIndex:5641; }","duration":"109.380919ms","start":"2026-05-05T07:50:09.488205Z","end":"2026-05-05T07:50:09.597586Z","steps":["trace[147288665] 'read index received' (duration: 100.852346ms)","trace[147288665] 'applied index is now lower than readState.Index' (duration: 8.527223ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:50:09.597952Z","caller":"traceutil/trace.go:171","msg":"trace[1982282559] transaction","detail":"{read_only:false; response_revision:5446; number_of_response:1; }","duration":"129.394557ms","start":"2026-05-05T07:50:09.468261Z","end":"2026-05-05T07:50:09.597655Z","steps":["trace[1982282559] 'process raft request' (duration: 120.843694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:09.597985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.730966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/ingress-nginx/ingress-nginx-controller-dkf7z.18ac9b7713c165d4\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-05-05T07:50:09.598381Z","caller":"traceutil/trace.go:171","msg":"trace[447689915] range","detail":"{range_begin:/registry/events/ingress-nginx/ingress-nginx-controller-dkf7z.18ac9b7713c165d4; range_end:; response_count:1; response_revision:5446; }","duration":"110.234787ms","start":"2026-05-05T07:50:09.488127Z","end":"2026-05-05T07:50:09.598362Z","steps":["trace[447689915] 'agreement among raft nodes before linearized reading' (duration: 109.600484ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:10.159211Z","caller":"traceutil/trace.go:171","msg":"trace[85267250] transaction","detail":"{read_only:false; response_revision:5463; number_of_response:1; }","duration":"138.216857ms","start":"2026-05-05T07:50:10.020949Z","end":"2026-05-05T07:50:10.159166Z","steps":["trace[85267250] 'process raft request' (duration: 137.91041ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:10.159485Z","caller":"traceutil/trace.go:171","msg":"trace[1100549208] transaction","detail":"{read_only:false; response_revision:5462; number_of_response:1; }","duration":"141.724853ms","start":"2026-05-05T07:50:10.017751Z","end":"2026-05-05T07:50:10.159476Z","steps":["trace[1100549208] 'process raft request' (duration: 141.075989ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:10.159526Z","caller":"traceutil/trace.go:171","msg":"trace[654714477] linearizableReadLoop","detail":"{readStateIndex:5659; appliedIndex:5655; }","duration":"138.598655ms","start":"2026-05-05T07:50:10.020915Z","end":"2026-05-05T07:50:10.159514Z","steps":["trace[654714477] 'read index received' (duration: 7.578712ms)","trace[654714477] 'applied index is now lower than readState.Index' (duration: 131.018383ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:50:10.159343Z","caller":"traceutil/trace.go:171","msg":"trace[244102581] transaction","detail":"{read_only:false; response_revision:5460; number_of_response:1; }","duration":"141.679201ms","start":"2026-05-05T07:50:10.017638Z","end":"2026-05-05T07:50:10.159317Z","steps":["trace[244102581] 'process raft request' (duration: 141.101799ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:10.159462Z","caller":"traceutil/trace.go:171","msg":"trace[2086897221] transaction","detail":"{read_only:false; response_revision:5464; number_of_response:1; }","duration":"131.602926ms","start":"2026-05-05T07:50:10.027834Z","end":"2026-05-05T07:50:10.159437Z","steps":["trace[2086897221] 'process raft request' (duration: 131.065544ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:10.159449Z","caller":"traceutil/trace.go:171","msg":"trace[1234406269] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"141.704692ms","start":"2026-05-05T07:50:10.0177Z","end":"2026-05-05T07:50:10.159404Z","steps":["trace[1234406269] 'process raft request' (duration: 141.088709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:10.159623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.689327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:50:10.159661Z","caller":"traceutil/trace.go:171","msg":"trace[1242845233] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:5465; }","duration":"138.748658ms","start":"2026-05-05T07:50:10.020903Z","end":"2026-05-05T07:50:10.159651Z","steps":["trace[1242845233] 'agreement among raft nodes before linearized reading' (duration: 138.668517ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:10.159913Z","caller":"traceutil/trace.go:171","msg":"trace[572074894] transaction","detail":"{read_only:false; response_revision:5459; number_of_response:1; }","duration":"142.302535ms","start":"2026-05-05T07:50:10.017599Z","end":"2026-05-05T07:50:10.159902Z","steps":["trace[572074894] 'process raft request' (duration: 125.016725ms)","trace[572074894] 'compare' (duration: 15.846549ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:50:12.048416Z","caller":"traceutil/trace.go:171","msg":"trace[342277344] transaction","detail":"{read_only:false; response_revision:5482; number_of_response:1; }","duration":"115.899222ms","start":"2026-05-05T07:50:11.932474Z","end":"2026-05-05T07:50:12.048373Z","steps":["trace[342277344] 'process raft request' (duration: 115.615446ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:38.425792Z","caller":"traceutil/trace.go:171","msg":"trace[2040971168] transaction","detail":"{read_only:false; response_revision:5802; number_of_response:1; }","duration":"231.429441ms","start":"2026-05-05T07:50:38.194333Z","end":"2026-05-05T07:50:38.425762Z","steps":["trace[2040971168] 'process raft request' (duration: 231.234247ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:38.461933Z","caller":"traceutil/trace.go:171","msg":"trace[1662584635] linearizableReadLoop","detail":"{readStateIndex:6010; appliedIndex:6008; }","duration":"239.267121ms","start":"2026-05-05T07:50:38.222641Z","end":"2026-05-05T07:50:38.461909Z","steps":["trace[1662584635] 'read index received' (duration: 202.904632ms)","trace[1662584635] 'applied index is now lower than readState.Index' (duration: 36.361509ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:50:38.46204Z","caller":"traceutil/trace.go:171","msg":"trace[597285641] transaction","detail":"{read_only:false; response_revision:5804; number_of_response:1; }","duration":"185.041984ms","start":"2026-05-05T07:50:38.276986Z","end":"2026-05-05T07:50:38.462028Z","steps":["trace[597285641] 'process raft request' (duration: 184.880861ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:38.462033Z","caller":"traceutil/trace.go:171","msg":"trace[468696626] transaction","detail":"{read_only:false; response_revision:5803; number_of_response:1; }","duration":"266.657346ms","start":"2026-05-05T07:50:38.195338Z","end":"2026-05-05T07:50:38.461996Z","steps":["trace[468696626] 'process raft request' (duration: 266.425701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:38.462363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.704581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T07:50:38.46241Z","caller":"traceutil/trace.go:171","msg":"trace[1243344477] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5804; }","duration":"239.800932ms","start":"2026-05-05T07:50:38.222597Z","end":"2026-05-05T07:50:38.462398Z","steps":["trace[1243344477] 'agreement among raft nodes before linearized reading' (duration: 239.622428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:50:38.462374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.382607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T07:50:38.462475Z","caller":"traceutil/trace.go:171","msg":"trace[1473138640] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:5804; }","duration":"188.52104ms","start":"2026-05-05T07:50:38.273938Z","end":"2026-05-05T07:50:38.462459Z","steps":["trace[1473138640] 'agreement among raft nodes before linearized reading' (duration: 188.339336ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:43.854159Z","caller":"traceutil/trace.go:171","msg":"trace[1799688614] transaction","detail":"{read_only:false; response_revision:5834; number_of_response:1; }","duration":"173.257208ms","start":"2026-05-05T07:50:43.680876Z","end":"2026-05-05T07:50:43.854133Z","steps":["trace[1799688614] 'process raft request' (duration: 172.847599ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:43.854338Z","caller":"traceutil/trace.go:171","msg":"trace[1440939730] linearizableReadLoop","detail":"{readStateIndex:6042; appliedIndex:6041; }","duration":"150.489743ms","start":"2026-05-05T07:50:43.703603Z","end":"2026-05-05T07:50:43.854092Z","steps":["trace[1440939730] 'read index received' (duration: 150.152006ms)","trace[1440939730] 'applied index is now lower than readState.Index' (duration: 336.297µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:50:43.854628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.987314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:50:43.854705Z","caller":"traceutil/trace.go:171","msg":"trace[866006685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5834; }","duration":"151.130887ms","start":"2026-05-05T07:50:43.703557Z","end":"2026-05-05T07:50:43.854688Z","steps":["trace[866006685] 'agreement among raft nodes before linearized reading' (duration: 150.717718ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:50:54.257189Z","caller":"traceutil/trace.go:171","msg":"trace[529654603] transaction","detail":"{read_only:false; response_revision:5893; number_of_response:1; }","duration":"239.403222ms","start":"2026-05-05T07:50:54.017762Z","end":"2026-05-05T07:50:54.257165Z","steps":["trace[529654603] 'process raft request' (duration: 239.226718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:05.419788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.690198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:51:05.419895Z","caller":"traceutil/trace.go:171","msg":"trace[1563251115] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret; range_end:; response_count:0; response_revision:6006; }","duration":"134.847421ms","start":"2026-05-05T07:51:05.285021Z","end":"2026-05-05T07:51:05.419868Z","steps":["trace[1563251115] 'range keys from in-memory index tree' (duration: 134.586026ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:07.673042Z","caller":"traceutil/trace.go:171","msg":"trace[1849009911] transaction","detail":"{read_only:false; response_revision:6020; number_of_response:1; }","duration":"161.757045ms","start":"2026-05-05T07:51:07.511262Z","end":"2026-05-05T07:51:07.673019Z","steps":["trace[1849009911] 'process raft request' (duration: 161.237714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:08.04663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.385003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:51:08.046731Z","caller":"traceutil/trace.go:171","msg":"trace[1016901553] linearizableReadLoop","detail":"{readStateIndex:6238; appliedIndex:6237; }","duration":"345.092883ms","start":"2026-05-05T07:51:07.701623Z","end":"2026-05-05T07:51:08.046716Z","steps":["trace[1016901553] 'read index received' (duration: 73.570377ms)","trace[1016901553] 'applied index is now lower than readState.Index' (duration: 271.521116ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:51:08.046813Z","caller":"traceutil/trace.go:171","msg":"trace[1905188754] transaction","detail":"{read_only:false; response_revision:6021; number_of_response:1; }","duration":"369.426058ms","start":"2026-05-05T07:51:07.677375Z","end":"2026-05-05T07:51:08.046801Z","steps":["trace[1905188754] 'process raft request' (duration: 97.786929ms)","trace[1905188754] 'compare' (duration: 271.144308ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:51:08.04687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:07.677352Z","time spent":"369.48569ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:51:08.047043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.41747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:51:08.047153Z","caller":"traceutil/trace.go:171","msg":"trace[10554989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6021; }","duration":"345.485642ms","start":"2026-05-05T07:51:07.701582Z","end":"2026-05-05T07:51:08.047067Z","steps":["trace[10554989] 'agreement among raft nodes before linearized reading' (duration: 345.427501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:08.047254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:51:07.701564Z","time spent":"345.676226ms","remote":"127.0.0.1:38214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T07:51:08.047553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.38741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T07:51:08.047595Z","caller":"traceutil/trace.go:171","msg":"trace[46892201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6021; }","duration":"259.462441ms","start":"2026-05-05T07:51:07.788123Z","end":"2026-05-05T07:51:08.047585Z","steps":["trace[46892201] 'agreement among raft nodes before linearized reading' (duration: 259.329218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:08.047708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.447289ms","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-05-05T07:51:08.047739Z","caller":"traceutil/trace.go:171","msg":"trace[1145718635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6021; }","duration":"253.496301ms","start":"2026-05-05T07:51:07.794234Z","end":"2026-05-05T07:51:08.04773Z","steps":["trace[1145718635] 'agreement among raft nodes before linearized reading' (duration: 253.424099ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:17.236784Z","caller":"traceutil/trace.go:171","msg":"trace[1257427922] transaction","detail":"{read_only:false; response_revision:6073; number_of_response:1; }","duration":"164.407268ms","start":"2026-05-05T07:51:17.072356Z","end":"2026-05-05T07:51:17.236763Z","steps":["trace[1257427922] 'process raft request' (duration: 164.293035ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:26.149282Z","caller":"traceutil/trace.go:171","msg":"trace[1318762792] transaction","detail":"{read_only:false; response_revision:6155; number_of_response:1; }","duration":"118.084885ms","start":"2026-05-05T07:51:26.031169Z","end":"2026-05-05T07:51:26.149254Z","steps":["trace[1318762792] 'process raft request' (duration: 118.010513ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:26.14936Z","caller":"traceutil/trace.go:171","msg":"trace[1569337530] transaction","detail":"{read_only:false; response_revision:6154; number_of_response:1; }","duration":"167.481528ms","start":"2026-05-05T07:51:25.981844Z","end":"2026-05-05T07:51:26.149325Z","steps":["trace[1569337530] 'process raft request' (duration: 77.163388ms)","trace[1569337530] 'compare' (duration: 90.011154ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:51:26.149409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.623203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/podmonitors.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:51:26.149289Z","caller":"traceutil/trace.go:171","msg":"trace[318101897] linearizableReadLoop","detail":"{readStateIndex:6376; appliedIndex:6375; }","duration":"129.748903ms","start":"2026-05-05T07:51:26.019518Z","end":"2026-05-05T07:51:26.149267Z","steps":["trace[318101897] 'read index received' (duration: 43.493733ms)","trace[318101897] 'applied index is now lower than readState.Index' (duration: 86.25325ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:51:26.149447Z","caller":"traceutil/trace.go:171","msg":"trace[940792696] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/podmonitors.monitoring.coreos.com; range_end:; response_count:0; response_revision:6155; }","duration":"158.712804ms","start":"2026-05-05T07:51:25.990724Z","end":"2026-05-05T07:51:26.149437Z","steps":["trace[940792696] 'agreement among raft nodes before linearized reading' (duration: 158.606271ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:26.224894Z","caller":"traceutil/trace.go:171","msg":"trace[1426156388] transaction","detail":"{read_only:false; response_revision:6156; number_of_response:1; }","duration":"156.985036ms","start":"2026-05-05T07:51:26.067871Z","end":"2026-05-05T07:51:26.224856Z","steps":["trace[1426156388] 'process raft request' (duration: 156.26832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:26.504088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.036992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:51:26.504508Z","caller":"traceutil/trace.go:171","msg":"trace[1795071728] transaction","detail":"{read_only:false; response_revision:6161; number_of_response:1; }","duration":"168.159214ms","start":"2026-05-05T07:51:26.336312Z","end":"2026-05-05T07:51:26.504471Z","steps":["trace[1795071728] 'process raft request' (duration: 22.634691ms)","trace[1795071728] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-cp-lock; req_size:416; } (duration: 144.849248ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:51:26.504841Z","caller":"traceutil/trace.go:171","msg":"trace[999354034] transaction","detail":"{read_only:false; response_revision:6163; number_of_response:1; }","duration":"161.578899ms","start":"2026-05-05T07:51:26.343161Z","end":"2026-05-05T07:51:26.50474Z","steps":["trace[999354034] 'process raft request' (duration: 161.351743ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:26.504916Z","caller":"traceutil/trace.go:171","msg":"trace[956044030] transaction","detail":"{read_only:false; response_revision:6162; number_of_response:1; }","duration":"168.258586ms","start":"2026-05-05T07:51:26.336562Z","end":"2026-05-05T07:51:26.504821Z","steps":["trace[956044030] 'process raft request' (duration: 167.828717ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:26.548439Z","caller":"traceutil/trace.go:171","msg":"trace[1994771248] transaction","detail":"{read_only:false; response_revision:6164; number_of_response:1; }","duration":"187.012941ms","start":"2026-05-05T07:51:26.361381Z","end":"2026-05-05T07:51:26.548394Z","steps":["trace[1994771248] 'process raft request' (duration: 186.639103ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:27.323686Z","caller":"traceutil/trace.go:171","msg":"trace[306503670] transaction","detail":"{read_only:false; response_revision:6173; number_of_response:1; }","duration":"137.712611ms","start":"2026-05-05T07:51:27.18594Z","end":"2026-05-05T07:51:27.323653Z","steps":["trace[306503670] 'process raft request' (duration: 137.440325ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:27.333751Z","caller":"traceutil/trace.go:171","msg":"trace[310890672] transaction","detail":"{read_only:false; response_revision:6174; number_of_response:1; }","duration":"136.433482ms","start":"2026-05-05T07:51:27.197293Z","end":"2026-05-05T07:51:27.333726Z","steps":["trace[310890672] 'process raft request' (duration: 136.043973ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:27.69208Z","caller":"traceutil/trace.go:171","msg":"trace[887839428] linearizableReadLoop","detail":"{readStateIndex:6400; appliedIndex:6399; }","duration":"116.333937ms","start":"2026-05-05T07:51:27.575729Z","end":"2026-05-05T07:51:27.692063Z","steps":["trace[887839428] 'read index received' (duration: 109.400833ms)","trace[887839428] 'applied index is now lower than readState.Index' (duration: 6.932284ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:51:27.692115Z","caller":"traceutil/trace.go:171","msg":"trace[1417468879] transaction","detail":"{read_only:false; response_revision:6177; number_of_response:1; }","duration":"158.039061ms","start":"2026-05-05T07:51:27.534058Z","end":"2026-05-05T07:51:27.692097Z","steps":["trace[1417468879] 'process raft request' (duration: 150.954824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:51:27.692187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.439459ms","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-05-05T07:51:27.692208Z","caller":"traceutil/trace.go:171","msg":"trace[1240516762] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:6177; }","duration":"116.47672ms","start":"2026-05-05T07:51:27.575724Z","end":"2026-05-05T07:51:27.692201Z","steps":["trace[1240516762] 'agreement among raft nodes before linearized reading' (duration: 116.402318ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:51:38.252776Z","caller":"traceutil/trace.go:171","msg":"trace[1534815234] transaction","detail":"{read_only:false; response_revision:6254; number_of_response:1; }","duration":"252.534256ms","start":"2026-05-05T07:51:38.000199Z","end":"2026-05-05T07:51:38.252733Z","steps":["trace[1534815234] 'process raft request' (duration: 252.314202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:09.469526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.503745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T07:52:09.469647Z","caller":"traceutil/trace.go:171","msg":"trace[783712121] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:6470; }","duration":"134.682238ms","start":"2026-05-05T07:52:09.334941Z","end":"2026-05-05T07:52:09.469623Z","steps":["trace[783712121] 'count revisions from in-memory index tree' (duration: 134.425912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:09.469698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.470341ms","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-05-05T07:52:09.469787Z","caller":"traceutil/trace.go:171","msg":"trace[1092077524] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6470; }","duration":"133.596734ms","start":"2026-05-05T07:52:09.33617Z","end":"2026-05-05T07:52:09.469767Z","steps":["trace[1092077524] 'range keys from in-memory index tree' (duration: 133.324747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:15.589822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.400608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:52:15.589994Z","caller":"traceutil/trace.go:171","msg":"trace[1115002125] transaction","detail":"{read_only:false; response_revision:6821; number_of_response:1; }","duration":"275.831461ms","start":"2026-05-05T07:52:15.314132Z","end":"2026-05-05T07:52:15.589963Z","steps":["trace[1115002125] 'process raft request' (duration: 85.169208ms)","trace[1115002125] 'compare' (duration: 189.902666ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:52:15.591993Z","caller":"traceutil/trace.go:171","msg":"trace[752862640] transaction","detail":"{read_only:false; response_revision:6822; number_of_response:1; }","duration":"276.21486ms","start":"2026-05-05T07:52:15.315751Z","end":"2026-05-05T07:52:15.591965Z","steps":["trace[752862640] 'process raft request' (duration: 275.859932ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:15.592353Z","caller":"traceutil/trace.go:171","msg":"trace[2047129664] transaction","detail":"{read_only:false; number_of_response:1; response_revision:6822; }","duration":"226.91601ms","start":"2026-05-05T07:52:15.365368Z","end":"2026-05-05T07:52:15.592284Z","steps":["trace[2047129664] 'process raft request' (duration: 226.524101ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:16.703139Z","caller":"traceutil/trace.go:171","msg":"trace[175151875] linearizableReadLoop","detail":"{readStateIndex:7094; appliedIndex:7093; }","duration":"180.724571ms","start":"2026-05-05T07:52:16.52238Z","end":"2026-05-05T07:52:16.703105Z","steps":["trace[175151875] 'read index received' (duration: 180.575058ms)","trace[175151875] 'applied index is now lower than readState.Index' (duration: 148.283µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:52:16.703373Z","caller":"traceutil/trace.go:171","msg":"trace[421772982] transaction","detail":"{read_only:false; response_revision:6843; number_of_response:1; }","duration":"211.131767ms","start":"2026-05-05T07:52:16.492224Z","end":"2026-05-05T07:52:16.703355Z","steps":["trace[421772982] 'process raft request' (duration: 210.676817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:16.70363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.200732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T07:52:16.703743Z","caller":"traceutil/trace.go:171","msg":"trace[1228638408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6843; }","duration":"181.354376ms","start":"2026-05-05T07:52:16.522352Z","end":"2026-05-05T07:52:16.703706Z","steps":["trace[1228638408] 'agreement among raft nodes before linearized reading' (duration: 180.945877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:16.73418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.814429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-patch-srlh6\" ","response":"range_response_count:1 size:4332"} {"level":"info","ts":"2026-05-05T07:52:16.734249Z","caller":"traceutil/trace.go:171","msg":"trace[177000167] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-patch-srlh6; range_end:; response_count:1; response_revision:6844; }","duration":"210.936902ms","start":"2026-05-05T07:52:16.523292Z","end":"2026-05-05T07:52:16.734229Z","steps":["trace[177000167] 'agreement among raft nodes before linearized reading' (duration: 210.695927ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:18.738107Z","caller":"traceutil/trace.go:171","msg":"trace[548106119] transaction","detail":"{read_only:false; response_revision:6922; number_of_response:1; }","duration":"176.261913ms","start":"2026-05-05T07:52:18.561808Z","end":"2026-05-05T07:52:18.73807Z","steps":["trace[548106119] 'process raft request' (duration: 175.951526ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:18.896983Z","caller":"traceutil/trace.go:171","msg":"trace[1057114544] transaction","detail":"{read_only:false; response_revision:6923; number_of_response:1; }","duration":"150.282408ms","start":"2026-05-05T07:52:18.74667Z","end":"2026-05-05T07:52:18.896952Z","steps":["trace[1057114544] 'process raft request' (duration: 61.526197ms)","trace[1057114544] 'compare' (duration: 88.465634ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:52:18.897444Z","caller":"traceutil/trace.go:171","msg":"trace[1545713670] transaction","detail":"{read_only:false; response_revision:6924; number_of_response:1; }","duration":"149.251485ms","start":"2026-05-05T07:52:18.748176Z","end":"2026-05-05T07:52:18.897427Z","steps":["trace[1545713670] 'process raft request' (duration: 148.723303ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:19.159668Z","caller":"traceutil/trace.go:171","msg":"trace[656030108] linearizableReadLoop","detail":"{readStateIndex:7178; appliedIndex:7177; }","duration":"189.525303ms","start":"2026-05-05T07:52:18.970109Z","end":"2026-05-05T07:52:19.159635Z","steps":["trace[656030108] 'read index received' (duration: 90.418848ms)","trace[656030108] 'applied index is now lower than readState.Index' (duration: 99.105305ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:52:19.159834Z","caller":"traceutil/trace.go:171","msg":"trace[330170797] transaction","detail":"{read_only:false; response_revision:6926; number_of_response:1; }","duration":"208.557212ms","start":"2026-05-05T07:52:18.951226Z","end":"2026-05-05T07:52:19.159783Z","steps":["trace[330170797] 'process raft request' (duration: 109.361656ms)","trace[330170797] 'compare' (duration: 98.650694ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:52:19.159957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.83037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T07:52:19.159996Z","caller":"traceutil/trace.go:171","msg":"trace[1215095470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6926; }","duration":"189.893012ms","start":"2026-05-05T07:52:18.970092Z","end":"2026-05-05T07:52:19.159985Z","steps":["trace[1215095470] 'agreement among raft nodes before linearized reading' (duration: 189.689947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:19.18853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.402053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T07:52:19.188644Z","caller":"traceutil/trace.go:171","msg":"trace[1797560414] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:6927; }","duration":"190.594607ms","start":"2026-05-05T07:52:18.998023Z","end":"2026-05-05T07:52:19.188617Z","steps":["trace[1797560414] 'agreement among raft nodes before linearized reading' (duration: 190.322671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:19.188621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.522796ms","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-05-05T07:52:19.188745Z","caller":"traceutil/trace.go:171","msg":"trace[679245546] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:6927; }","duration":"151.724051ms","start":"2026-05-05T07:52:19.036999Z","end":"2026-05-05T07:52:19.188723Z","steps":["trace[679245546] 'agreement among raft nodes before linearized reading' (duration: 151.496856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:19.188564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.724132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:52:19.188967Z","caller":"traceutil/trace.go:171","msg":"trace[2137419374] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:6927; }","duration":"113.203603ms","start":"2026-05-05T07:52:19.075734Z","end":"2026-05-05T07:52:19.188938Z","steps":["trace[2137419374] 'agreement among raft nodes before linearized reading' (duration: 112.683071ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:24.624526Z","caller":"traceutil/trace.go:171","msg":"trace[1416882644] transaction","detail":"{read_only:false; response_revision:7048; number_of_response:1; }","duration":"139.878179ms","start":"2026-05-05T07:52:24.48461Z","end":"2026-05-05T07:52:24.624488Z","steps":["trace[1416882644] 'process raft request' (duration: 139.656914ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:25.285885Z","caller":"traceutil/trace.go:171","msg":"trace[2135845109] transaction","detail":"{read_only:false; response_revision:7056; number_of_response:1; }","duration":"225.536635ms","start":"2026-05-05T07:52:25.060316Z","end":"2026-05-05T07:52:25.285853Z","steps":["trace[2135845109] 'process raft request' (duration: 225.361311ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:30.816086Z","caller":"traceutil/trace.go:171","msg":"trace[1336388310] transaction","detail":"{read_only:false; response_revision:7106; number_of_response:1; }","duration":"101.048686ms","start":"2026-05-05T07:52:30.715003Z","end":"2026-05-05T07:52:30.816052Z","steps":["trace[1336388310] 'process raft request' (duration: 100.746889ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:30.987952Z","caller":"traceutil/trace.go:171","msg":"trace[533220450] transaction","detail":"{read_only:false; response_revision:7108; number_of_response:1; }","duration":"163.392426ms","start":"2026-05-05T07:52:30.824517Z","end":"2026-05-05T07:52:30.987909Z","steps":["trace[533220450] 'process raft request' (duration: 163.087049ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:30.988859Z","caller":"traceutil/trace.go:171","msg":"trace[1172107309] transaction","detail":"{read_only:false; response_revision:7109; number_of_response:1; }","duration":"162.523897ms","start":"2026-05-05T07:52:30.826296Z","end":"2026-05-05T07:52:30.98882Z","steps":["trace[1172107309] 'process raft request' (duration: 161.453303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:30.989174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.482596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f66e0827-8323-4ae4-8bd3-920074af85a1\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-05-05T07:52:30.98968Z","caller":"traceutil/trace.go:171","msg":"trace[1550382520] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f66e0827-8323-4ae4-8bd3-920074af85a1; range_end:; response_count:1; response_revision:7110; }","duration":"162.597828ms","start":"2026-05-05T07:52:30.826629Z","end":"2026-05-05T07:52:30.989227Z","steps":["trace[1550382520] 'agreement among raft nodes before linearized reading' (duration: 162.280141ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:30.988554Z","caller":"traceutil/trace.go:171","msg":"trace[54020367] transaction","detail":"{read_only:false; response_revision:7110; number_of_response:1; }","duration":"161.79276ms","start":"2026-05-05T07:52:30.826741Z","end":"2026-05-05T07:52:30.988533Z","steps":["trace[54020367] 'process raft request' (duration: 161.485203ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:30.992787Z","caller":"traceutil/trace.go:171","msg":"trace[1299303691] linearizableReadLoop","detail":"{readStateIndex:7369; appliedIndex:7366; }","duration":"161.74639ms","start":"2026-05-05T07:52:30.826666Z","end":"2026-05-05T07:52:30.988412Z","steps":["trace[1299303691] 'read index received' (duration: 26.394817ms)","trace[1299303691] 'applied index is now lower than readState.Index' (duration: 135.350383ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:52:30.993389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.015695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16085"} {"level":"info","ts":"2026-05-05T07:52:30.993545Z","caller":"traceutil/trace.go:171","msg":"trace[460970830] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:7110; }","duration":"166.19868ms","start":"2026-05-05T07:52:30.827305Z","end":"2026-05-05T07:52:30.993504Z","steps":["trace[460970830] 'agreement among raft nodes before linearized reading' (duration: 165.850162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:30.993981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.843084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:52:30.995037Z","caller":"traceutil/trace.go:171","msg":"trace[2125984667] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:7110; }","duration":"148.919779ms","start":"2026-05-05T07:52:30.846094Z","end":"2026-05-05T07:52:30.995014Z","steps":["trace[2125984667] 'agreement among raft nodes before linearized reading' (duration: 147.844814ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:31.24064Z","caller":"traceutil/trace.go:171","msg":"trace[1368237673] transaction","detail":"{read_only:false; response_revision:7111; number_of_response:1; }","duration":"237.228958ms","start":"2026-05-05T07:52:31.003381Z","end":"2026-05-05T07:52:31.24061Z","steps":["trace[1368237673] 'process raft request' (duration: 236.88009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:31.240953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.532226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T07:52:31.241053Z","caller":"traceutil/trace.go:171","msg":"trace[1372411973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7111; }","duration":"226.68497ms","start":"2026-05-05T07:52:31.014344Z","end":"2026-05-05T07:52:31.241029Z","steps":["trace[1372411973] 'agreement among raft nodes before linearized reading' (duration: 226.362313ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:31.241839Z","caller":"traceutil/trace.go:171","msg":"trace[925016131] linearizableReadLoop","detail":"{readStateIndex:7371; appliedIndex:7370; }","duration":"226.143198ms","start":"2026-05-05T07:52:31.014401Z","end":"2026-05-05T07:52:31.240544Z","steps":["trace[925016131] 'read index received' (duration: 225.77781ms)","trace[925016131] 'applied index is now lower than readState.Index' (duration: 363.698µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:52:31.327628Z","caller":"traceutil/trace.go:171","msg":"trace[1600999106] transaction","detail":"{read_only:false; response_revision:7112; number_of_response:1; }","duration":"180.098576ms","start":"2026-05-05T07:52:31.147492Z","end":"2026-05-05T07:52:31.327591Z","steps":["trace[1600999106] 'process raft request' (duration: 110.512991ms)","trace[1600999106] 'compare' (duration: 68.651373ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:52:31.327808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.616503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T07:52:31.32787Z","caller":"traceutil/trace.go:171","msg":"trace[1787373810] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7112; }","duration":"133.710025ms","start":"2026-05-05T07:52:31.194141Z","end":"2026-05-05T07:52:31.327851Z","steps":["trace[1787373810] 'agreement among raft nodes before linearized reading' (duration: 133.602953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:31.327694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.18628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T07:52:31.328014Z","caller":"traceutil/trace.go:171","msg":"trace[1542005313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7112; }","duration":"223.541759ms","start":"2026-05-05T07:52:31.104459Z","end":"2026-05-05T07:52:31.328001Z","steps":["trace[1542005313] 'agreement among raft nodes before linearized reading' (duration: 223.055358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:31.327695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.073779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T07:52:31.328097Z","caller":"traceutil/trace.go:171","msg":"trace[479215413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7112; }","duration":"273.516969ms","start":"2026-05-05T07:52:31.054571Z","end":"2026-05-05T07:52:31.328088Z","steps":["trace[479215413] 'agreement among raft nodes before linearized reading' (duration: 272.948246ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:31.390957Z","caller":"traceutil/trace.go:171","msg":"trace[1497093324] transaction","detail":"{read_only:false; response_revision:7113; number_of_response:1; }","duration":"141.482902ms","start":"2026-05-05T07:52:31.249412Z","end":"2026-05-05T07:52:31.390895Z","steps":["trace[1497093324] 'process raft request' (duration: 141.065153ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:31.391545Z","caller":"traceutil/trace.go:171","msg":"trace[2058084622] transaction","detail":"{read_only:false; response_revision:7114; number_of_response:1; }","duration":"141.369869ms","start":"2026-05-05T07:52:31.250161Z","end":"2026-05-05T07:52:31.39153Z","steps":["trace[2058084622] 'process raft request' (duration: 140.648233ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:31.391771Z","caller":"traceutil/trace.go:171","msg":"trace[857952927] transaction","detail":"{read_only:false; response_revision:7117; number_of_response:1; }","duration":"138.630907ms","start":"2026-05-05T07:52:31.253105Z","end":"2026-05-05T07:52:31.391736Z","steps":["trace[857952927] 'process raft request' (duration: 138.569046ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:31.392012Z","caller":"traceutil/trace.go:171","msg":"trace[1890346786] transaction","detail":"{read_only:false; response_revision:7115; number_of_response:1; }","duration":"141.791079ms","start":"2026-05-05T07:52:31.250204Z","end":"2026-05-05T07:52:31.391995Z","steps":["trace[1890346786] 'process raft request' (duration: 140.94518ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:31.39211Z","caller":"traceutil/trace.go:171","msg":"trace[497105188] transaction","detail":"{read_only:false; response_revision:7116; number_of_response:1; }","duration":"141.445311ms","start":"2026-05-05T07:52:31.250653Z","end":"2026-05-05T07:52:31.392098Z","steps":["trace[497105188] 'process raft request' (duration: 140.975501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:31.403838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.782634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T07:52:31.403887Z","caller":"traceutil/trace.go:171","msg":"trace[1456349007] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:7121; }","duration":"147.890347ms","start":"2026-05-05T07:52:31.255986Z","end":"2026-05-05T07:52:31.403876Z","steps":["trace[1456349007] 'agreement among raft nodes before linearized reading' (duration: 147.723423ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:31.616543Z","caller":"traceutil/trace.go:171","msg":"trace[1975828423] transaction","detail":"{read_only:false; response_revision:7122; number_of_response:1; }","duration":"209.096382ms","start":"2026-05-05T07:52:31.40742Z","end":"2026-05-05T07:52:31.616516Z","steps":["trace[1975828423] 'process raft request' (duration: 208.898617ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:31.616935Z","caller":"traceutil/trace.go:171","msg":"trace[1160248553] transaction","detail":"{read_only:false; response_revision:7123; number_of_response:1; }","duration":"207.549647ms","start":"2026-05-05T07:52:31.40936Z","end":"2026-05-05T07:52:31.61691Z","steps":["trace[1160248553] 'process raft request' (duration: 207.109817ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:31.616931Z","caller":"traceutil/trace.go:171","msg":"trace[2018501880] linearizableReadLoop","detail":"{readStateIndex:7385; appliedIndex:7381; }","duration":"171.095801ms","start":"2026-05-05T07:52:31.445804Z","end":"2026-05-05T07:52:31.6169Z","steps":["trace[2018501880] 'read index received' (duration: 115.966984ms)","trace[2018501880] 'applied index is now lower than readState.Index' (duration: 55.127437ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:52:31.617208Z","caller":"traceutil/trace.go:171","msg":"trace[2127691851] transaction","detail":"{read_only:false; response_revision:7124; number_of_response:1; }","duration":"206.128464ms","start":"2026-05-05T07:52:31.410855Z","end":"2026-05-05T07:52:31.616983Z","steps":["trace[2127691851] 'process raft request' (duration: 205.91831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:31.619731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.358578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-05T07:52:31.619829Z","caller":"traceutil/trace.go:171","msg":"trace[1349809880] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7124; }","duration":"174.077569ms","start":"2026-05-05T07:52:31.445727Z","end":"2026-05-05T07:52:31.619805Z","steps":["trace[1349809880] 'agreement among raft nodes before linearized reading' (duration: 171.267466ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:31.822536Z","caller":"traceutil/trace.go:171","msg":"trace[1080876008] linearizableReadLoop","detail":"{readStateIndex:7387; appliedIndex:7386; }","duration":"100.423663ms","start":"2026-05-05T07:52:31.722076Z","end":"2026-05-05T07:52:31.8225Z","steps":["trace[1080876008] 'read index received' (duration: 100.215988ms)","trace[1080876008] 'applied index is now lower than readState.Index' (duration: 205.815µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:52:31.822665Z","caller":"traceutil/trace.go:171","msg":"trace[55628165] transaction","detail":"{read_only:false; response_revision:7126; number_of_response:1; }","duration":"103.776948ms","start":"2026-05-05T07:52:31.718864Z","end":"2026-05-05T07:52:31.822641Z","steps":["trace[55628165] 'process raft request' (duration: 103.41197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:31.823274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.17155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-05T07:52:31.823394Z","caller":"traceutil/trace.go:171","msg":"trace[831538627] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:7126; }","duration":"101.287202ms","start":"2026-05-05T07:52:31.722049Z","end":"2026-05-05T07:52:31.823336Z","steps":["trace[831538627] 'agreement among raft nodes before linearized reading' (duration: 100.987045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:31.829239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.819714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:52:31.829317Z","caller":"traceutil/trace.go:171","msg":"trace[833584550] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7127; }","duration":"101.915517ms","start":"2026-05-05T07:52:31.727375Z","end":"2026-05-05T07:52:31.82929Z","steps":["trace[833584550] 'agreement among raft nodes before linearized reading' (duration: 101.810344ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:39.977807Z","caller":"traceutil/trace.go:171","msg":"trace[671683123] transaction","detail":"{read_only:false; response_revision:7235; number_of_response:1; }","duration":"108.0334ms","start":"2026-05-05T07:52:39.86975Z","end":"2026-05-05T07:52:39.977783Z","steps":["trace[671683123] 'process raft request' (duration: 107.904177ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:39.981748Z","caller":"traceutil/trace.go:171","msg":"trace[1752423850] transaction","detail":"{read_only:false; response_revision:7236; number_of_response:1; }","duration":"109.541314ms","start":"2026-05-05T07:52:39.872178Z","end":"2026-05-05T07:52:39.981719Z","steps":["trace[1752423850] 'process raft request' (duration: 108.984452ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:45.646161Z","caller":"traceutil/trace.go:171","msg":"trace[1388953094] transaction","detail":"{read_only:false; response_revision:7284; number_of_response:1; }","duration":"106.218183ms","start":"2026-05-05T07:52:45.539917Z","end":"2026-05-05T07:52:45.646135Z","steps":["trace[1388953094] 'process raft request' (duration: 106.171462ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:45.646254Z","caller":"traceutil/trace.go:171","msg":"trace[1895427785] transaction","detail":"{read_only:false; response_revision:7283; number_of_response:1; }","duration":"107.237646ms","start":"2026-05-05T07:52:45.538976Z","end":"2026-05-05T07:52:45.646214Z","steps":["trace[1895427785] 'process raft request' (duration: 90.831713ms)","trace[1895427785] 'compare' (duration: 16.150887ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:52:56.153485Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828437948700241,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T07:52:56.172728Z","caller":"traceutil/trace.go:171","msg":"trace[562016765] transaction","detail":"{read_only:false; response_revision:7333; number_of_response:1; }","duration":"527.281002ms","start":"2026-05-05T07:52:55.645384Z","end":"2026-05-05T07:52:56.172665Z","steps":["trace[562016765] 'process raft request' (duration: 517.634232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:56.172891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:52:55.645355Z","time spent":"527.448276ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:52:56.176871Z","caller":"traceutil/trace.go:171","msg":"trace[583936726] transaction","detail":"{read_only:false; response_revision:7334; number_of_response:1; }","duration":"471.644485ms","start":"2026-05-05T07:52:55.705188Z","end":"2026-05-05T07:52:56.176833Z","steps":["trace[583936726] 'process raft request' (duration: 470.716084ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:52:56.176873Z","caller":"traceutil/trace.go:171","msg":"trace[1508115431] transaction","detail":"{read_only:false; response_revision:7335; number_of_response:1; }","duration":"410.401149ms","start":"2026-05-05T07:52:55.766443Z","end":"2026-05-05T07:52:56.176844Z","steps":["trace[1508115431] 'process raft request' (duration: 410.311377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:56.176985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:52:55.705159Z","time spent":"471.776748ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:52:56.177043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:52:55.766414Z","time spent":"410.545343ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:52:56.177287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.884669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-05T07:52:56.177443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.200364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cert-manager-controller-challenges\" ","response":"range_response_count:1 size:1599"} {"level":"info","ts":"2026-05-05T07:52:56.177489Z","caller":"traceutil/trace.go:171","msg":"trace[1766111809] range","detail":"{range_begin:/registry/clusterroles/cert-manager-controller-challenges; range_end:; response_count:1; response_revision:7336; }","duration":"586.311707ms","start":"2026-05-05T07:52:55.591166Z","end":"2026-05-05T07:52:56.177478Z","steps":["trace[1766111809] 'agreement among raft nodes before linearized reading' (duration: 586.133813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:56.177529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:52:55.591151Z","time spent":"586.368288ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1622,"request content":"key:\"/registry/clusterroles/cert-manager-controller-challenges\" "} {"level":"info","ts":"2026-05-05T07:52:56.177348Z","caller":"traceutil/trace.go:171","msg":"trace[995019015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7336; }","duration":"582.95011ms","start":"2026-05-05T07:52:55.594383Z","end":"2026-05-05T07:52:56.177334Z","steps":["trace[995019015] 'agreement among raft nodes before linearized reading' (duration: 582.628533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:56.177756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:52:55.594366Z","time spent":"583.330309ms","remote":"127.0.0.1:38492","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":"info","ts":"2026-05-05T07:52:56.176995Z","caller":"traceutil/trace.go:171","msg":"trace[710555119] linearizableReadLoop","detail":"{readStateIndex:7604; appliedIndex:7600; }","duration":"524.199662ms","start":"2026-05-05T07:52:55.652768Z","end":"2026-05-05T07:52:56.176967Z","steps":["trace[710555119] 'read index received' (duration: 510.327576ms)","trace[710555119] 'applied index is now lower than readState.Index' (duration: 13.870406ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:52:56.178588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.368331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T07:52:56.178651Z","caller":"traceutil/trace.go:171","msg":"trace[1932946615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7336; }","duration":"490.502714ms","start":"2026-05-05T07:52:55.688131Z","end":"2026-05-05T07:52:56.178634Z","steps":["trace[1932946615] 'agreement among raft nodes before linearized reading' (duration: 490.279829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:56.178697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:52:55.688103Z","time spent":"490.585066ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T07:52:56.178831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.097706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:52:56.178861Z","caller":"traceutil/trace.go:171","msg":"trace[1621822334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7336; }","duration":"476.153218ms","start":"2026-05-05T07:52:55.7027Z","end":"2026-05-05T07:52:56.178853Z","steps":["trace[1621822334] 'agreement among raft nodes before linearized reading' (duration: 476.104667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:56.179077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.023227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T07:52:56.179128Z","caller":"traceutil/trace.go:171","msg":"trace[95657872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7336; }","duration":"387.108259ms","start":"2026-05-05T07:52:55.792006Z","end":"2026-05-05T07:52:56.179114Z","steps":["trace[95657872] 'agreement among raft nodes before linearized reading' (duration: 386.990566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:56.179165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:52:55.79199Z","time spent":"387.167921ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T07:52:56.179233Z","caller":"traceutil/trace.go:171","msg":"trace[50578711] transaction","detail":"{read_only:false; response_revision:7336; number_of_response:1; }","duration":"121.820786ms","start":"2026-05-05T07:52:56.05737Z","end":"2026-05-05T07:52:56.179191Z","steps":["trace[50578711] 'process raft request' (duration: 119.428031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:56.178886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:52:55.702682Z","time spent":"476.199029ms","remote":"127.0.0.1:38214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T07:52:56.179692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.923952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cert-manager-controller-clusterissuers\" ","response":"range_response_count:1 size:1224"} {"level":"info","ts":"2026-05-05T07:52:56.179799Z","caller":"traceutil/trace.go:171","msg":"trace[568305478] range","detail":"{range_begin:/registry/clusterroles/cert-manager-controller-clusterissuers; range_end:; response_count:1; response_revision:7336; }","duration":"447.059045ms","start":"2026-05-05T07:52:55.732675Z","end":"2026-05-05T07:52:56.179734Z","steps":["trace[568305478] 'agreement among raft nodes before linearized reading' (duration: 445.758125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:56.180308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.998715ms","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-05-05T07:52:56.180509Z","caller":"traceutil/trace.go:171","msg":"trace[332248860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7336; }","duration":"488.070819ms","start":"2026-05-05T07:52:55.692398Z","end":"2026-05-05T07:52:56.180469Z","steps":["trace[332248860] 'agreement among raft nodes before linearized reading' (duration: 486.839061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:52:56.180598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:52:55.692353Z","time spent":"488.216002ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T07:52:56.181543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:52:55.732661Z","time spent":"448.859866ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":1247,"request content":"key:\"/registry/clusterroles/cert-manager-controller-clusterissuers\" "} {"level":"info","ts":"2026-05-05T07:53:38.299549Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4926} {"level":"info","ts":"2026-05-05T07:53:38.342386Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4926,"took":"41.35502ms","hash":601078314,"current-db-size-bytes":38060032,"current-db-size":"38 MB","current-db-size-in-use-bytes":29151232,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-05T07:53:38.342454Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":601078314,"revision":4926,"compact-revision":3013}