{"level":"warn","ts":"2026-10-14T19:42:00.793135Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T19:42:00.793278Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.97: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.97:2380","--initial-cluster=instance=https://199.204.45.97:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.97:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.97: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-10-14T19:42:00.793409Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T19:42:00.793431Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.97:2380"]} {"level":"info","ts":"2026-10-14T19:42:00.793457Z","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-10-14T19:42:00.794345Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.97:2379"]} {"level":"info","ts":"2026-10-14T19:42:00.794453Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.97:2380"],"listen-peer-urls":["https://199.204.45.97:2380"],"advertise-client-urls":["https://199.204.45.97:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.97:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.97: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-10-14T19:42:00.798036Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.213742ms"} {"level":"info","ts":"2026-10-14T19:42:00.80508Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9ac9f866d7f9ac7b","cluster-id":"cdc383debcb174a0"} {"level":"info","ts":"2026-10-14T19:42:00.805257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ac9f866d7f9ac7b switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T19:42:00.805294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ac9f866d7f9ac7b became follower at term 0"} {"level":"info","ts":"2026-10-14T19:42:00.80531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9ac9f866d7f9ac7b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T19:42:00.805333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ac9f866d7f9ac7b became follower at term 1"} {"level":"info","ts":"2026-10-14T19:42:00.805534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ac9f866d7f9ac7b switched to configuration voters=(11153719072753560699)"} {"level":"warn","ts":"2026-10-14T19:42:00.812278Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T19:42:00.815395Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T19:42:00.817755Z","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-10-14T19:42:00.820628Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9ac9f866d7f9ac7b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T19:42:00.820816Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9ac9f866d7f9ac7b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T19:42:00.820941Z","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-10-14T19:42:00.821083Z","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-10-14T19:42:00.821113Z","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-10-14T19:42:00.822014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ac9f866d7f9ac7b switched to configuration voters=(11153719072753560699)"} {"level":"info","ts":"2026-10-14T19:42:00.822132Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc383debcb174a0","local-member-id":"9ac9f866d7f9ac7b","added-peer-id":"9ac9f866d7f9ac7b","added-peer-peer-urls":["https://199.204.45.97:2380"]} {"level":"info","ts":"2026-10-14T19:42:00.823559Z","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-10-14T19:42:00.823613Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.97:2380"} {"level":"info","ts":"2026-10-14T19:42:00.823646Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.97:2380"} {"level":"info","ts":"2026-10-14T19:42:00.823895Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9ac9f866d7f9ac7b","initial-advertise-peer-urls":["https://199.204.45.97:2380"],"listen-peer-urls":["https://199.204.45.97:2380"],"advertise-client-urls":["https://199.204.45.97:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.97:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T19:42:00.823959Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T19:42:01.306703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ac9f866d7f9ac7b is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T19:42:01.306804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ac9f866d7f9ac7b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T19:42:01.306841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ac9f866d7f9ac7b received MsgPreVoteResp from 9ac9f866d7f9ac7b at term 1"} {"level":"info","ts":"2026-10-14T19:42:01.306856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ac9f866d7f9ac7b became candidate at term 2"} {"level":"info","ts":"2026-10-14T19:42:01.306863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ac9f866d7f9ac7b received MsgVoteResp from 9ac9f866d7f9ac7b at term 2"} {"level":"info","ts":"2026-10-14T19:42:01.306873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ac9f866d7f9ac7b became leader at term 2"} {"level":"info","ts":"2026-10-14T19:42:01.306881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9ac9f866d7f9ac7b elected leader 9ac9f866d7f9ac7b at term 2"} {"level":"info","ts":"2026-10-14T19:42:01.307964Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T19:42:01.310606Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9ac9f866d7f9ac7b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.97:2379]}","request-path":"/0/members/9ac9f866d7f9ac7b/attributes","cluster-id":"cdc383debcb174a0","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T19:42:01.310644Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T19:42:01.310672Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T19:42:01.311022Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T19:42:01.311245Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T19:42:01.311261Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc383debcb174a0","local-member-id":"9ac9f866d7f9ac7b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T19:42:01.311358Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T19:42:01.31139Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T19:42:01.31633Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.97:2379"} {"level":"info","ts":"2026-10-14T19:42:01.318704Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T19:42:17.174234Z","caller":"traceutil/trace.go:171","msg":"trace[1054647322] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"146.242978ms","start":"2026-10-14T19:42:17.027907Z","end":"2026-10-14T19:42:17.17415Z","steps":["trace[1054647322] 'process raft request' (duration: 146.106254ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:42:17.175475Z","caller":"traceutil/trace.go:171","msg":"trace[832406134] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"101.117019ms","start":"2026-10-14T19:42:17.074303Z","end":"2026-10-14T19:42:17.17542Z","steps":["trace[832406134] 'process raft request' (duration: 100.995106ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:42:22.768229Z","caller":"traceutil/trace.go:171","msg":"trace[1285994753] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"198.130326ms","start":"2026-10-14T19:42:22.570078Z","end":"2026-10-14T19:42:22.768208Z","steps":["trace[1285994753] 'read index received' (duration: 197.982073ms)","trace[1285994753] 'applied index is now lower than readState.Index' (duration: 147.423µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:42:22.768292Z","caller":"traceutil/trace.go:171","msg":"trace[828601298] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"237.899856ms","start":"2026-10-14T19:42:22.530358Z","end":"2026-10-14T19:42:22.768258Z","steps":["trace[828601298] 'process raft request' (duration: 237.717731ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:42:22.768421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.322381ms","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-10-14T19:42:22.768511Z","caller":"traceutil/trace.go:171","msg":"trace[671342297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"198.447754ms","start":"2026-10-14T19:42:22.570052Z","end":"2026-10-14T19:42:22.7685Z","steps":["trace[671342297] 'agreement among raft nodes before linearized reading' (duration: 198.26729ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:42:22.888901Z","caller":"traceutil/trace.go:171","msg":"trace[318971545] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"116.401761ms","start":"2026-10-14T19:42:22.772474Z","end":"2026-10-14T19:42:22.888876Z","steps":["trace[318971545] 'process raft request' (duration: 114.283313ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:42:28.376965Z","caller":"traceutil/trace.go:171","msg":"trace[314410179] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"391.502828ms","start":"2026-10-14T19:42:27.985399Z","end":"2026-10-14T19:42:28.376902Z","steps":["trace[314410179] 'process raft request' (duration: 391.350684ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:42:28.37792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:42:27.985382Z","time spent":"391.656051ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T19:42:31.349111Z","caller":"traceutil/trace.go:171","msg":"trace[317743768] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"141.143554ms","start":"2026-10-14T19:42:31.207927Z","end":"2026-10-14T19:42:31.34907Z","steps":["trace[317743768] 'process raft request' (duration: 140.729445ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:42:47.163606Z","caller":"traceutil/trace.go:171","msg":"trace[2130854145] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"136.419579ms","start":"2026-10-14T19:42:47.027134Z","end":"2026-10-14T19:42:47.163554Z","steps":["trace[2130854145] 'process raft request' (duration: 136.105992ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:42:54.41732Z","caller":"traceutil/trace.go:171","msg":"trace[1791719270] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"556.774015ms","start":"2026-10-14T19:42:53.860497Z","end":"2026-10-14T19:42:54.417271Z","steps":["trace[1791719270] 'process raft request' (duration: 556.54459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:42:54.417587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:42:53.860476Z","time spent":"556.954089ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T19:42:55.246027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.243209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-14T19:42:55.247528Z","caller":"traceutil/trace.go:171","msg":"trace[1142418961] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"711.482452ms","start":"2026-10-14T19:42:54.534686Z","end":"2026-10-14T19:42:55.246168Z","steps":["trace[1142418961] 'read index received' (duration: 62.661µs)","trace[1142418961] 'applied index is now lower than readState.Index' (duration: 711.417881ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:42:55.247592Z","caller":"traceutil/trace.go:171","msg":"trace[714426826] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.263552762s","start":"2026-10-14T19:42:53.983994Z","end":"2026-10-14T19:42:55.247547Z","steps":["trace[714426826] 'process raft request' (duration: 541.462383ms)","trace[714426826] 'compare' (duration: 719.956481ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:42:55.248725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:42:53.983965Z","time spent":"1.264650527s","remote":"127.0.0.1:58050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T19:42:55.248854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.328339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-14T19:42:55.248888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.550028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.97\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-10-14T19:42:55.247852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.146149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-14T19:42:55.248996Z","caller":"traceutil/trace.go:171","msg":"trace[888256325] range","detail":"{range_begin:/registry/masterleases/199.204.45.97; range_end:; response_count:1; response_revision:654; }","duration":"589.709261ms","start":"2026-10-14T19:42:54.659266Z","end":"2026-10-14T19:42:55.248975Z","steps":["trace[888256325] 'agreement among raft nodes before linearized reading' (duration: 589.513277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:42:55.249044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:42:54.659241Z","time spent":"589.793944ms","remote":"127.0.0.1:57710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.97\" "} {"level":"info","ts":"2026-10-14T19:42:55.24896Z","caller":"traceutil/trace.go:171","msg":"trace[1579136269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"307.419922ms","start":"2026-10-14T19:42:54.941484Z","end":"2026-10-14T19:42:55.248904Z","steps":["trace[1579136269] 'agreement among raft nodes before linearized reading' (duration: 307.32737ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:42:55.249373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:42:54.94147Z","time spent":"307.883122ms","remote":"127.0.0.1:57960","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-10-14T19:42:55.249504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.83137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:42:55.249613Z","caller":"traceutil/trace.go:171","msg":"trace[165648075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"714.378947ms","start":"2026-10-14T19:42:54.534632Z","end":"2026-10-14T19:42:55.249011Z","steps":["trace[165648075] 'agreement among raft nodes before linearized reading' (duration: 712.940735ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:42:55.249706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:42:54.534612Z","time spent":"715.066023ms","remote":"127.0.0.1:57960","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-10-14T19:42:55.249622Z","caller":"traceutil/trace.go:171","msg":"trace[1776847506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"486.015964ms","start":"2026-10-14T19:42:54.76358Z","end":"2026-10-14T19:42:55.249596Z","steps":["trace[1776847506] 'agreement among raft nodes before linearized reading' (duration: 485.297678ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:42:55.249799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:42:54.763566Z","time spent":"486.221308ms","remote":"127.0.0.1:57680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T19:43:08.966725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.959766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:43:08.966837Z","caller":"traceutil/trace.go:171","msg":"trace[1062563367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"203.15017ms","start":"2026-10-14T19:43:08.763665Z","end":"2026-10-14T19:43:08.966815Z","steps":["trace[1062563367] 'range keys from in-memory index tree' (duration: 202.823692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:43:42.071727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.503566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T19:43:42.072213Z","caller":"traceutil/trace.go:171","msg":"trace[472586446] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1227; }","duration":"190.061458ms","start":"2026-10-14T19:43:41.882119Z","end":"2026-10-14T19:43:42.072181Z","steps":["trace[472586446] 'count revisions from in-memory index tree' (duration: 189.408883ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:44:04.242525Z","caller":"traceutil/trace.go:171","msg":"trace[1430328334] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"103.292831ms","start":"2026-10-14T19:44:04.139203Z","end":"2026-10-14T19:44:04.242496Z","steps":["trace[1430328334] 'process raft request' (duration: 102.692147ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:44:06.554429Z","caller":"traceutil/trace.go:171","msg":"trace[378262642] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"244.974389ms","start":"2026-10-14T19:44:06.309422Z","end":"2026-10-14T19:44:06.554397Z","steps":["trace[378262642] 'process raft request' (duration: 244.723354ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:44:06.69652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.586861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T19:44:06.696778Z","caller":"traceutil/trace.go:171","msg":"trace[941494923] transaction","detail":"{read_only:false; response_revision:1495; number_of_response:1; }","duration":"241.525111ms","start":"2026-10-14T19:44:06.455212Z","end":"2026-10-14T19:44:06.696737Z","steps":["trace[941494923] 'process raft request' (duration: 118.581762ms)","trace[941494923] 'compare' (duration: 122.407917ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:44:34.430593Z","caller":"traceutil/trace.go:171","msg":"trace[1959982110] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"162.695308ms","start":"2026-10-14T19:44:34.267854Z","end":"2026-10-14T19:44:34.430549Z","steps":["trace[1959982110] 'process raft request' (duration: 114.270445ms)","trace[1959982110] 'compare' (duration: 48.131256ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:44:34.743634Z","caller":"traceutil/trace.go:171","msg":"trace[805639963] linearizableReadLoop","detail":"{readStateIndex:2006; appliedIndex:2005; }","duration":"296.849168ms","start":"2026-10-14T19:44:34.446757Z","end":"2026-10-14T19:44:34.743607Z","steps":["trace[805639963] 'read index received' (duration: 296.681355ms)","trace[805639963] 'applied index is now lower than readState.Index' (duration: 166.493µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:44:34.743672Z","caller":"traceutil/trace.go:171","msg":"trace[1143803711] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"305.097502ms","start":"2026-10-14T19:44:34.438551Z","end":"2026-10-14T19:44:34.743649Z","steps":["trace[1143803711] 'process raft request' (duration: 304.888168ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:44:34.743815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:44:34.43852Z","time spent":"305.208905ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T19:44:34.743861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.077733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:44:34.74396Z","caller":"traceutil/trace.go:171","msg":"trace[1968209551] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1930; }","duration":"297.202006ms","start":"2026-10-14T19:44:34.446736Z","end":"2026-10-14T19:44:34.743938Z","steps":["trace[1968209551] 'agreement among raft nodes before linearized reading' (duration: 297.014452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:44:35.145031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.830585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T19:44:35.145182Z","caller":"traceutil/trace.go:171","msg":"trace[1395944720] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"376.019688ms","start":"2026-10-14T19:44:34.769132Z","end":"2026-10-14T19:44:35.145152Z","steps":["trace[1395944720] 'process raft request' (duration: 58.933427ms)","trace[1395944720] 'compare' (duration: 316.678892ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:44:35.145261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:44:34.769114Z","time spent":"376.10877ms","remote":"127.0.0.1:57710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T19:44:35.150801Z","caller":"traceutil/trace.go:171","msg":"trace[514235511] linearizableReadLoop","detail":"{readStateIndex:2011; appliedIndex:2010; }","duration":"250.039801ms","start":"2026-10-14T19:44:34.900741Z","end":"2026-10-14T19:44:35.150781Z","steps":["trace[514235511] 'read index received' (duration: 249.872947ms)","trace[514235511] 'applied index is now lower than readState.Index' (duration: 165.664µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:44:35.151097Z","caller":"traceutil/trace.go:171","msg":"trace[1728236159] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"353.037485ms","start":"2026-10-14T19:44:34.798007Z","end":"2026-10-14T19:44:35.151045Z","steps":["trace[1728236159] 'process raft request' (duration: 352.664727ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:44:35.151124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.357289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:44:35.151232Z","caller":"traceutil/trace.go:171","msg":"trace[1431043369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1934; }","duration":"250.535743ms","start":"2026-10-14T19:44:34.900674Z","end":"2026-10-14T19:44:35.15121Z","steps":["trace[1431043369] 'agreement among raft nodes before linearized reading' (duration: 250.242086ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:44:35.151323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:44:34.797981Z","time spent":"353.216338ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T19:44:35.151218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.618686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-10-14T19:44:35.151377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.185176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c6308db7-232d-46b2-9afc-ee77b46d8198\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-10-14T19:44:35.151449Z","caller":"traceutil/trace.go:171","msg":"trace[1320560359] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1934; }","duration":"179.875243ms","start":"2026-10-14T19:44:34.971551Z","end":"2026-10-14T19:44:35.151427Z","steps":["trace[1320560359] 'agreement among raft nodes before linearized reading' (duration: 179.425493ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:44:35.151468Z","caller":"traceutil/trace.go:171","msg":"trace[1260955587] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c6308db7-232d-46b2-9afc-ee77b46d8198; range_end:; response_count:1; response_revision:1934; }","duration":"149.296239ms","start":"2026-10-14T19:44:35.002147Z","end":"2026-10-14T19:44:35.151443Z","steps":["trace[1260955587] 'agreement among raft nodes before linearized reading' (duration: 148.966822ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:44:48.215398Z","caller":"traceutil/trace.go:171","msg":"trace[1861003463] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"181.979109ms","start":"2026-10-14T19:44:48.033388Z","end":"2026-10-14T19:44:48.215367Z","steps":["trace[1861003463] 'process raft request' (duration: 181.684033ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:44:48.218466Z","caller":"traceutil/trace.go:171","msg":"trace[1245452475] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"183.77154ms","start":"2026-10-14T19:44:48.034676Z","end":"2026-10-14T19:44:48.218448Z","steps":["trace[1245452475] 'process raft request' (duration: 183.648187ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:44:48.218827Z","caller":"traceutil/trace.go:171","msg":"trace[442974430] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"182.183005ms","start":"2026-10-14T19:44:48.0366Z","end":"2026-10-14T19:44:48.218783Z","steps":["trace[442974430] 'process raft request' (duration: 181.806146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:45:13.275889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.407512ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:45:13.27609Z","caller":"traceutil/trace.go:171","msg":"trace[512596167] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2192; }","duration":"104.951577ms","start":"2026-10-14T19:45:13.171113Z","end":"2026-10-14T19:45:13.276065Z","steps":["trace[512596167] 'range keys from in-memory index tree' (duration: 103.381052ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:45:24.643053Z","caller":"traceutil/trace.go:171","msg":"trace[610172927] linearizableReadLoop","detail":"{readStateIndex:2336; appliedIndex:2335; }","duration":"213.996156ms","start":"2026-10-14T19:45:24.429032Z","end":"2026-10-14T19:45:24.643028Z","steps":["trace[610172927] 'read index received' (duration: 213.952185ms)","trace[610172927] 'applied index is now lower than readState.Index' (duration: 43.081µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:45:24.643129Z","caller":"traceutil/trace.go:171","msg":"trace[347892345] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"223.525692ms","start":"2026-10-14T19:45:24.419568Z","end":"2026-10-14T19:45:24.643094Z","steps":["trace[347892345] 'process raft request' (duration: 223.333218ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:45:24.643519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.472367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-14T19:45:24.643577Z","caller":"traceutil/trace.go:171","msg":"trace[1922488581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2249; }","duration":"214.59196ms","start":"2026-10-14T19:45:24.428968Z","end":"2026-10-14T19:45:24.643559Z","steps":["trace[1922488581] 'agreement among raft nodes before linearized reading' (duration: 214.191411ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:45:24.660188Z","caller":"traceutil/trace.go:171","msg":"trace[1579350578] transaction","detail":"{read_only:false; response_revision:2250; number_of_response:1; }","duration":"155.531083ms","start":"2026-10-14T19:45:24.504626Z","end":"2026-10-14T19:45:24.660157Z","steps":["trace[1579350578] 'process raft request' (duration: 155.341768ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:45:24.660249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.663622ms","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-10-14T19:45:24.660299Z","caller":"traceutil/trace.go:171","msg":"trace[249664331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2250; }","duration":"218.763973ms","start":"2026-10-14T19:45:24.441522Z","end":"2026-10-14T19:45:24.660286Z","steps":["trace[249664331] 'agreement among raft nodes before linearized reading' (duration: 218.582759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:45:24.660306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.840418ms","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-10-14T19:45:24.660382Z","caller":"traceutil/trace.go:171","msg":"trace[1622780696] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2250; }","duration":"124.96058ms","start":"2026-10-14T19:45:24.535405Z","end":"2026-10-14T19:45:24.660365Z","steps":["trace[1622780696] 'agreement among raft nodes before linearized reading' (duration: 124.804457ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:45:25.465362Z","caller":"traceutil/trace.go:171","msg":"trace[159830281] linearizableReadLoop","detail":"{readStateIndex:2343; appliedIndex:2342; }","duration":"402.122396ms","start":"2026-10-14T19:45:25.063192Z","end":"2026-10-14T19:45:25.465314Z","steps":["trace[159830281] 'read index received' (duration: 402.025704ms)","trace[159830281] 'applied index is now lower than readState.Index' (duration: 95.592µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:45:25.465437Z","caller":"traceutil/trace.go:171","msg":"trace[1995304002] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"408.562692ms","start":"2026-10-14T19:45:25.05684Z","end":"2026-10-14T19:45:25.465403Z","steps":["trace[1995304002] 'process raft request' (duration: 408.332257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:45:25.465629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:45:25.056811Z","time spent":"408.725706ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T19:45:25.465649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.431214ms","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-10-14T19:45:25.465692Z","caller":"traceutil/trace.go:171","msg":"trace[620130330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2255; }","duration":"402.534796ms","start":"2026-10-14T19:45:25.063144Z","end":"2026-10-14T19:45:25.465679Z","steps":["trace[620130330] 'agreement among raft nodes before linearized reading' (duration: 402.302891ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:45:25.465725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:45:25.063125Z","time spent":"402.590087ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-14T19:45:25.466855Z","caller":"traceutil/trace.go:171","msg":"trace[1346552645] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"120.638462ms","start":"2026-10-14T19:45:25.346196Z","end":"2026-10-14T19:45:25.466834Z","steps":["trace[1346552645] 'process raft request' (duration: 120.493519ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:45:30.798191Z","caller":"traceutil/trace.go:171","msg":"trace[1622587648] transaction","detail":"{read_only:false; response_revision:2280; number_of_response:1; }","duration":"118.582895ms","start":"2026-10-14T19:45:30.679577Z","end":"2026-10-14T19:45:30.79816Z","steps":["trace[1622587648] 'process raft request' (duration: 118.312409ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:45:30.854981Z","caller":"traceutil/trace.go:171","msg":"trace[428267045] transaction","detail":"{read_only:false; response_revision:2281; number_of_response:1; }","duration":"151.00928ms","start":"2026-10-14T19:45:30.703952Z","end":"2026-10-14T19:45:30.854961Z","steps":["trace[428267045] 'process raft request' (duration: 130.71276ms)","trace[428267045] 'compare' (duration: 19.987183ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:45:30.855079Z","caller":"traceutil/trace.go:171","msg":"trace[1298926993] linearizableReadLoop","detail":"{readStateIndex:2370; appliedIndex:2368; }","duration":"147.597903ms","start":"2026-10-14T19:45:30.707197Z","end":"2026-10-14T19:45:30.854795Z","steps":["trace[1298926993] 'read index received' (duration: 90.757476ms)","trace[1298926993] 'applied index is now lower than readState.Index' (duration: 56.839127ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:45:30.855305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.089074ms","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-10-14T19:45:30.855454Z","caller":"traceutil/trace.go:171","msg":"trace[92792400] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2281; }","duration":"148.245717ms","start":"2026-10-14T19:45:30.707182Z","end":"2026-10-14T19:45:30.855428Z","steps":["trace[92792400] 'agreement among raft nodes before linearized reading' (duration: 147.970361ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:45:30.902382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.036399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:45:30.902457Z","caller":"traceutil/trace.go:171","msg":"trace[1939638070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2282; }","duration":"139.13411ms","start":"2026-10-14T19:45:30.7633Z","end":"2026-10-14T19:45:30.902434Z","steps":["trace[1939638070] 'agreement among raft nodes before linearized reading' (duration: 138.906745ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:45:30.902423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.936052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-14T19:45:30.902562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.875644ms","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-10-14T19:45:30.902665Z","caller":"traceutil/trace.go:171","msg":"trace[733908950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2282; }","duration":"188.029958ms","start":"2026-10-14T19:45:30.714613Z","end":"2026-10-14T19:45:30.902643Z","steps":["trace[733908950] 'agreement among raft nodes before linearized reading' (duration: 187.707501ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:45:30.902632Z","caller":"traceutil/trace.go:171","msg":"trace[951787657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2282; }","duration":"194.100556ms","start":"2026-10-14T19:45:30.708471Z","end":"2026-10-14T19:45:30.902572Z","steps":["trace[951787657] 'agreement among raft nodes before linearized reading' (duration: 193.703667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:46:18.101625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.410794ms","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-10-14T19:46:18.101796Z","caller":"traceutil/trace.go:171","msg":"trace[1780362434] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2711; }","duration":"181.608228ms","start":"2026-10-14T19:46:17.92012Z","end":"2026-10-14T19:46:18.101729Z","steps":["trace[1780362434] 'range keys from in-memory index tree' (duration: 181.295691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:46:51.227044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.728095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T19:46:51.227148Z","caller":"traceutil/trace.go:171","msg":"trace[1747578252] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2908; }","duration":"195.92852ms","start":"2026-10-14T19:46:51.031199Z","end":"2026-10-14T19:46:51.227128Z","steps":["trace[1747578252] 'count revisions from in-memory index tree' (duration: 195.633544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:46:51.672322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.279792ms","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-10-14T19:46:51.672441Z","caller":"traceutil/trace.go:171","msg":"trace[1039154146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2910; }","duration":"320.460046ms","start":"2026-10-14T19:46:51.351943Z","end":"2026-10-14T19:46:51.672403Z","steps":["trace[1039154146] 'range keys from in-memory index tree' (duration: 320.053257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:46:51.67249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:46:51.351909Z","time spent":"320.569359ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T19:46:51.672532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.72951ms","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-10-14T19:46:51.672652Z","caller":"traceutil/trace.go:171","msg":"trace[592617167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2910; }","duration":"319.932245ms","start":"2026-10-14T19:46:51.352688Z","end":"2026-10-14T19:46:51.67262Z","steps":["trace[592617167] 'range keys from in-memory index tree' (duration: 319.476204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:46:51.67277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:46:51.352658Z","time spent":"320.039946ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-14T19:46:57.36619Z","caller":"traceutil/trace.go:171","msg":"trace[1512627132] linearizableReadLoop","detail":"{readStateIndex:3057; appliedIndex:3056; }","duration":"104.008095ms","start":"2026-10-14T19:46:57.262152Z","end":"2026-10-14T19:46:57.36616Z","steps":["trace[1512627132] 'read index received' (duration: 103.821611ms)","trace[1512627132] 'applied index is now lower than readState.Index' (duration: 185.514µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:46:57.366302Z","caller":"traceutil/trace.go:171","msg":"trace[1162768039] transaction","detail":"{read_only:false; response_revision:2942; number_of_response:1; }","duration":"114.275226ms","start":"2026-10-14T19:46:57.251971Z","end":"2026-10-14T19:46:57.366246Z","steps":["trace[1162768039] 'process raft request' (duration: 114.00699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:46:57.36648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.308592ms","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-10-14T19:46:57.366597Z","caller":"traceutil/trace.go:171","msg":"trace[825403766] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2942; }","duration":"104.485156ms","start":"2026-10-14T19:46:57.262085Z","end":"2026-10-14T19:46:57.36657Z","steps":["trace[825403766] 'agreement among raft nodes before linearized reading' (duration: 104.19628ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:49:31.938263Z","caller":"traceutil/trace.go:171","msg":"trace[628665962] transaction","detail":"{read_only:false; response_revision:3879; number_of_response:1; }","duration":"129.899921ms","start":"2026-10-14T19:49:31.808317Z","end":"2026-10-14T19:49:31.938217Z","steps":["trace[628665962] 'process raft request' (duration: 128.086821ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:49:31.940731Z","caller":"traceutil/trace.go:171","msg":"trace[889523688] transaction","detail":"{read_only:false; response_revision:3880; number_of_response:1; }","duration":"130.842602ms","start":"2026-10-14T19:49:31.809859Z","end":"2026-10-14T19:49:31.940702Z","steps":["trace[889523688] 'process raft request' (duration: 130.586876ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:49:31.941299Z","caller":"traceutil/trace.go:171","msg":"trace[1802101219] transaction","detail":"{read_only:false; response_revision:3881; number_of_response:1; }","duration":"127.436916ms","start":"2026-10-14T19:49:31.813812Z","end":"2026-10-14T19:49:31.941249Z","steps":["trace[1802101219] 'process raft request' (duration: 126.805702ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:49:58.731352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.699949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T19:49:58.731625Z","caller":"traceutil/trace.go:171","msg":"trace[321027252] transaction","detail":"{read_only:false; response_revision:4097; number_of_response:1; }","duration":"247.128212ms","start":"2026-10-14T19:49:58.484411Z","end":"2026-10-14T19:49:58.731539Z","steps":["trace[321027252] 'process raft request' (duration: 34.150227ms)","trace[321027252] 'compare' (duration: 212.583326ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:49:58.850579Z","caller":"traceutil/trace.go:171","msg":"trace[832545207] transaction","detail":"{read_only:false; response_revision:4098; number_of_response:1; }","duration":"114.807697ms","start":"2026-10-14T19:49:58.735742Z","end":"2026-10-14T19:49:58.85055Z","steps":["trace[832545207] 'process raft request' (duration: 114.126471ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:49:58.860044Z","caller":"traceutil/trace.go:171","msg":"trace[566961125] linearizableReadLoop","detail":"{readStateIndex:4257; appliedIndex:4255; }","duration":"123.742715ms","start":"2026-10-14T19:49:58.736268Z","end":"2026-10-14T19:49:58.86001Z","steps":["trace[566961125] 'read index received' (duration: 113.564499ms)","trace[566961125] 'applied index is now lower than readState.Index' (duration: 10.177266ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:49:58.860144Z","caller":"traceutil/trace.go:171","msg":"trace[1663921059] transaction","detail":"{read_only:false; response_revision:4099; number_of_response:1; }","duration":"124.073393ms","start":"2026-10-14T19:49:58.736023Z","end":"2026-10-14T19:49:58.860096Z","steps":["trace[1663921059] 'process raft request' (duration: 123.868718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:49:58.860423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.144564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62973"} {"level":"info","ts":"2026-10-14T19:49:58.86049Z","caller":"traceutil/trace.go:171","msg":"trace[1741313697] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4099; }","duration":"124.266567ms","start":"2026-10-14T19:49:58.73621Z","end":"2026-10-14T19:49:58.860477Z","steps":["trace[1741313697] 'agreement among raft nodes before linearized reading' (duration: 123.93313ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:51:25.461681Z","caller":"traceutil/trace.go:171","msg":"trace[1695525350] transaction","detail":"{read_only:false; response_revision:4656; number_of_response:1; }","duration":"102.485528ms","start":"2026-10-14T19:51:25.359155Z","end":"2026-10-14T19:51:25.461641Z","steps":["trace[1695525350] 'process raft request' (duration: 89.07117ms)","trace[1695525350] 'compare' (duration: 13.004108ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:51:40.958771Z","caller":"traceutil/trace.go:171","msg":"trace[1924250434] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"109.3264ms","start":"2026-10-14T19:51:40.849413Z","end":"2026-10-14T19:51:40.958739Z","steps":["trace[1924250434] 'process raft request' (duration: 109.085474ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:51:41.652233Z","caller":"traceutil/trace.go:171","msg":"trace[486652374] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"210.890336ms","start":"2026-10-14T19:51:41.441322Z","end":"2026-10-14T19:51:41.652212Z","steps":["trace[486652374] 'process raft request' (duration: 210.746212ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:51:41.679353Z","caller":"traceutil/trace.go:171","msg":"trace[571719945] transaction","detail":"{read_only:false; response_revision:5248; number_of_response:1; }","duration":"225.90174ms","start":"2026-10-14T19:51:41.453417Z","end":"2026-10-14T19:51:41.679319Z","steps":["trace[571719945] 'process raft request' (duration: 225.650144ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:51:41.679627Z","caller":"traceutil/trace.go:171","msg":"trace[342414618] linearizableReadLoop","detail":"{readStateIndex:5453; appliedIndex:5451; }","duration":"154.855451ms","start":"2026-10-14T19:51:41.524701Z","end":"2026-10-14T19:51:41.679556Z","steps":["trace[342414618] 'read index received' (duration: 127.37107ms)","trace[342414618] 'applied index is now lower than readState.Index' (duration: 27.481021ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:51:41.680046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.000574ms","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-10-14T19:51:41.680146Z","caller":"traceutil/trace.go:171","msg":"trace[573587557] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5249; }","duration":"128.157348ms","start":"2026-10-14T19:51:41.551965Z","end":"2026-10-14T19:51:41.680122Z","steps":["trace[573587557] 'agreement among raft nodes before linearized reading' (duration: 127.867781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:51:41.680036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.296791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-10-14T19:51:41.680264Z","caller":"traceutil/trace.go:171","msg":"trace[2012483646] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5249; }","duration":"155.580887ms","start":"2026-10-14T19:51:41.524655Z","end":"2026-10-14T19:51:41.680236Z","steps":["trace[2012483646] 'agreement among raft nodes before linearized reading' (duration: 155.073436ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:51:45.143192Z","caller":"traceutil/trace.go:171","msg":"trace[1448544438] transaction","detail":"{read_only:false; response_revision:5373; number_of_response:1; }","duration":"158.493272ms","start":"2026-10-14T19:51:44.98467Z","end":"2026-10-14T19:51:45.143163Z","steps":["trace[1448544438] 'process raft request' (duration: 158.234156ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:51:45.14334Z","caller":"traceutil/trace.go:171","msg":"trace[1979559445] linearizableReadLoop","detail":"{readStateIndex:5583; appliedIndex:5581; }","duration":"143.390367ms","start":"2026-10-14T19:51:44.999924Z","end":"2026-10-14T19:51:45.143314Z","steps":["trace[1979559445] 'read index received' (duration: 143.07424ms)","trace[1979559445] 'applied index is now lower than readState.Index' (duration: 314.657µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:51:45.14339Z","caller":"traceutil/trace.go:171","msg":"trace[1485501004] transaction","detail":"{read_only:false; response_revision:5374; number_of_response:1; }","duration":"158.322588ms","start":"2026-10-14T19:51:44.985048Z","end":"2026-10-14T19:51:45.14337Z","steps":["trace[1485501004] 'process raft request' (duration: 158.046712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:51:45.143781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.832527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/monitoring\" ","response":"range_response_count:1 size:372"} {"level":"info","ts":"2026-10-14T19:51:45.143829Z","caller":"traceutil/trace.go:171","msg":"trace[1415740000] range","detail":"{range_begin:/registry/namespaces/monitoring; range_end:; response_count:1; response_revision:5374; }","duration":"143.915278ms","start":"2026-10-14T19:51:44.999901Z","end":"2026-10-14T19:51:45.143816Z","steps":["trace[1415740000] 'agreement among raft nodes before linearized reading' (duration: 143.514589ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:51:45.24755Z","caller":"traceutil/trace.go:171","msg":"trace[31079876] transaction","detail":"{read_only:false; response_revision:5375; number_of_response:1; }","duration":"245.360473ms","start":"2026-10-14T19:51:45.002165Z","end":"2026-10-14T19:51:45.247526Z","steps":["trace[31079876] 'process raft request' (duration: 193.649814ms)","trace[31079876] 'compare' (duration: 51.541605ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:51:45.247543Z","caller":"traceutil/trace.go:171","msg":"trace[138002210] linearizableReadLoop","detail":"{readStateIndex:5584; appliedIndex:5583; }","duration":"104.111593ms","start":"2026-10-14T19:51:45.143403Z","end":"2026-10-14T19:51:45.247514Z","steps":["trace[138002210] 'read index received' (duration: 52.495447ms)","trace[138002210] 'applied index is now lower than readState.Index' (duration: 51.615016ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:51:45.247667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.613801ms","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-10-14T19:51:45.247714Z","caller":"traceutil/trace.go:171","msg":"trace[886161971] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:5375; }","duration":"224.692453ms","start":"2026-10-14T19:51:45.023008Z","end":"2026-10-14T19:51:45.2477Z","steps":["trace[886161971] 'agreement among raft nodes before linearized reading' (duration: 224.5996ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:51:45.266545Z","caller":"traceutil/trace.go:171","msg":"trace[891721877] transaction","detail":"{read_only:false; response_revision:5376; number_of_response:1; }","duration":"109.363341ms","start":"2026-10-14T19:51:45.157152Z","end":"2026-10-14T19:51:45.266515Z","steps":["trace[891721877] 'process raft request' (duration: 109.071534ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:51:45.560395Z","caller":"traceutil/trace.go:171","msg":"trace[1785487078] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"154.494004ms","start":"2026-10-14T19:51:45.405873Z","end":"2026-10-14T19:51:45.560367Z","steps":["trace[1785487078] 'process raft request' (duration: 154.430312ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:51:45.560453Z","caller":"traceutil/trace.go:171","msg":"trace[1192684584] transaction","detail":"{read_only:false; response_revision:5387; number_of_response:1; }","duration":"171.613224ms","start":"2026-10-14T19:51:45.388802Z","end":"2026-10-14T19:51:45.560415Z","steps":["trace[1192684584] 'process raft request' (duration: 124.839274ms)","trace[1192684584] 'compare' (duration: 46.489383ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:51:46.971722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.251879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-76ce2950-47bf-48f5-a808-bd1efc868bef\" ","response":"range_response_count:1 size:3890"} {"level":"info","ts":"2026-10-14T19:51:46.971813Z","caller":"traceutil/trace.go:171","msg":"trace[235940887] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-76ce2950-47bf-48f5-a808-bd1efc868bef; range_end:; response_count:1; response_revision:5408; }","duration":"114.416562ms","start":"2026-10-14T19:51:46.857373Z","end":"2026-10-14T19:51:46.971789Z","steps":["trace[235940887] 'range keys from in-memory index tree' (duration: 114.011204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:52:01.485103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.244259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T19:52:01.485634Z","caller":"traceutil/trace.go:171","msg":"trace[1948801873] transaction","detail":"{read_only:false; response_revision:5649; number_of_response:1; }","duration":"424.192226ms","start":"2026-10-14T19:52:01.061353Z","end":"2026-10-14T19:52:01.485545Z","steps":["trace[1948801873] 'process raft request' (duration: 138.314844ms)","trace[1948801873] 'compare' (duration: 284.691996ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:52:01.485768Z","caller":"traceutil/trace.go:171","msg":"trace[1248457520] transaction","detail":"{read_only:false; response_revision:5651; number_of_response:1; }","duration":"423.403199ms","start":"2026-10-14T19:52:01.062317Z","end":"2026-10-14T19:52:01.48572Z","steps":["trace[1248457520] 'process raft request' (duration: 423.238085ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:52:01.485751Z","caller":"traceutil/trace.go:171","msg":"trace[186154196] linearizableReadLoop","detail":"{readStateIndex:5868; appliedIndex:5865; }","duration":"377.529009ms","start":"2026-10-14T19:52:01.108193Z","end":"2026-10-14T19:52:01.485722Z","steps":["trace[186154196] 'read index received' (duration: 91.33764ms)","trace[186154196] 'applied index is now lower than readState.Index' (duration: 286.189449ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:52:01.485798Z","caller":"traceutil/trace.go:171","msg":"trace[1371502777] transaction","detail":"{read_only:false; response_revision:5650; number_of_response:1; }","duration":"423.648274ms","start":"2026-10-14T19:52:01.06211Z","end":"2026-10-14T19:52:01.485758Z","steps":["trace[1371502777] 'process raft request' (duration: 423.164613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:52:01.485872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:52:01.061314Z","time spent":"424.433512ms","remote":"127.0.0.1:57858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":528,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T19:52:01.485916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:52:01.062297Z","time spent":"423.536582ms","remote":"127.0.0.1:57970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1192,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T19:52:01.485975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:52:01.062077Z","time spent":"423.809938ms","remote":"127.0.0.1:57970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1066,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T19:52:01.486096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.896677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-14T19:52:01.486136Z","caller":"traceutil/trace.go:171","msg":"trace[755854743] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5651; }","duration":"377.980739ms","start":"2026-10-14T19:52:01.108141Z","end":"2026-10-14T19:52:01.486121Z","steps":["trace[755854743] 'agreement among raft nodes before linearized reading' (duration: 377.761494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:52:01.486178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:52:01.108118Z","time spent":"378.048211ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-14T19:52:01.491661Z","caller":"traceutil/trace.go:171","msg":"trace[1798198680] transaction","detail":"{read_only:false; response_revision:5652; number_of_response:1; }","duration":"259.450456ms","start":"2026-10-14T19:52:01.232192Z","end":"2026-10-14T19:52:01.491642Z","steps":["trace[1798198680] 'process raft request' (duration: 259.320073ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:52:01.578869Z","caller":"traceutil/trace.go:171","msg":"trace[971004849] transaction","detail":"{read_only:false; response_revision:5653; number_of_response:1; }","duration":"344.840763ms","start":"2026-10-14T19:52:01.233997Z","end":"2026-10-14T19:52:01.578838Z","steps":["trace[971004849] 'process raft request' (duration: 344.604908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:52:01.579094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:52:01.233962Z","time spent":"345.007636ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T19:52:01.579304Z","caller":"traceutil/trace.go:171","msg":"trace[316817127] transaction","detail":"{read_only:false; response_revision:5654; number_of_response:1; }","duration":"239.435731ms","start":"2026-10-14T19:52:01.339791Z","end":"2026-10-14T19:52:01.579227Z","steps":["trace[316817127] 'process raft request' (duration: 238.974011ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:52:01.579718Z","caller":"traceutil/trace.go:171","msg":"trace[1995185122] transaction","detail":"{read_only:false; response_revision:5655; number_of_response:1; }","duration":"224.677912ms","start":"2026-10-14T19:52:01.355021Z","end":"2026-10-14T19:52:01.579699Z","steps":["trace[1995185122] 'process raft request' (duration: 224.099179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:52:01.580078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.646676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T19:52:01.580188Z","caller":"traceutil/trace.go:171","msg":"trace[1342723706] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5655; }","duration":"334.862302ms","start":"2026-10-14T19:52:01.245302Z","end":"2026-10-14T19:52:01.580164Z","steps":["trace[1342723706] 'agreement among raft nodes before linearized reading' (duration: 334.035433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:52:01.580242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:52:01.245271Z","time spent":"334.958283ms","remote":"127.0.0.1:46478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-14T19:52:01.580339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.039361ms","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-10-14T19:52:01.580406Z","caller":"traceutil/trace.go:171","msg":"trace[1266279475] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:5655; }","duration":"396.199014ms","start":"2026-10-14T19:52:01.184192Z","end":"2026-10-14T19:52:01.580391Z","steps":["trace[1266279475] 'agreement among raft nodes before linearized reading' (duration: 395.217012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:52:01.580442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:52:01.184159Z","time spent":"396.273356ms","remote":"127.0.0.1:57988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":31,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-10-14T19:52:01.580113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.394ms","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-10-14T19:52:01.580699Z","caller":"traceutil/trace.go:171","msg":"trace[1577120805] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5655; }","duration":"231.028854ms","start":"2026-10-14T19:52:01.349655Z","end":"2026-10-14T19:52:01.580684Z","steps":["trace[1577120805] 'agreement among raft nodes before linearized reading' (duration: 229.692355ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:52:01.589151Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2962} {"level":"info","ts":"2026-10-14T19:52:01.689874Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2962,"took":"97.289142ms","hash":172932736,"current-db-size-bytes":54329344,"current-db-size":"54 MB","current-db-size-in-use-bytes":31211520,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T19:52:01.689978Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":172932736,"revision":2962,"compact-revision":-1} {"level":"info","ts":"2026-10-14T19:52:09.963472Z","caller":"traceutil/trace.go:171","msg":"trace[835330409] linearizableReadLoop","detail":"{readStateIndex:6058; appliedIndex:6057; }","duration":"199.777999ms","start":"2026-10-14T19:52:09.763657Z","end":"2026-10-14T19:52:09.963435Z","steps":["trace[835330409] 'read index received' (duration: 168.248718ms)","trace[835330409] 'applied index is now lower than readState.Index' (duration: 31.527371ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:52:09.963775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.099806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:52:09.96383Z","caller":"traceutil/trace.go:171","msg":"trace[1244568604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5836; }","duration":"200.175678ms","start":"2026-10-14T19:52:09.763638Z","end":"2026-10-14T19:52:09.963813Z","steps":["trace[1244568604] 'agreement among raft nodes before linearized reading' (duration: 200.049235ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:52:09.963914Z","caller":"traceutil/trace.go:171","msg":"trace[754457137] transaction","detail":"{read_only:false; response_revision:5836; number_of_response:1; }","duration":"271.404451ms","start":"2026-10-14T19:52:09.692271Z","end":"2026-10-14T19:52:09.963675Z","steps":["trace[754457137] 'process raft request' (duration: 239.619345ms)","trace[754457137] 'compare' (duration: 31.132162ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:52:10.290782Z","caller":"traceutil/trace.go:171","msg":"trace[1588137253] linearizableReadLoop","detail":"{readStateIndex:6059; appliedIndex:6058; }","duration":"327.114059ms","start":"2026-10-14T19:52:09.963634Z","end":"2026-10-14T19:52:10.290748Z","steps":["trace[1588137253] 'read index received' (duration: 326.992037ms)","trace[1588137253] 'applied index is now lower than readState.Index' (duration: 120.512µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:52:10.290955Z","caller":"traceutil/trace.go:171","msg":"trace[114849260] transaction","detail":"{read_only:false; response_revision:5837; number_of_response:1; }","duration":"464.408239ms","start":"2026-10-14T19:52:09.826484Z","end":"2026-10-14T19:52:10.290892Z","steps":["trace[114849260] 'process raft request' (duration: 464.034381ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:52:10.291193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:52:09.82644Z","time spent":"464.626094ms","remote":"127.0.0.1:57872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19852,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T19:52:10.291297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.789386ms","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-10-14T19:52:10.291305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.383853ms","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-10-14T19:52:10.291433Z","caller":"traceutil/trace.go:171","msg":"trace[516192892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5837; }","duration":"409.993201ms","start":"2026-10-14T19:52:09.881407Z","end":"2026-10-14T19:52:10.2914Z","steps":["trace[516192892] 'agreement among raft nodes before linearized reading' (duration: 409.51555ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:52:10.291451Z","caller":"traceutil/trace.go:171","msg":"trace[973732548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5837; }","duration":"412.604839ms","start":"2026-10-14T19:52:09.878814Z","end":"2026-10-14T19:52:10.291419Z","steps":["trace[973732548] 'agreement among raft nodes before linearized reading' (duration: 412.154529ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:52:10.291501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:52:09.881386Z","time spent":"410.101303ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-14T19:52:10.291545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:52:09.878784Z","time spent":"412.744782ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T19:52:10.291661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.116672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4nh6q\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-14T19:52:10.291743Z","caller":"traceutil/trace.go:171","msg":"trace[900481434] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4nh6q; range_end:; response_count:1; response_revision:5837; }","duration":"387.258756ms","start":"2026-10-14T19:52:09.904459Z","end":"2026-10-14T19:52:10.291718Z","steps":["trace[900481434] 'agreement among raft nodes before linearized reading' (duration: 386.448388ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:52:10.291796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:52:09.904425Z","time spent":"387.360798ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-4nh6q\" "} {"level":"info","ts":"2026-10-14T19:52:10.317994Z","caller":"traceutil/trace.go:171","msg":"trace[731253577] transaction","detail":"{read_only:false; response_revision:5838; number_of_response:1; }","duration":"182.115817ms","start":"2026-10-14T19:52:10.135839Z","end":"2026-10-14T19:52:10.317955Z","steps":["trace[731253577] 'process raft request' (duration: 181.947623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:52:10.318292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.939623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:52:10.318413Z","caller":"traceutil/trace.go:171","msg":"trace[844265471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5839; }","duration":"353.111186ms","start":"2026-10-14T19:52:09.965276Z","end":"2026-10-14T19:52:10.318387Z","steps":["trace[844265471] 'agreement among raft nodes before linearized reading' (duration: 352.804869ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:52:10.318461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:52:09.965252Z","time spent":"353.197639ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T19:52:10.318572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.768737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-results-cache-0\" ","response":"range_response_count:1 size:4473"} {"level":"warn","ts":"2026-10-14T19:52:10.318624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.409376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-10-14T19:52:10.318756Z","caller":"traceutil/trace.go:171","msg":"trace[1958100495] range","detail":"{range_begin:/registry/pods/monitoring/loki-results-cache-0; range_end:; response_count:1; response_revision:5839; }","duration":"297.976452ms","start":"2026-10-14T19:52:10.020693Z","end":"2026-10-14T19:52:10.31867Z","steps":["trace[1958100495] 'agreement among raft nodes before linearized reading' (duration: 297.423889ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:52:10.318776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.793955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-14T19:52:10.318858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.434578ms","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-10-14T19:52:10.31887Z","caller":"traceutil/trace.go:171","msg":"trace[462806557] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5839; }","duration":"287.603971ms","start":"2026-10-14T19:52:10.031097Z","end":"2026-10-14T19:52:10.318701Z","steps":["trace[462806557] 'agreement among raft nodes before linearized reading' (duration: 287.008908ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:52:10.318958Z","caller":"traceutil/trace.go:171","msg":"trace[1443768215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5839; }","duration":"341.891927ms","start":"2026-10-14T19:52:09.976938Z","end":"2026-10-14T19:52:10.31883Z","steps":["trace[1443768215] 'agreement among raft nodes before linearized reading' (duration: 341.12702ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:52:10.318914Z","caller":"traceutil/trace.go:171","msg":"trace[460575496] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5839; }","duration":"337.51263ms","start":"2026-10-14T19:52:09.98139Z","end":"2026-10-14T19:52:10.318903Z","steps":["trace[460575496] 'agreement among raft nodes before linearized reading' (duration: 336.674401ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:52:10.319031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:52:09.976902Z","time spent":"342.110852ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-14T19:52:10.319065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:52:09.981378Z","time spent":"337.672453ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-14T19:57:01.598111Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5654} {"level":"info","ts":"2026-10-14T19:57:01.712695Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5654,"took":"110.491742ms","hash":1925546700,"current-db-size-bytes":54329344,"current-db-size":"54 MB","current-db-size-in-use-bytes":27664384,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T19:57:01.712803Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1925546700,"revision":5654,"compact-revision":2962} {"level":"info","ts":"2026-10-14T19:58:14.865067Z","caller":"traceutil/trace.go:171","msg":"trace[1275604462] transaction","detail":"{read_only:false; response_revision:8473; number_of_response:1; }","duration":"107.812782ms","start":"2026-10-14T19:58:14.757202Z","end":"2026-10-14T19:58:14.865015Z","steps":["trace[1275604462] 'process raft request' (duration: 107.550807ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:58:19.37403Z","caller":"traceutil/trace.go:171","msg":"trace[1553701938] transaction","detail":"{read_only:false; response_revision:8526; number_of_response:1; }","duration":"108.441497ms","start":"2026-10-14T19:58:19.265548Z","end":"2026-10-14T19:58:19.373989Z","steps":["trace[1553701938] 'process raft request' (duration: 108.222202ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:58:34.909568Z","caller":"traceutil/trace.go:171","msg":"trace[586094154] transaction","detail":"{read_only:false; response_revision:8747; number_of_response:1; }","duration":"139.956636ms","start":"2026-10-14T19:58:34.76957Z","end":"2026-10-14T19:58:34.909526Z","steps":["trace[586094154] 'process raft request' (duration: 99.60523ms)","trace[586094154] 'compare' (duration: 40.201002ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:58:34.91178Z","caller":"traceutil/trace.go:171","msg":"trace[695549148] transaction","detail":"{read_only:false; response_revision:8748; number_of_response:1; }","duration":"140.263212ms","start":"2026-10-14T19:58:34.771475Z","end":"2026-10-14T19:58:34.911738Z","steps":["trace[695549148] 'process raft request' (duration: 140.031637ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:58:34.911991Z","caller":"traceutil/trace.go:171","msg":"trace[266425204] linearizableReadLoop","detail":"{readStateIndex:9065; appliedIndex:9063; }","duration":"138.783639ms","start":"2026-10-14T19:58:34.77319Z","end":"2026-10-14T19:58:34.911974Z","steps":["trace[266425204] 'read index received' (duration: 95.928148ms)","trace[266425204] 'applied index is now lower than readState.Index' (duration: 42.854091ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:58:34.9123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.073296ms","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-10-14T19:58:34.912593Z","caller":"traceutil/trace.go:171","msg":"trace[1500613918] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8749; }","duration":"139.370113ms","start":"2026-10-14T19:58:34.773166Z","end":"2026-10-14T19:58:34.912536Z","steps":["trace[1500613918] 'agreement among raft nodes before linearized reading' (duration: 138.926733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:58:37.242543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.61198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:58:37.242657Z","caller":"traceutil/trace.go:171","msg":"trace[60170651] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:8756; }","duration":"537.807804ms","start":"2026-10-14T19:58:36.704823Z","end":"2026-10-14T19:58:37.242631Z","steps":["trace[60170651] 'count revisions from in-memory index tree' (duration: 537.448005ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:58:37.242717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:58:36.704791Z","time spent":"537.901795ms","remote":"127.0.0.1:46748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-14T19:58:37.243527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.126163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T19:58:37.243657Z","caller":"traceutil/trace.go:171","msg":"trace[4753308] linearizableReadLoop","detail":"{readStateIndex:9074; appliedIndex:9073; }","duration":"523.955216ms","start":"2026-10-14T19:58:36.719681Z","end":"2026-10-14T19:58:37.243637Z","steps":["trace[4753308] 'read index received' (duration: 281.2218ms)","trace[4753308] 'applied index is now lower than readState.Index' (duration: 242.731726ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:58:37.243759Z","caller":"traceutil/trace.go:171","msg":"trace[490474014] transaction","detail":"{read_only:false; response_revision:8757; number_of_response:1; }","duration":"530.002411ms","start":"2026-10-14T19:58:36.713744Z","end":"2026-10-14T19:58:37.243746Z","steps":["trace[490474014] 'process raft request' (duration: 287.215114ms)","trace[490474014] 'compare' (duration: 241.964449ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:58:37.24382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:58:36.713701Z","time spent":"530.087673ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T19:58:37.243972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.717849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:58:37.244075Z","caller":"traceutil/trace.go:171","msg":"trace[1236318099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8757; }","duration":"481.878353ms","start":"2026-10-14T19:58:36.762171Z","end":"2026-10-14T19:58:37.244049Z","steps":["trace[1236318099] 'agreement among raft nodes before linearized reading' (duration: 481.714179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:58:37.244056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.815601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4nh6q\" ","response":"range_response_count:1 size:6526"} {"level":"warn","ts":"2026-10-14T19:58:37.244125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:58:36.762145Z","time spent":"481.970445ms","remote":"127.0.0.1:57680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T19:58:37.244104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.685286ms","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-10-14T19:58:37.244156Z","caller":"traceutil/trace.go:171","msg":"trace[856071450] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4nh6q; range_end:; response_count:1; response_revision:8757; }","duration":"152.990864ms","start":"2026-10-14T19:58:37.091142Z","end":"2026-10-14T19:58:37.244133Z","steps":["trace[856071450] 'agreement among raft nodes before linearized reading' (duration: 152.761829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:58:37.244128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.024234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-14T19:58:37.244177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.067539ms","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-10-14T19:58:37.244204Z","caller":"traceutil/trace.go:171","msg":"trace[1480883080] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8757; }","duration":"320.83437ms","start":"2026-10-14T19:58:36.923346Z","end":"2026-10-14T19:58:37.244181Z","steps":["trace[1480883080] 'agreement among raft nodes before linearized reading' (duration: 320.580214ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:58:37.244252Z","caller":"traceutil/trace.go:171","msg":"trace[1295431711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8757; }","duration":"330.178987ms","start":"2026-10-14T19:58:36.914024Z","end":"2026-10-14T19:58:37.244203Z","steps":["trace[1295431711] 'agreement among raft nodes before linearized reading' (duration: 329.888541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:58:37.244255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.597433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:58:37.24429Z","caller":"traceutil/trace.go:171","msg":"trace[1214697918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8757; }","duration":"328.216323ms","start":"2026-10-14T19:58:36.916049Z","end":"2026-10-14T19:58:37.244265Z","steps":["trace[1214697918] 'agreement among raft nodes before linearized reading' (duration: 327.839755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:58:37.2443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:58:36.914Z","time spent":"330.291509ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-14T19:58:37.244307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:58:36.923322Z","time spent":"320.965612ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T19:58:37.24411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.340856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-14T19:58:37.24432Z","caller":"traceutil/trace.go:171","msg":"trace[590136582] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:8757; }","duration":"443.727286ms","start":"2026-10-14T19:58:36.800572Z","end":"2026-10-14T19:58:37.2443Z","steps":["trace[590136582] 'agreement among raft nodes before linearized reading' (duration: 443.610154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:58:37.244337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:58:36.916045Z","time spent":"328.281835ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-14T19:58:37.244368Z","caller":"traceutil/trace.go:171","msg":"trace[1007184494] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8757; }","duration":"442.664383ms","start":"2026-10-14T19:58:36.801686Z","end":"2026-10-14T19:58:37.244351Z","steps":["trace[1007184494] 'agreement among raft nodes before linearized reading' (duration: 442.209623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:58:37.244372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:58:36.800545Z","time spent":"443.813409ms","remote":"127.0.0.1:33410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-10-14T19:58:37.244418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:58:36.801665Z","time spent":"442.736974ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-14T19:58:37.244426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.698573ms","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-10-14T19:58:37.244547Z","caller":"traceutil/trace.go:171","msg":"trace[674640001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8757; }","duration":"524.860017ms","start":"2026-10-14T19:58:36.719659Z","end":"2026-10-14T19:58:37.244519Z","steps":["trace[674640001] 'agreement among raft nodes before linearized reading' (duration: 524.449628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:58:37.244607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:58:36.719649Z","time spent":"524.945659ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-14T19:58:48.545407Z","caller":"traceutil/trace.go:171","msg":"trace[1140023803] transaction","detail":"{read_only:false; response_revision:8816; number_of_response:1; }","duration":"236.391286ms","start":"2026-10-14T19:58:48.308988Z","end":"2026-10-14T19:58:48.545379Z","steps":["trace[1140023803] 'process raft request' (duration: 236.095999ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:58:48.636601Z","caller":"traceutil/trace.go:171","msg":"trace[1083552537] linearizableReadLoop","detail":"{readStateIndex:9136; appliedIndex:9134; }","duration":"254.780523ms","start":"2026-10-14T19:58:48.381786Z","end":"2026-10-14T19:58:48.636567Z","steps":["trace[1083552537] 'read index received' (duration: 163.472987ms)","trace[1083552537] 'applied index is now lower than readState.Index' (duration: 91.306296ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:58:48.636759Z","caller":"traceutil/trace.go:171","msg":"trace[264925818] transaction","detail":"{read_only:false; response_revision:8817; number_of_response:1; }","duration":"270.635605ms","start":"2026-10-14T19:58:48.366105Z","end":"2026-10-14T19:58:48.63674Z","steps":["trace[264925818] 'process raft request' (duration: 203.977046ms)","trace[264925818] 'compare' (duration: 66.325861ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:58:48.637141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.756881ms","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-10-14T19:58:48.637247Z","caller":"traceutil/trace.go:171","msg":"trace[542534696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8817; }","duration":"221.905754ms","start":"2026-10-14T19:58:48.4153Z","end":"2026-10-14T19:58:48.637205Z","steps":["trace[542534696] 'agreement among raft nodes before linearized reading' (duration: 221.651028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:58:48.637322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.494389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T19:58:48.63742Z","caller":"traceutil/trace.go:171","msg":"trace[286569206] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8817; }","duration":"255.674023ms","start":"2026-10-14T19:58:48.381725Z","end":"2026-10-14T19:58:48.637399Z","steps":["trace[286569206] 'agreement among raft nodes before linearized reading' (duration: 255.434058ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:58:49.532692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.507012ms","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-10-14T19:58:49.53279Z","caller":"traceutil/trace.go:171","msg":"trace[121347269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8819; }","duration":"215.681196ms","start":"2026-10-14T19:58:49.317087Z","end":"2026-10-14T19:58:49.532768Z","steps":["trace[121347269] 'range keys from in-memory index tree' (duration: 215.253166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:58:49.533943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.109344ms","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-10-14T19:58:49.534Z","caller":"traceutil/trace.go:171","msg":"trace[1388594241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8819; }","duration":"179.198947ms","start":"2026-10-14T19:58:49.354786Z","end":"2026-10-14T19:58:49.533985Z","steps":["trace[1388594241] 'range keys from in-memory index tree' (duration: 178.995972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:58:49.534109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.478366ms","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-10-14T19:58:49.53424Z","caller":"traceutil/trace.go:171","msg":"trace[186644102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8819; }","duration":"190.667751ms","start":"2026-10-14T19:58:49.343527Z","end":"2026-10-14T19:58:49.534195Z","steps":["trace[186644102] 'range keys from in-memory index tree' (duration: 190.20131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:58:49.53451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.08215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4nh6q\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-14T19:58:49.534551Z","caller":"traceutil/trace.go:171","msg":"trace[1016795355] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4nh6q; range_end:; response_count:1; response_revision:8819; }","duration":"200.165252ms","start":"2026-10-14T19:58:49.334372Z","end":"2026-10-14T19:58:49.534537Z","steps":["trace[1016795355] 'range keys from in-memory index tree' (duration: 199.968177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:58:49.534776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.188535ms","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-10-14T19:58:49.534811Z","caller":"traceutil/trace.go:171","msg":"trace[1845105649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8819; }","duration":"192.275237ms","start":"2026-10-14T19:58:49.342526Z","end":"2026-10-14T19:58:49.534801Z","steps":["trace[1845105649] 'range keys from in-memory index tree' (duration: 192.026751ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:58:49.534956Z","caller":"traceutil/trace.go:171","msg":"trace[1089244822] transaction","detail":"{read_only:false; response_revision:8820; number_of_response:1; }","duration":"152.940343ms","start":"2026-10-14T19:58:49.381971Z","end":"2026-10-14T19:58:49.534912Z","steps":["trace[1089244822] 'process raft request' (duration: 144.970417ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:58:49.573598Z","caller":"traceutil/trace.go:171","msg":"trace[1687546364] transaction","detail":"{read_only:false; response_revision:8821; number_of_response:1; }","duration":"183.083112ms","start":"2026-10-14T19:58:49.390495Z","end":"2026-10-14T19:58:49.573578Z","steps":["trace[1687546364] 'process raft request' (duration: 182.951099ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:58:50.749044Z","caller":"traceutil/trace.go:171","msg":"trace[605264881] transaction","detail":"{read_only:false; response_revision:8827; number_of_response:1; }","duration":"105.90061ms","start":"2026-10-14T19:58:50.643032Z","end":"2026-10-14T19:58:50.748932Z","steps":["trace[605264881] 'process raft request' (duration: 105.604633ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:59:20.10206Z","caller":"traceutil/trace.go:171","msg":"trace[767188996] transaction","detail":"{read_only:false; response_revision:9035; number_of_response:1; }","duration":"401.917359ms","start":"2026-10-14T19:59:19.700104Z","end":"2026-10-14T19:59:20.102021Z","steps":["trace[767188996] 'process raft request' (duration: 401.713004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:59:20.102288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:59:19.700077Z","time spent":"402.087962ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T19:59:20.40977Z","caller":"traceutil/trace.go:171","msg":"trace[668505087] transaction","detail":"{read_only:false; response_revision:9037; number_of_response:1; }","duration":"706.349014ms","start":"2026-10-14T19:59:19.703388Z","end":"2026-10-14T19:59:20.409737Z","steps":["trace[668505087] 'process raft request' (duration: 706.18303ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:59:20.409762Z","caller":"traceutil/trace.go:171","msg":"trace[1389005222] transaction","detail":"{read_only:false; response_revision:9036; number_of_response:1; }","duration":"706.434785ms","start":"2026-10-14T19:59:19.703261Z","end":"2026-10-14T19:59:20.409696Z","steps":["trace[1389005222] 'process raft request' (duration: 701.882974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:59:20.40996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:59:19.703341Z","time spent":"706.528958ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T19:59:20.410038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:59:19.703223Z","time spent":"706.676231ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T19:59:20.412307Z","caller":"traceutil/trace.go:171","msg":"trace[1508643372] linearizableReadLoop","detail":"{readStateIndex:9365; appliedIndex:9362; }","duration":"652.954908ms","start":"2026-10-14T19:59:19.759318Z","end":"2026-10-14T19:59:20.412273Z","steps":["trace[1508643372] 'read index received' (duration: 342.463269ms)","trace[1508643372] 'applied index is now lower than readState.Index' (duration: 310.490299ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:59:20.412637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.289826ms","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-10-14T19:59:20.412694Z","caller":"traceutil/trace.go:171","msg":"trace[986552526] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9037; }","duration":"653.406368ms","start":"2026-10-14T19:59:19.759271Z","end":"2026-10-14T19:59:20.412678Z","steps":["trace[986552526] 'agreement among raft nodes before linearized reading' (duration: 653.173583ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:59:20.41273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:59:19.759256Z","time spent":"653.46404ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T19:59:20.413134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.489494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:59:20.413176Z","caller":"traceutil/trace.go:171","msg":"trace[2138448681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9037; }","duration":"650.550855ms","start":"2026-10-14T19:59:19.762615Z","end":"2026-10-14T19:59:20.413166Z","steps":["trace[2138448681] 'agreement among raft nodes before linearized reading' (duration: 650.489864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:59:20.413221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:59:19.762605Z","time spent":"650.610046ms","remote":"127.0.0.1:57680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T19:59:20.413458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.570756ms","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-10-14T19:59:20.413493Z","caller":"traceutil/trace.go:171","msg":"trace[186540455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9037; }","duration":"623.623408ms","start":"2026-10-14T19:59:19.789861Z","end":"2026-10-14T19:59:20.413484Z","steps":["trace[186540455] 'agreement among raft nodes before linearized reading' (duration: 623.531676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:59:20.413517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:59:19.789853Z","time spent":"623.658229ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T19:59:20.413656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.81681ms","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-10-14T19:59:20.413773Z","caller":"traceutil/trace.go:171","msg":"trace[1224698794] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9037; }","duration":"622.970823ms","start":"2026-10-14T19:59:19.790773Z","end":"2026-10-14T19:59:20.413744Z","steps":["trace[1224698794] 'agreement among raft nodes before linearized reading' (duration: 622.618845ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:59:20.413756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.396325ms","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-10-14T19:59:20.413828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:59:19.790759Z","time spent":"623.059236ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-14T19:59:20.41386Z","caller":"traceutil/trace.go:171","msg":"trace[1449167913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9037; }","duration":"624.581689ms","start":"2026-10-14T19:59:19.789256Z","end":"2026-10-14T19:59:20.413838Z","steps":["trace[1449167913] 'agreement among raft nodes before linearized reading' (duration: 624.247102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:59:20.413895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.570112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-14T19:59:20.413915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:59:19.789228Z","time spent":"624.676712ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-14T19:59:20.413933Z","caller":"traceutil/trace.go:171","msg":"trace[1533153499] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9037; }","duration":"479.658303ms","start":"2026-10-14T19:59:19.934263Z","end":"2026-10-14T19:59:20.413921Z","steps":["trace[1533153499] 'agreement among raft nodes before linearized reading' (duration: 479.587481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:59:20.413964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:59:19.934244Z","time spent":"479.709705ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-14T19:59:20.414069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.298344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4nh6q\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-14T19:59:20.414109Z","caller":"traceutil/trace.go:171","msg":"trace[1037758824] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4nh6q; range_end:; response_count:1; response_revision:9037; }","duration":"606.337975ms","start":"2026-10-14T19:59:19.807761Z","end":"2026-10-14T19:59:20.414099Z","steps":["trace[1037758824] 'agreement among raft nodes before linearized reading' (duration: 606.255463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:59:20.414135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:59:19.807706Z","time spent":"606.423227ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-4nh6q\" "} {"level":"warn","ts":"2026-10-14T19:59:20.414141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.074801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:59:20.414226Z","caller":"traceutil/trace.go:171","msg":"trace[2127310869] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:9037; }","duration":"219.216394ms","start":"2026-10-14T19:59:20.194996Z","end":"2026-10-14T19:59:20.414212Z","steps":["trace[2127310869] 'agreement among raft nodes before linearized reading' (duration: 219.082601ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:59:23.242386Z","caller":"traceutil/trace.go:171","msg":"trace[482886216] linearizableReadLoop","detail":"{readStateIndex:9383; appliedIndex:9382; }","duration":"264.657792ms","start":"2026-10-14T19:59:22.9777Z","end":"2026-10-14T19:59:23.242358Z","steps":["trace[482886216] 'read index received' (duration: 264.416197ms)","trace[482886216] 'applied index is now lower than readState.Index' (duration: 240.465µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:59:23.242462Z","caller":"traceutil/trace.go:171","msg":"trace[319355727] transaction","detail":"{read_only:false; response_revision:9054; number_of_response:1; }","duration":"281.357653ms","start":"2026-10-14T19:59:22.961072Z","end":"2026-10-14T19:59:23.242429Z","steps":["trace[319355727] 'process raft request' (duration: 281.107517ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:59:23.242614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.900018ms","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-10-14T19:59:23.242653Z","caller":"traceutil/trace.go:171","msg":"trace[2127398947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9054; }","duration":"264.99988ms","start":"2026-10-14T19:59:22.977642Z","end":"2026-10-14T19:59:23.242641Z","steps":["trace[2127398947] 'agreement among raft nodes before linearized reading' (duration: 264.822656ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:59:31.591653Z","caller":"traceutil/trace.go:171","msg":"trace[25261175] linearizableReadLoop","detail":"{readStateIndex:9427; appliedIndex:9426; }","duration":"160.240736ms","start":"2026-10-14T19:59:31.431375Z","end":"2026-10-14T19:59:31.591616Z","steps":["trace[25261175] 'read index received' (duration: 147.689107ms)","trace[25261175] 'applied index is now lower than readState.Index' (duration: 12.549969ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:59:31.591752Z","caller":"traceutil/trace.go:171","msg":"trace[97147110] transaction","detail":"{read_only:false; response_revision:9096; number_of_response:1; }","duration":"198.960115ms","start":"2026-10-14T19:59:31.392749Z","end":"2026-10-14T19:59:31.591709Z","steps":["trace[97147110] 'process raft request' (duration: 186.247143ms)","trace[97147110] 'compare' (duration: 12.269912ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:59:31.591891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.467841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:59:31.592002Z","caller":"traceutil/trace.go:171","msg":"trace[1883205850] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:9096; }","duration":"160.632574ms","start":"2026-10-14T19:59:31.431343Z","end":"2026-10-14T19:59:31.591976Z","steps":["trace[1883205850] 'agreement among raft nodes before linearized reading' (duration: 160.411629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:59:31.592174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.856265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T19:59:31.592296Z","caller":"traceutil/trace.go:171","msg":"trace[434253209] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9096; }","duration":"118.03723ms","start":"2026-10-14T19:59:31.474233Z","end":"2026-10-14T19:59:31.59227Z","steps":["trace[434253209] 'agreement among raft nodes before linearized reading' (duration: 117.836295ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:59:32.409885Z","caller":"traceutil/trace.go:171","msg":"trace[1426991884] transaction","detail":"{read_only:false; response_revision:9099; number_of_response:1; }","duration":"161.003233ms","start":"2026-10-14T19:59:32.248844Z","end":"2026-10-14T19:59:32.409847Z","steps":["trace[1426991884] 'process raft request' (duration: 148.557067ms)","trace[1426991884] 'compare' (duration: 12.306503ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:59:34.192625Z","caller":"traceutil/trace.go:171","msg":"trace[707577888] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9118; }","duration":"107.323722ms","start":"2026-10-14T19:59:34.085271Z","end":"2026-10-14T19:59:34.192595Z","steps":["trace[707577888] 'compare' (duration: 85.16992ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:59:34.192777Z","caller":"traceutil/trace.go:171","msg":"trace[1043536097] transaction","detail":"{read_only:false; response_revision:9119; number_of_response:1; }","duration":"105.838178ms","start":"2026-10-14T19:59:34.086907Z","end":"2026-10-14T19:59:34.192745Z","steps":["trace[1043536097] 'process raft request' (duration: 105.298426ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:59:38.131582Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002525.wal"} {"level":"info","ts":"2026-10-14T20:00:33.653381Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9ac9f866d7f9ac7b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T20:00:33.663755Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-14T20:00:33.663987Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-14T20:01:03.809496Z","caller":"traceutil/trace.go:171","msg":"trace[1712295081] transaction","detail":"{read_only:false; response_revision:9970; number_of_response:1; }","duration":"111.459854ms","start":"2026-10-14T20:01:03.698007Z","end":"2026-10-14T20:01:03.809467Z","steps":["trace[1712295081] 'process raft request' (duration: 111.27934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:01:03.962805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.077426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T20:01:03.962963Z","caller":"traceutil/trace.go:171","msg":"trace[1312937387] linearizableReadLoop","detail":"{readStateIndex:10325; appliedIndex:10323; }","duration":"198.422654ms","start":"2026-10-14T20:01:03.764518Z","end":"2026-10-14T20:01:03.962941Z","steps":["trace[1312937387] 'read index received' (duration: 44.753673ms)","trace[1312937387] 'applied index is now lower than readState.Index' (duration: 153.666951ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:01:03.963185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.666899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T20:01:03.963249Z","caller":"traceutil/trace.go:171","msg":"trace[2113468574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9971; }","duration":"198.739441ms","start":"2026-10-14T20:01:03.764476Z","end":"2026-10-14T20:01:03.963215Z","steps":["trace[2113468574] 'agreement among raft nodes before linearized reading' (duration: 198.529046ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:01:03.963355Z","caller":"traceutil/trace.go:171","msg":"trace[936351837] transaction","detail":"{read_only:false; response_revision:9971; number_of_response:1; }","duration":"232.921449ms","start":"2026-10-14T20:01:03.730354Z","end":"2026-10-14T20:01:03.963275Z","steps":["trace[936351837] 'process raft request' (duration: 107.217319ms)","trace[936351837] 'compare' (duration: 124.78404ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:02:01.605295Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8001} {"level":"info","ts":"2026-10-14T20:02:01.693219Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8001,"took":"85.290573ms","hash":1037177197,"current-db-size-bytes":54329344,"current-db-size":"54 MB","current-db-size-in-use-bytes":25329664,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-14T20:02:01.693332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1037177197,"revision":8001,"compact-revision":5654} {"level":"info","ts":"2026-10-14T20:06:22.453371Z","caller":"traceutil/trace.go:171","msg":"trace[1698514019] transaction","detail":"{read_only:false; response_revision:12290; number_of_response:1; }","duration":"299.67912ms","start":"2026-10-14T20:06:22.153652Z","end":"2026-10-14T20:06:22.453331Z","steps":["trace[1698514019] 'process raft request' (duration: 299.442935ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:06:22.471596Z","caller":"traceutil/trace.go:171","msg":"trace[1282048042] transaction","detail":"{read_only:false; response_revision:12291; number_of_response:1; }","duration":"277.822825ms","start":"2026-10-14T20:06:22.193748Z","end":"2026-10-14T20:06:22.47157Z","steps":["trace[1282048042] 'process raft request' (duration: 275.940103ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:06:45.637137Z","caller":"traceutil/trace.go:171","msg":"trace[1548778876] linearizableReadLoop","detail":"{readStateIndex:12853; appliedIndex:12852; }","duration":"183.129449ms","start":"2026-10-14T20:06:45.453953Z","end":"2026-10-14T20:06:45.637082Z","steps":["trace[1548778876] 'read index received' (duration: 182.784862ms)","trace[1548778876] 'applied index is now lower than readState.Index' (duration: 343.237µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:06:45.637188Z","caller":"traceutil/trace.go:171","msg":"trace[849027998] transaction","detail":"{read_only:false; response_revision:12420; number_of_response:1; }","duration":"187.531117ms","start":"2026-10-14T20:06:45.449614Z","end":"2026-10-14T20:06:45.637145Z","steps":["trace[849027998] 'process raft request' (duration: 187.185249ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:06:45.637427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.441996ms","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-10-14T20:06:45.63747Z","caller":"traceutil/trace.go:171","msg":"trace[928738752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12420; }","duration":"183.539188ms","start":"2026-10-14T20:06:45.453919Z","end":"2026-10-14T20:06:45.637458Z","steps":["trace[928738752] 'agreement among raft nodes before linearized reading' (duration: 183.334223ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:06:45.869526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.246321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T20:06:45.869668Z","caller":"traceutil/trace.go:171","msg":"trace[101365132] linearizableReadLoop","detail":"{readStateIndex:12854; appliedIndex:12853; }","duration":"105.137796ms","start":"2026-10-14T20:06:45.764509Z","end":"2026-10-14T20:06:45.869647Z","steps":["trace[101365132] 'read index received' (duration: 71.742µs)","trace[101365132] 'applied index is now lower than readState.Index' (duration: 105.064184ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:06:45.869746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.247508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T20:06:45.869735Z","caller":"traceutil/trace.go:171","msg":"trace[197003590] transaction","detail":"{read_only:false; response_revision:12421; number_of_response:1; }","duration":"227.066435ms","start":"2026-10-14T20:06:45.642622Z","end":"2026-10-14T20:06:45.869688Z","steps":["trace[197003590] 'process raft request' (duration: 84.551919ms)","trace[197003590] 'compare' (duration: 142.034476ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:06:45.869784Z","caller":"traceutil/trace.go:171","msg":"trace[200199202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12421; }","duration":"105.34241ms","start":"2026-10-14T20:06:45.764429Z","end":"2026-10-14T20:06:45.869772Z","steps":["trace[200199202] 'agreement among raft nodes before linearized reading' (duration: 105.273019ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:07:01.611996Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10388} {"level":"info","ts":"2026-10-14T20:07:01.701547Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10388,"took":"87.548975ms","hash":335354359,"current-db-size-bytes":54329344,"current-db-size":"54 MB","current-db-size-in-use-bytes":25776128,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-14T20:07:01.701655Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":335354359,"revision":10388,"compact-revision":8001} {"level":"info","ts":"2026-10-14T20:12:01.618512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12548} {"level":"info","ts":"2026-10-14T20:12:01.699271Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12548,"took":"78.38593ms","hash":2554173323,"current-db-size-bytes":54329344,"current-db-size":"54 MB","current-db-size-in-use-bytes":27668480,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T20:12:01.699333Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2554173323,"revision":12548,"compact-revision":10388} {"level":"info","ts":"2026-10-14T20:12:43.668058Z","caller":"traceutil/trace.go:171","msg":"trace[1472193984] transaction","detail":"{read_only:false; response_revision:14847; number_of_response:1; }","duration":"163.580752ms","start":"2026-10-14T20:12:43.504455Z","end":"2026-10-14T20:12:43.668036Z","steps":["trace[1472193984] 'process raft request' (duration: 163.414898ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:12:43.676855Z","caller":"traceutil/trace.go:171","msg":"trace[1463153912] transaction","detail":"{read_only:false; response_revision:14848; number_of_response:1; }","duration":"167.331375ms","start":"2026-10-14T20:12:43.509494Z","end":"2026-10-14T20:12:43.676826Z","steps":["trace[1463153912] 'process raft request' (duration: 167.186362ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:13:04.141918Z","caller":"traceutil/trace.go:171","msg":"trace[2119717960] transaction","detail":"{read_only:false; response_revision:14956; number_of_response:1; }","duration":"177.282667ms","start":"2026-10-14T20:13:03.964592Z","end":"2026-10-14T20:13:04.141874Z","steps":["trace[2119717960] 'process raft request' (duration: 177.120583ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:13:04.297163Z","caller":"traceutil/trace.go:171","msg":"trace[407335779] transaction","detail":"{read_only:false; response_revision:14957; number_of_response:1; }","duration":"251.406702ms","start":"2026-10-14T20:13:04.045731Z","end":"2026-10-14T20:13:04.297138Z","steps":["trace[407335779] 'process raft request' (duration: 251.142586ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:13:04.297409Z","caller":"traceutil/trace.go:171","msg":"trace[1897432262] linearizableReadLoop","detail":"{readStateIndex:15480; appliedIndex:15478; }","duration":"139.658101ms","start":"2026-10-14T20:13:04.15771Z","end":"2026-10-14T20:13:04.297368Z","steps":["trace[1897432262] 'read index received' (duration: 139.102409ms)","trace[1897432262] 'applied index is now lower than readState.Index' (duration: 553.442µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:13:04.297452Z","caller":"traceutil/trace.go:171","msg":"trace[9832792] transaction","detail":"{read_only:false; response_revision:14958; number_of_response:1; }","duration":"232.717657ms","start":"2026-10-14T20:13:04.064711Z","end":"2026-10-14T20:13:04.297429Z","steps":["trace[9832792] 'process raft request' (duration: 232.38367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:13:04.297658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.934077ms","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-10-14T20:13:04.2977Z","caller":"traceutil/trace.go:171","msg":"trace[2099701776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14958; }","duration":"140.036769ms","start":"2026-10-14T20:13:04.157651Z","end":"2026-10-14T20:13:04.297688Z","steps":["trace[2099701776] 'agreement among raft nodes before linearized reading' (duration: 139.842985ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:13:21.062296Z","caller":"traceutil/trace.go:171","msg":"trace[1883298570] transaction","detail":"{read_only:false; response_revision:15062; number_of_response:1; }","duration":"156.976715ms","start":"2026-10-14T20:13:20.905293Z","end":"2026-10-14T20:13:21.06227Z","steps":["trace[1883298570] 'process raft request' (duration: 156.77011ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:13:21.658117Z","caller":"traceutil/trace.go:171","msg":"trace[1148373061] transaction","detail":"{read_only:false; response_revision:15065; number_of_response:1; }","duration":"111.076866ms","start":"2026-10-14T20:13:21.54701Z","end":"2026-10-14T20:13:21.658087Z","steps":["trace[1148373061] 'process raft request' (duration: 110.869352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:13:22.092501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.289439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T20:13:22.092599Z","caller":"traceutil/trace.go:171","msg":"trace[431630388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15065; }","duration":"328.449742ms","start":"2026-10-14T20:13:21.764126Z","end":"2026-10-14T20:13:22.092576Z","steps":["trace[431630388] 'range keys from in-memory index tree' (duration: 328.157056ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:13:22.092643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:13:21.764093Z","time spent":"328.541644ms","remote":"127.0.0.1:57680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T20:13:22.092628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.719724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4nh6q\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-14T20:13:22.092699Z","caller":"traceutil/trace.go:171","msg":"trace[1973887639] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4nh6q; range_end:; response_count:1; response_revision:15065; }","duration":"321.847116ms","start":"2026-10-14T20:13:21.770834Z","end":"2026-10-14T20:13:22.092681Z","steps":["trace[1973887639] 'range keys from in-memory index tree' (duration: 321.56633ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:13:22.092743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:13:21.770801Z","time spent":"321.934018ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-4nh6q\" "} {"level":"warn","ts":"2026-10-14T20:13:22.093203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.321051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T20:13:22.094231Z","caller":"traceutil/trace.go:171","msg":"trace[312859402] linearizableReadLoop","detail":"{readStateIndex:15593; appliedIndex:15592; }","duration":"232.482591ms","start":"2026-10-14T20:13:21.86173Z","end":"2026-10-14T20:13:22.094213Z","steps":["trace[312859402] 'read index received' (duration: 38.973635ms)","trace[312859402] 'applied index is now lower than readState.Index' (duration: 193.507776ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:13:22.094303Z","caller":"traceutil/trace.go:171","msg":"trace[1051755777] transaction","detail":"{read_only:false; response_revision:15066; number_of_response:1; }","duration":"277.137384ms","start":"2026-10-14T20:13:21.81714Z","end":"2026-10-14T20:13:22.094277Z","steps":["trace[1051755777] 'process raft request' (duration: 83.649168ms)","trace[1051755777] 'compare' (duration: 192.026413ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:13:22.09477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.999814ms","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-10-14T20:13:22.095102Z","caller":"traceutil/trace.go:171","msg":"trace[510004820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15066; }","duration":"233.204748ms","start":"2026-10-14T20:13:21.861667Z","end":"2026-10-14T20:13:22.094872Z","steps":["trace[510004820] 'agreement among raft nodes before linearized reading' (duration: 232.666416ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:13:45.976088Z","caller":"traceutil/trace.go:171","msg":"trace[1800639540] linearizableReadLoop","detail":"{readStateIndex:15925; appliedIndex:15924; }","duration":"251.522004ms","start":"2026-10-14T20:13:45.724538Z","end":"2026-10-14T20:13:45.97606Z","steps":["trace[1800639540] 'read index received' (duration: 251.270479ms)","trace[1800639540] 'applied index is now lower than readState.Index' (duration: 250.345µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:13:45.976161Z","caller":"traceutil/trace.go:171","msg":"trace[1543876996] transaction","detail":"{read_only:false; response_revision:15383; number_of_response:1; }","duration":"311.383534ms","start":"2026-10-14T20:13:45.664756Z","end":"2026-10-14T20:13:45.976139Z","steps":["trace[1543876996] 'process raft request' (duration: 311.117078ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:13:45.976325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.933ms","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":"warn","ts":"2026-10-14T20:13:45.976347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:13:45.664714Z","time spent":"311.523737ms","remote":"127.0.0.1:57872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19597,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T20:13:45.976366Z","caller":"traceutil/trace.go:171","msg":"trace[1356883654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15383; }","duration":"200.020322ms","start":"2026-10-14T20:13:45.776333Z","end":"2026-10-14T20:13:45.976354Z","steps":["trace[1356883654] 'agreement among raft nodes before linearized reading' (duration: 199.871688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:13:45.976393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.800611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-l5zp7\" ","response":"range_response_count:1 size:20655"} {"level":"warn","ts":"2026-10-14T20:13:45.976478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.600489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-296690c4-13c2-44e9-969a-83ba54fced5f\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-10-14T20:13:45.97652Z","caller":"traceutil/trace.go:171","msg":"trace[821822908] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-l5zp7; range_end:; response_count:1; response_revision:15383; }","duration":"252.016676ms","start":"2026-10-14T20:13:45.724478Z","end":"2026-10-14T20:13:45.976494Z","steps":["trace[821822908] 'agreement among raft nodes before linearized reading' (duration: 251.672558ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:13:45.976551Z","caller":"traceutil/trace.go:171","msg":"trace[1668622041] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-296690c4-13c2-44e9-969a-83ba54fced5f; range_end:; response_count:1; response_revision:15383; }","duration":"184.719552ms","start":"2026-10-14T20:13:45.791813Z","end":"2026-10-14T20:13:45.976532Z","steps":["trace[1668622041] 'agreement among raft nodes before linearized reading' (duration: 184.492607ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:15:12.364529Z","caller":"traceutil/trace.go:171","msg":"trace[1557710446] linearizableReadLoop","detail":"{readStateIndex:16763; appliedIndex:16762; }","duration":"110.921668ms","start":"2026-10-14T20:15:12.253578Z","end":"2026-10-14T20:15:12.3645Z","steps":["trace[1557710446] 'read index received' (duration: 110.801606ms)","trace[1557710446] 'applied index is now lower than readState.Index' (duration: 118.432µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:15:12.364665Z","caller":"traceutil/trace.go:171","msg":"trace[1220996411] transaction","detail":"{read_only:false; response_revision:16197; number_of_response:1; }","duration":"128.697703ms","start":"2026-10-14T20:15:12.235874Z","end":"2026-10-14T20:15:12.364572Z","steps":["trace[1220996411] 'process raft request' (duration: 128.441267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:15:12.364681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.070801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T20:15:12.36487Z","caller":"traceutil/trace.go:171","msg":"trace[1029638899] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:16197; }","duration":"111.314477ms","start":"2026-10-14T20:15:12.253521Z","end":"2026-10-14T20:15:12.364835Z","steps":["trace[1029638899] 'agreement among raft nodes before linearized reading' (duration: 111.087802ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:15:13.78413Z","caller":"traceutil/trace.go:171","msg":"trace[1397743035] linearizableReadLoop","detail":"{readStateIndex:16771; appliedIndex:16770; }","duration":"207.056199ms","start":"2026-10-14T20:15:13.577039Z","end":"2026-10-14T20:15:13.784096Z","steps":["trace[1397743035] 'read index received' (duration: 206.765562ms)","trace[1397743035] 'applied index is now lower than readState.Index' (duration: 289.307µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:15:13.784258Z","caller":"traceutil/trace.go:171","msg":"trace[1648265584] transaction","detail":"{read_only:false; response_revision:16205; number_of_response:1; }","duration":"253.935828ms","start":"2026-10-14T20:15:13.530286Z","end":"2026-10-14T20:15:13.784222Z","steps":["trace[1648265584] 'process raft request' (duration: 253.536129ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:15:13.784481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.071828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"warn","ts":"2026-10-14T20:15:13.784438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.125996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T20:15:13.784524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.144108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-14T20:15:13.78455Z","caller":"traceutil/trace.go:171","msg":"trace[13738708] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16205; }","duration":"202.186751ms","start":"2026-10-14T20:15:13.582346Z","end":"2026-10-14T20:15:13.784533Z","steps":["trace[13738708] 'agreement among raft nodes before linearized reading' (duration: 201.944986ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:15:13.78459Z","caller":"traceutil/trace.go:171","msg":"trace[1580360788] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16205; }","duration":"201.22829ms","start":"2026-10-14T20:15:13.583342Z","end":"2026-10-14T20:15:13.784571Z","steps":["trace[1580360788] 'agreement among raft nodes before linearized reading' (duration: 201.042296ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:15:13.784589Z","caller":"traceutil/trace.go:171","msg":"trace[415065711] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16205; }","duration":"200.297989ms","start":"2026-10-14T20:15:13.58426Z","end":"2026-10-14T20:15:13.784558Z","steps":["trace[415065711] 'agreement among raft nodes before linearized reading' (duration: 200.086234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:15:13.784695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.497176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-14T20:15:13.78483Z","caller":"traceutil/trace.go:171","msg":"trace[1850033352] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16205; }","duration":"201.659359ms","start":"2026-10-14T20:15:13.583119Z","end":"2026-10-14T20:15:13.784779Z","steps":["trace[1850033352] 'agreement among raft nodes before linearized reading' (duration: 201.282591ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:15:13.785362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.318377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T20:15:13.785421Z","caller":"traceutil/trace.go:171","msg":"trace[1918763621] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16205; }","duration":"208.411039ms","start":"2026-10-14T20:15:13.576996Z","end":"2026-10-14T20:15:13.785407Z","steps":["trace[1918763621] 'agreement among raft nodes before linearized reading' (duration: 207.269304ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:17:01.625707Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14558} {"level":"info","ts":"2026-10-14T20:17:01.708302Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14558,"took":"79.82936ms","hash":330817839,"current-db-size-bytes":54329344,"current-db-size":"54 MB","current-db-size-in-use-bytes":30695424,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T20:17:01.708375Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":330817839,"revision":14558,"compact-revision":12548} {"level":"info","ts":"2026-10-14T20:18:16.12772Z","caller":"traceutil/trace.go:171","msg":"trace[1588375204] transaction","detail":"{read_only:false; response_revision:17674; number_of_response:1; }","duration":"135.479313ms","start":"2026-10-14T20:18:15.99221Z","end":"2026-10-14T20:18:16.127689Z","steps":["trace[1588375204] 'process raft request' (duration: 135.296379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:21:26.555589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.345985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T20:21:26.55571Z","caller":"traceutil/trace.go:171","msg":"trace[431233892] transaction","detail":"{read_only:false; response_revision:18917; number_of_response:1; }","duration":"234.369468ms","start":"2026-10-14T20:21:26.321321Z","end":"2026-10-14T20:21:26.555691Z","steps":["trace[431233892] 'process raft request' (duration: 89.825818ms)","trace[431233892] 'compare' (duration: 144.225333ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:21:27.11186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.272723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-14T20:21:27.111911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.082975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-14T20:21:27.112028Z","caller":"traceutil/trace.go:171","msg":"trace[2057260329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18918; }","duration":"114.246839ms","start":"2026-10-14T20:21:26.997756Z","end":"2026-10-14T20:21:27.112003Z","steps":["trace[2057260329] 'range keys from in-memory index tree' (duration: 113.886821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:21:27.112058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.987088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-14T20:21:27.112085Z","caller":"traceutil/trace.go:171","msg":"trace[1243433480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18918; }","duration":"121.050269ms","start":"2026-10-14T20:21:26.991027Z","end":"2026-10-14T20:21:27.112077Z","steps":["trace[1243433480] 'range keys from in-memory index tree' (duration: 120.788613ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:21:27.111967Z","caller":"traceutil/trace.go:171","msg":"trace[1242638991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18918; }","duration":"116.482328ms","start":"2026-10-14T20:21:26.995465Z","end":"2026-10-14T20:21:27.111947Z","steps":["trace[1242638991] 'range keys from in-memory index tree' (duration: 116.029527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:21:27.112203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.87825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T20:21:27.112549Z","caller":"traceutil/trace.go:171","msg":"trace[1471151721] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:18918; }","duration":"250.534865ms","start":"2026-10-14T20:21:26.861982Z","end":"2026-10-14T20:21:27.112517Z","steps":["trace[1471151721] 'count revisions from in-memory index tree' (duration: 249.776568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:21:27.627617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.779621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T20:21:27.627782Z","caller":"traceutil/trace.go:171","msg":"trace[2060289915] transaction","detail":"{read_only:false; response_revision:18920; number_of_response:1; }","duration":"510.415537ms","start":"2026-10-14T20:21:27.117348Z","end":"2026-10-14T20:21:27.627764Z","steps":["trace[2060289915] 'process raft request' (duration: 109.407632ms)","trace[2060289915] 'compare' (duration: 400.158976ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:21:27.627848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:21:27.117326Z","time spent":"510.492329ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T20:21:27.628155Z","caller":"traceutil/trace.go:171","msg":"trace[1808419046] linearizableReadLoop","detail":"{readStateIndex:19578; appliedIndex:19575; }","duration":"485.456195ms","start":"2026-10-14T20:21:27.142688Z","end":"2026-10-14T20:21:27.628144Z","steps":["trace[1808419046] 'read index received' (duration: 8.260733ms)","trace[1808419046] 'applied index is now lower than readState.Index' (duration: 477.194442ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:21:27.628131Z","caller":"traceutil/trace.go:171","msg":"trace[5397509] transaction","detail":"{read_only:false; response_revision:18921; number_of_response:1; }","duration":"510.732514ms","start":"2026-10-14T20:21:27.117348Z","end":"2026-10-14T20:21:27.628081Z","steps":["trace[5397509] 'process raft request' (duration: 510.363686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:21:27.628306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.632689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-79hv5\" ","response":"range_response_count:1 size:22129"} {"level":"info","ts":"2026-10-14T20:21:27.628279Z","caller":"traceutil/trace.go:171","msg":"trace[2091698945] transaction","detail":"{read_only:false; response_revision:18922; number_of_response:1; }","duration":"384.191824ms","start":"2026-10-14T20:21:27.244054Z","end":"2026-10-14T20:21:27.628246Z","steps":["trace[2091698945] 'process raft request' (duration: 384.063781ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:21:27.628344Z","caller":"traceutil/trace.go:171","msg":"trace[474550793] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-79hv5; range_end:; response_count:1; response_revision:18921; }","duration":"485.704921ms","start":"2026-10-14T20:21:27.142629Z","end":"2026-10-14T20:21:27.628334Z","steps":["trace[474550793] 'agreement among raft nodes before linearized reading' (duration: 485.558818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:21:27.628372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:21:27.142613Z","time spent":"485.752242ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22153,"request content":"key:\"/registry/pods/openstack/nova-compute-default-79hv5\" "} {"level":"warn","ts":"2026-10-14T20:21:27.628445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:21:27.244028Z","time spent":"384.326536ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T20:21:27.62848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:21:27.117318Z","time spent":"510.896888ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T20:21:27.93225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.880305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T20:21:27.932346Z","caller":"traceutil/trace.go:171","msg":"trace[2012755774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18922; }","duration":"168.06169ms","start":"2026-10-14T20:21:27.764263Z","end":"2026-10-14T20:21:27.932325Z","steps":["trace[2012755774] 'range keys from in-memory index tree' (duration: 167.512608ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:21:27.932385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.568472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T20:21:27.932497Z","caller":"traceutil/trace.go:171","msg":"trace[1438482661] linearizableReadLoop","detail":"{readStateIndex:19580; appliedIndex:19579; }","duration":"128.915243ms","start":"2026-10-14T20:21:27.80356Z","end":"2026-10-14T20:21:27.932475Z","steps":["trace[1438482661] 'read index received' (duration: 16.154918ms)","trace[1438482661] 'applied index is now lower than readState.Index' (duration: 112.758595ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:21:27.932531Z","caller":"traceutil/trace.go:171","msg":"trace[1845775679] transaction","detail":"{read_only:false; response_revision:18923; number_of_response:1; }","duration":"160.82108ms","start":"2026-10-14T20:21:27.771664Z","end":"2026-10-14T20:21:27.932485Z","steps":["trace[1845775679] 'process raft request' (duration: 48.006093ms)","trace[1845775679] 'compare' (duration: 112.307715ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:21:27.932681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.083677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T20:21:27.932766Z","caller":"traceutil/trace.go:171","msg":"trace[672017204] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:18923; }","duration":"129.23705ms","start":"2026-10-14T20:21:27.803506Z","end":"2026-10-14T20:21:27.932743Z","steps":["trace[672017204] 'agreement among raft nodes before linearized reading' (duration: 129.034046ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:22:01.634672Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16890} {"level":"info","ts":"2026-10-14T20:22:01.735717Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16890,"took":"97.867146ms","hash":4063142532,"current-db-size-bytes":54329344,"current-db-size":"54 MB","current-db-size-in-use-bytes":32378880,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T20:22:01.735785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4063142532,"revision":16890,"compact-revision":14558} {"level":"info","ts":"2026-10-14T20:22:04.85963Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9ac9f866d7f9ac7b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T20:22:04.871558Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-14T20:22:04.871771Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-14T20:27:01.641077Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19273} {"level":"info","ts":"2026-10-14T20:27:01.750986Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19273,"took":"105.72253ms","hash":3595580488,"current-db-size-bytes":54329344,"current-db-size":"54 MB","current-db-size-in-use-bytes":33611776,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-14T20:27:01.751056Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3595580488,"revision":19273,"compact-revision":16890} {"level":"warn","ts":"2026-10-14T20:27:09.811612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.006564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T20:27:09.811718Z","caller":"traceutil/trace.go:171","msg":"trace[1570021425] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:21587; }","duration":"236.143177ms","start":"2026-10-14T20:27:09.575545Z","end":"2026-10-14T20:27:09.811688Z","steps":["trace[1570021425] 'count revisions from in-memory index tree' (duration: 235.933262ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:27:09.812098Z","caller":"traceutil/trace.go:171","msg":"trace[51688342] transaction","detail":"{read_only:false; response_revision:21588; number_of_response:1; }","duration":"165.32775ms","start":"2026-10-14T20:27:09.646738Z","end":"2026-10-14T20:27:09.812065Z","steps":["trace[51688342] 'process raft request' (duration: 96.23391ms)","trace[51688342] 'compare' (duration: 68.712051ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:27:10.297712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.549518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T20:27:10.297936Z","caller":"traceutil/trace.go:171","msg":"trace[1535700268] linearizableReadLoop","detail":"{readStateIndex:22336; appliedIndex:22334; }","duration":"301.687488ms","start":"2026-10-14T20:27:09.996213Z","end":"2026-10-14T20:27:10.297901Z","steps":["trace[1535700268] 'read index received' (duration: 134.063998ms)","trace[1535700268] 'applied index is now lower than readState.Index' (duration: 167.62267ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:27:10.298Z","caller":"traceutil/trace.go:171","msg":"trace[1889077192] transaction","detail":"{read_only:false; response_revision:21590; number_of_response:1; }","duration":"371.695547ms","start":"2026-10-14T20:27:09.926295Z","end":"2026-10-14T20:27:10.297991Z","steps":["trace[1889077192] 'process raft request' (duration: 371.522013ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:27:10.298059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:27:09.926266Z","time spent":"371.762358ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T20:27:10.298009Z","caller":"traceutil/trace.go:171","msg":"trace[1076005449] transaction","detail":"{read_only:false; response_revision:21589; number_of_response:1; }","duration":"408.589453ms","start":"2026-10-14T20:27:09.889392Z","end":"2026-10-14T20:27:10.297981Z","steps":["trace[1076005449] 'process raft request' (duration: 90.682667ms)","trace[1076005449] 'compare' (duration: 317.221291ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:27:10.298285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.052435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-14T20:27:10.298448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:27:09.889375Z","time spent":"408.91493ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T20:27:10.298484Z","caller":"traceutil/trace.go:171","msg":"trace[1647475917] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:21590; }","duration":"302.188329ms","start":"2026-10-14T20:27:09.996163Z","end":"2026-10-14T20:27:10.298352Z","steps":["trace[1647475917] 'agreement among raft nodes before linearized reading' (duration: 302.035835ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:27:10.298731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:27:09.99614Z","time spent":"302.567407ms","remote":"127.0.0.1:47314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":32,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-10-14T20:27:10.451825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.782728ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-14T20:27:10.452001Z","caller":"traceutil/trace.go:171","msg":"trace[90777237] linearizableReadLoop","detail":"{readStateIndex:22337; appliedIndex:22336; }","duration":"153.979637ms","start":"2026-10-14T20:27:10.297961Z","end":"2026-10-14T20:27:10.451941Z","steps":["trace[90777237] 'read index received' (duration: 44.976195ms)","trace[90777237] 'applied index is now lower than readState.Index' (duration: 109.001702ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:27:10.452125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.623141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T20:27:10.452227Z","caller":"traceutil/trace.go:171","msg":"trace[314840893] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:21590; }","duration":"262.770625ms","start":"2026-10-14T20:27:10.189437Z","end":"2026-10-14T20:27:10.452207Z","steps":["trace[314840893] 'agreement among raft nodes before linearized reading' (duration: 262.599361ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:27:11.043315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.311014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T20:27:11.043391Z","caller":"traceutil/trace.go:171","msg":"trace[1727490882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21591; }","duration":"280.464637ms","start":"2026-10-14T20:27:10.762909Z","end":"2026-10-14T20:27:11.043373Z","steps":["trace[1727490882] 'range keys from in-memory index tree' (duration: 280.231232ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:27:11.492745Z","caller":"traceutil/trace.go:171","msg":"trace[455216458] transaction","detail":"{read_only:false; response_revision:21593; number_of_response:1; }","duration":"389.468621ms","start":"2026-10-14T20:27:11.103233Z","end":"2026-10-14T20:27:11.492701Z","steps":["trace[455216458] 'process raft request' (duration: 311.743ms)","trace[455216458] 'compare' (duration: 77.578697ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:27:11.493022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:27:11.103197Z","time spent":"389.665154ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T20:27:11.49688Z","caller":"traceutil/trace.go:171","msg":"trace[1112613938] transaction","detail":"{read_only:false; response_revision:21594; number_of_response:1; }","duration":"175.228569ms","start":"2026-10-14T20:27:11.321046Z","end":"2026-10-14T20:27:11.496274Z","steps":["trace[1112613938] 'process raft request' (duration: 175.063235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:27:11.880277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.711475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T20:27:11.880389Z","caller":"traceutil/trace.go:171","msg":"trace[1636362054] linearizableReadLoop","detail":"{readStateIndex:22342; appliedIndex:22341; }","duration":"383.258743ms","start":"2026-10-14T20:27:11.497115Z","end":"2026-10-14T20:27:11.880374Z","steps":["trace[1636362054] 'read index received' (duration: 176.317013ms)","trace[1636362054] 'applied index is now lower than readState.Index' (duration: 206.94064ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:27:11.88048Z","caller":"traceutil/trace.go:171","msg":"trace[160510732] transaction","detail":"{read_only:false; response_revision:21595; number_of_response:1; }","duration":"391.937184ms","start":"2026-10-14T20:27:11.488531Z","end":"2026-10-14T20:27:11.880468Z","steps":["trace[160510732] 'process raft request' (duration: 184.924702ms)","trace[160510732] 'compare' (duration: 206.607363ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:27:11.880541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:27:11.488503Z","time spent":"392.006326ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T20:27:11.880707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.561789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-14T20:27:11.880829Z","caller":"traceutil/trace.go:171","msg":"trace[768140244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21595; }","duration":"383.742733ms","start":"2026-10-14T20:27:11.497061Z","end":"2026-10-14T20:27:11.880804Z","steps":["trace[768140244] 'agreement among raft nodes before linearized reading' (duration: 383.407686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:27:11.880891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:27:11.496814Z","time spent":"384.06288ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T20:27:11.899559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.038021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-14T20:27:11.899632Z","caller":"traceutil/trace.go:171","msg":"trace[2020666550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21596; }","duration":"394.155264ms","start":"2026-10-14T20:27:11.50546Z","end":"2026-10-14T20:27:11.899615Z","steps":["trace[2020666550] 'agreement among raft nodes before linearized reading' (duration: 393.906729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:27:11.899632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.397656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T20:27:11.899735Z","caller":"traceutil/trace.go:171","msg":"trace[2135363799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21596; }","duration":"135.57196ms","start":"2026-10-14T20:27:11.764148Z","end":"2026-10-14T20:27:11.89972Z","steps":["trace[2135363799] 'agreement among raft nodes before linearized reading' (duration: 135.263944ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:27:11.899668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:27:11.505444Z","time spent":"394.216125ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-14T20:27:12.251957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.493931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T20:27:12.252085Z","caller":"traceutil/trace.go:171","msg":"trace[1290945521] transaction","detail":"{read_only:false; response_revision:21597; number_of_response:1; }","duration":"366.511212ms","start":"2026-10-14T20:27:11.885551Z","end":"2026-10-14T20:27:12.252062Z","steps":["trace[1290945521] 'process raft request' (duration: 196.785395ms)","trace[1290945521] 'compare' (duration: 169.180285ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:27:12.252201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:27:11.885527Z","time spent":"366.639995ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T20:27:12.434309Z","caller":"traceutil/trace.go:171","msg":"trace[1854732741] linearizableReadLoop","detail":"{readStateIndex:22345; appliedIndex:22344; }","duration":"157.949546ms","start":"2026-10-14T20:27:12.276334Z","end":"2026-10-14T20:27:12.434284Z","steps":["trace[1854732741] 'read index received' (duration: 157.746221ms)","trace[1854732741] 'applied index is now lower than readState.Index' (duration: 201.795µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:27:12.434364Z","caller":"traceutil/trace.go:171","msg":"trace[309235233] transaction","detail":"{read_only:false; response_revision:21598; number_of_response:1; }","duration":"529.860418ms","start":"2026-10-14T20:27:11.904478Z","end":"2026-10-14T20:27:12.434339Z","steps":["trace[309235233] 'process raft request' (duration: 529.637053ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:27:12.434465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.113299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T20:27:12.43451Z","caller":"traceutil/trace.go:171","msg":"trace[2015963070] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:21598; }","duration":"158.210272ms","start":"2026-10-14T20:27:12.276284Z","end":"2026-10-14T20:27:12.434495Z","steps":["trace[2015963070] 'agreement among raft nodes before linearized reading' (duration: 158.12541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:27:12.43458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:27:11.904456Z","time spent":"529.96818ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T20:27:12.436268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.723777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-14T20:27:12.436383Z","caller":"traceutil/trace.go:171","msg":"trace[1694577296] transaction","detail":"{read_only:false; response_revision:21599; number_of_response:1; }","duration":"130.797435ms","start":"2026-10-14T20:27:12.305339Z","end":"2026-10-14T20:27:12.436137Z","steps":["trace[1694577296] 'process raft request' (duration: 130.634871ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:27:12.436619Z","caller":"traceutil/trace.go:171","msg":"trace[1059784261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21599; }","duration":"132.86412ms","start":"2026-10-14T20:27:12.303471Z","end":"2026-10-14T20:27:12.436335Z","steps":["trace[1059784261] 'agreement among raft nodes before linearized reading' (duration: 132.620575ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:28:28.770619Z","caller":"traceutil/trace.go:171","msg":"trace[134839361] transaction","detail":"{read_only:false; response_revision:22285; number_of_response:1; }","duration":"115.239065ms","start":"2026-10-14T20:28:28.655355Z","end":"2026-10-14T20:28:28.770594Z","steps":["trace[134839361] 'process raft request' (duration: 115.00858ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:31:22.630258Z","caller":"traceutil/trace.go:171","msg":"trace[1965526397] transaction","detail":"{read_only:false; response_revision:24151; number_of_response:1; }","duration":"338.648569ms","start":"2026-10-14T20:31:22.291583Z","end":"2026-10-14T20:31:22.630231Z","steps":["trace[1965526397] 'process raft request' (duration: 338.490806ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:31:22.630365Z","caller":"traceutil/trace.go:171","msg":"trace[1022934735] linearizableReadLoop","detail":"{readStateIndex:24976; appliedIndex:24976; }","duration":"169.480348ms","start":"2026-10-14T20:31:22.460834Z","end":"2026-10-14T20:31:22.630315Z","steps":["trace[1022934735] 'read index received' (duration: 169.470648ms)","trace[1022934735] 'applied index is now lower than readState.Index' (duration: 7.87µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:31:22.630558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:31:22.291554Z","time spent":"338.882275ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T20:31:22.630592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.745654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-14T20:31:22.630626Z","caller":"traceutil/trace.go:171","msg":"trace[1975257835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24151; }","duration":"169.835086ms","start":"2026-10-14T20:31:22.460778Z","end":"2026-10-14T20:31:22.630614Z","steps":["trace[1975257835] 'agreement among raft nodes before linearized reading' (duration: 169.656712ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:31:22.632377Z","caller":"traceutil/trace.go:171","msg":"trace[286211341] transaction","detail":"{read_only:false; response_revision:24152; number_of_response:1; }","duration":"168.739091ms","start":"2026-10-14T20:31:22.463617Z","end":"2026-10-14T20:31:22.632356Z","steps":["trace[286211341] 'process raft request' (duration: 168.450475ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:32:01.647735Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21546} {"level":"info","ts":"2026-10-14T20:32:01.763063Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21546,"took":"111.585434ms","hash":3468298971,"current-db-size-bytes":57438208,"current-db-size":"57 MB","current-db-size-in-use-bytes":42319872,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-14T20:32:01.763127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3468298971,"revision":21546,"compact-revision":19273} {"level":"info","ts":"2026-10-14T20:33:59.345704Z","caller":"traceutil/trace.go:171","msg":"trace[931011538] transaction","detail":"{read_only:false; response_revision:25693; number_of_response:1; }","duration":"103.593947ms","start":"2026-10-14T20:33:59.242082Z","end":"2026-10-14T20:33:59.345676Z","steps":["trace[931011538] 'process raft request' (duration: 103.103156ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:34:50.36957Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006997.wal"} {"level":"info","ts":"2026-10-14T20:35:00.992737Z","caller":"traceutil/trace.go:171","msg":"trace[1748518554] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"122.066297ms","start":"2026-10-14T20:35:00.870645Z","end":"2026-10-14T20:35:00.992711Z","steps":["trace[1748518554] 'process raft request' (duration: 121.923194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:35:01.261426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.585761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T20:35:01.261874Z","caller":"traceutil/trace.go:171","msg":"trace[274984914] linearizableReadLoop","detail":"{readStateIndex:27136; appliedIndex:27134; }","duration":"345.968661ms","start":"2026-10-14T20:35:00.915891Z","end":"2026-10-14T20:35:01.261859Z","steps":["trace[274984914] 'read index received' (duration: 76.856054ms)","trace[274984914] 'applied index is now lower than readState.Index' (duration: 269.111697ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:35:01.261879Z","caller":"traceutil/trace.go:171","msg":"trace[1703437268] transaction","detail":"{read_only:false; response_revision:26255; number_of_response:1; }","duration":"390.131461ms","start":"2026-10-14T20:35:00.871709Z","end":"2026-10-14T20:35:01.261841Z","steps":["trace[1703437268] 'process raft request' (duration: 219.967747ms)","trace[1703437268] 'compare' (duration: 169.304095ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:35:01.26203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:00.871697Z","time spent":"390.240764ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T20:35:01.262078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.191677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11793"} {"level":"info","ts":"2026-10-14T20:35:01.262105Z","caller":"traceutil/trace.go:171","msg":"trace[1494404254] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26256; }","duration":"346.262258ms","start":"2026-10-14T20:35:00.915834Z","end":"2026-10-14T20:35:01.262097Z","steps":["trace[1494404254] 'agreement among raft nodes before linearized reading' (duration: 346.106325ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:35:01.262148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:00.915811Z","time spent":"346.329599ms","remote":"127.0.0.1:58162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11817,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-10-14T20:35:01.261951Z","caller":"traceutil/trace.go:171","msg":"trace[1656382482] transaction","detail":"{read_only:false; response_revision:26256; number_of_response:1; }","duration":"389.578099ms","start":"2026-10-14T20:35:00.872313Z","end":"2026-10-14T20:35:01.261892Z","steps":["trace[1656382482] 'process raft request' (duration: 389.451526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:35:01.262561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:00.872292Z","time spent":"390.08988ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T20:35:01.273688Z","caller":"traceutil/trace.go:171","msg":"trace[1341985981] transaction","detail":"{read_only:false; response_revision:26257; number_of_response:1; }","duration":"272.086134ms","start":"2026-10-14T20:35:01.001569Z","end":"2026-10-14T20:35:01.273656Z","steps":["trace[1341985981] 'process raft request' (duration: 271.819248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:35:01.273833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.778628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-14T20:35:01.273908Z","caller":"traceutil/trace.go:171","msg":"trace[178297336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26257; }","duration":"350.919851ms","start":"2026-10-14T20:35:00.922973Z","end":"2026-10-14T20:35:01.273893Z","steps":["trace[178297336] 'agreement among raft nodes before linearized reading' (duration: 350.704256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:35:01.273941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:00.922952Z","time spent":"350.979933ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-14T20:35:01.274107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.89057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-14T20:35:01.274241Z","caller":"traceutil/trace.go:171","msg":"trace[1144631033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26257; }","duration":"314.083604ms","start":"2026-10-14T20:35:00.960133Z","end":"2026-10-14T20:35:01.274216Z","steps":["trace[1144631033] 'agreement among raft nodes before linearized reading' (duration: 313.549302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:35:01.27433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:00.960099Z","time spent":"314.213867ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-14T20:35:01.804241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.47077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T20:35:01.804437Z","caller":"traceutil/trace.go:171","msg":"trace[1064839526] linearizableReadLoop","detail":"{readStateIndex:27140; appliedIndex:27137; }","duration":"302.761663ms","start":"2026-10-14T20:35:01.50166Z","end":"2026-10-14T20:35:01.804421Z","steps":["trace[1064839526] 'read index received' (duration: 150.224761ms)","trace[1064839526] 'applied index is now lower than readState.Index' (duration: 152.535902ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:35:01.804523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.866225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T20:35:01.80455Z","caller":"traceutil/trace.go:171","msg":"trace[464531412] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:26260; }","duration":"302.930637ms","start":"2026-10-14T20:35:01.501608Z","end":"2026-10-14T20:35:01.804538Z","steps":["trace[464531412] 'agreement among raft nodes before linearized reading' (duration: 302.857925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:35:01.80458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:01.501586Z","time spent":"302.984919ms","remote":"127.0.0.1:59630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"info","ts":"2026-10-14T20:35:01.804773Z","caller":"traceutil/trace.go:171","msg":"trace[555556317] transaction","detail":"{read_only:false; response_revision:26260; number_of_response:1; }","duration":"525.605515ms","start":"2026-10-14T20:35:01.279155Z","end":"2026-10-14T20:35:01.80476Z","steps":["trace[555556317] 'process raft request' (duration: 525.234017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:35:01.804828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:01.279137Z","time spent":"525.660627ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T20:35:01.805043Z","caller":"traceutil/trace.go:171","msg":"trace[223843422] transaction","detail":"{read_only:false; response_revision:26258; number_of_response:1; }","duration":"526.028355ms","start":"2026-10-14T20:35:01.278958Z","end":"2026-10-14T20:35:01.804986Z","steps":["trace[223843422] 'process raft request' (duration: 170.724226ms)","trace[223843422] 'compare' (duration: 353.867967ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:35:01.805631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:01.278927Z","time spent":"526.625758ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T20:35:01.804758Z","caller":"traceutil/trace.go:171","msg":"trace[617333429] transaction","detail":"{read_only:false; response_revision:26259; number_of_response:1; }","duration":"525.584565ms","start":"2026-10-14T20:35:01.279125Z","end":"2026-10-14T20:35:01.804709Z","steps":["trace[617333429] 'process raft request' (duration: 525.209987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:35:01.805813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:01.279102Z","time spent":"526.597038ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T20:35:02.004365Z","caller":"traceutil/trace.go:171","msg":"trace[1944606551] transaction","detail":"{read_only:false; response_revision:26263; number_of_response:1; }","duration":"396.744188ms","start":"2026-10-14T20:35:01.607576Z","end":"2026-10-14T20:35:02.00432Z","steps":["trace[1944606551] 'process raft request' (duration: 396.706087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:35:02.004495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:01.607564Z","time spent":"396.864401ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T20:35:02.004854Z","caller":"traceutil/trace.go:171","msg":"trace[649633795] transaction","detail":"{read_only:false; response_revision:26261; number_of_response:1; }","duration":"491.325705ms","start":"2026-10-14T20:35:01.513514Z","end":"2026-10-14T20:35:02.00484Z","steps":["trace[649633795] 'process raft request' (duration: 490.558998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:35:02.004977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:01.513486Z","time spent":"491.401387ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T20:35:02.005156Z","caller":"traceutil/trace.go:171","msg":"trace[1821386253] transaction","detail":"{read_only:false; response_revision:26262; number_of_response:1; }","duration":"397.927594ms","start":"2026-10-14T20:35:01.607187Z","end":"2026-10-14T20:35:02.005115Z","steps":["trace[1821386253] 'process raft request' (duration: 397.054014ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:35:02.005317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:01.607162Z","time spent":"398.069867ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T20:35:02.176832Z","caller":"traceutil/trace.go:171","msg":"trace[1805760197] transaction","detail":"{read_only:false; response_revision:26264; number_of_response:1; }","duration":"524.172554ms","start":"2026-10-14T20:35:01.652623Z","end":"2026-10-14T20:35:02.176796Z","steps":["trace[1805760197] 'process raft request' (duration: 523.53049ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:35:02.176855Z","caller":"traceutil/trace.go:171","msg":"trace[1483127888] transaction","detail":"{read_only:false; response_revision:26265; number_of_response:1; }","duration":"444.855325ms","start":"2026-10-14T20:35:01.731976Z","end":"2026-10-14T20:35:02.176831Z","steps":["trace[1483127888] 'process raft request' (duration: 444.718522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:35:02.177056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:01.731953Z","time spent":"444.998947ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T20:35:02.177067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:01.652599Z","time spent":"524.298126ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T20:35:02.177312Z","caller":"traceutil/trace.go:171","msg":"trace[1820335289] transaction","detail":"{read_only:false; response_revision:26266; number_of_response:1; }","duration":"382.487812ms","start":"2026-10-14T20:35:01.794812Z","end":"2026-10-14T20:35:02.177299Z","steps":["trace[1820335289] 'process raft request' (duration: 381.972761ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:35:02.177426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:01.79472Z","time spent":"382.663676ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T20:35:02.177642Z","caller":"traceutil/trace.go:171","msg":"trace[1185497189] linearizableReadLoop","detail":"{readStateIndex:27146; appliedIndex:27140; }","duration":"350.799109ms","start":"2026-10-14T20:35:01.826825Z","end":"2026-10-14T20:35:02.177624Z","steps":["trace[1185497189] 'read index received' (duration: 177.193269ms)","trace[1185497189] 'applied index is now lower than readState.Index' (duration: 173.60492ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:35:02.177821Z","caller":"traceutil/trace.go:171","msg":"trace[1367144656] transaction","detail":"{read_only:false; response_revision:26267; number_of_response:1; }","duration":"305.234928ms","start":"2026-10-14T20:35:01.872561Z","end":"2026-10-14T20:35:02.177796Z","steps":["trace[1367144656] 'process raft request' (duration: 304.366829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:35:02.17793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:01.872526Z","time spent":"305.351891ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T20:35:02.179598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.755252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-14T20:35:02.179637Z","caller":"traceutil/trace.go:171","msg":"trace[1206514040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26267; }","duration":"352.847955ms","start":"2026-10-14T20:35:01.826777Z","end":"2026-10-14T20:35:02.179625Z","steps":["trace[1206514040] 'agreement among raft nodes before linearized reading' (duration: 350.917282ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:35:02.179698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:35:01.826755Z","time spent":"352.934346ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-14T20:36:00.452961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.344174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T20:36:00.453092Z","caller":"traceutil/trace.go:171","msg":"trace[1806398271] transaction","detail":"{read_only:false; response_revision:26818; number_of_response:1; }","duration":"224.304784ms","start":"2026-10-14T20:36:00.228768Z","end":"2026-10-14T20:36:00.453072Z","steps":["trace[1806398271] 'process raft request' (duration: 119.720945ms)","trace[1806398271] 'compare' (duration: 104.16363ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:36:00.478641Z","caller":"traceutil/trace.go:171","msg":"trace[526230369] transaction","detail":"{read_only:false; response_revision:26819; number_of_response:1; }","duration":"246.560477ms","start":"2026-10-14T20:36:00.232049Z","end":"2026-10-14T20:36:00.47861Z","steps":["trace[526230369] 'process raft request' (duration: 246.2228ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:00.478714Z","caller":"traceutil/trace.go:171","msg":"trace[10380202] linearizableReadLoop","detail":"{readStateIndex:27714; appliedIndex:27711; }","duration":"212.463942ms","start":"2026-10-14T20:36:00.266203Z","end":"2026-10-14T20:36:00.478667Z","steps":["trace[10380202] 'read index received' (duration: 82.254144ms)","trace[10380202] 'applied index is now lower than readState.Index' (duration: 130.207788ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:36:00.478851Z","caller":"traceutil/trace.go:171","msg":"trace[942159393] transaction","detail":"{read_only:false; response_revision:26820; number_of_response:1; }","duration":"213.428202ms","start":"2026-10-14T20:36:00.26541Z","end":"2026-10-14T20:36:00.478838Z","steps":["trace[942159393] 'process raft request' (duration: 213.077295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:36:00.478926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.675276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T20:36:00.478966Z","caller":"traceutil/trace.go:171","msg":"trace[1963199574] transaction","detail":"{read_only:false; response_revision:26821; number_of_response:1; }","duration":"211.099051ms","start":"2026-10-14T20:36:00.267854Z","end":"2026-10-14T20:36:00.478953Z","steps":["trace[1963199574] 'process raft request' (duration: 210.699262ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:00.478978Z","caller":"traceutil/trace.go:171","msg":"trace[1796584410] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26821; }","duration":"212.805599ms","start":"2026-10-14T20:36:00.266155Z","end":"2026-10-14T20:36:00.478961Z","steps":["trace[1796584410] 'agreement among raft nodes before linearized reading' (duration: 212.672936ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:36:05.944068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.065863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T20:36:05.94417Z","caller":"traceutil/trace.go:171","msg":"trace[1646317673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26857; }","duration":"180.244646ms","start":"2026-10-14T20:36:05.763902Z","end":"2026-10-14T20:36:05.944147Z","steps":["trace[1646317673] 'range keys from in-memory index tree' (duration: 179.881059ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:36:05.944417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.543726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T20:36:05.944513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.567068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"warn","ts":"2026-10-14T20:36:05.944521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.322876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-14T20:36:05.94455Z","caller":"traceutil/trace.go:171","msg":"trace[259757140] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:26857; }","duration":"196.64087ms","start":"2026-10-14T20:36:05.747894Z","end":"2026-10-14T20:36:05.944535Z","steps":["trace[259757140] 'range keys from in-memory index tree' (duration: 196.365744ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:05.944576Z","caller":"traceutil/trace.go:171","msg":"trace[743215791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26857; }","duration":"262.43289ms","start":"2026-10-14T20:36:05.68213Z","end":"2026-10-14T20:36:05.944562Z","steps":["trace[743215791] 'range keys from in-memory index tree' (duration: 262.161573ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:05.944516Z","caller":"traceutil/trace.go:171","msg":"trace[1588023095] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:26857; }","duration":"158.715559ms","start":"2026-10-14T20:36:05.785779Z","end":"2026-10-14T20:36:05.944494Z","steps":["trace[1588023095] 'count revisions from in-memory index tree' (duration: 158.430513ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:06.053217Z","caller":"traceutil/trace.go:171","msg":"trace[2064828941] linearizableReadLoop","detail":"{readStateIndex:27753; appliedIndex:27752; }","duration":"100.99879ms","start":"2026-10-14T20:36:05.952183Z","end":"2026-10-14T20:36:06.053182Z","steps":["trace[2064828941] 'read index received' (duration: 100.693783ms)","trace[2064828941] 'applied index is now lower than readState.Index' (duration: 303.627µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:36:06.053273Z","caller":"traceutil/trace.go:171","msg":"trace[1281902908] transaction","detail":"{read_only:false; response_revision:26858; number_of_response:1; }","duration":"103.419774ms","start":"2026-10-14T20:36:05.949814Z","end":"2026-10-14T20:36:06.053233Z","steps":["trace[1281902908] 'process raft request' (duration: 103.083606ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:36:06.053759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.553162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-56e00fc6-ad72-4b01-854c-4c6826fdfc77\" ","response":"range_response_count:1 size:1318"} {"level":"info","ts":"2026-10-14T20:36:06.053846Z","caller":"traceutil/trace.go:171","msg":"trace[2135393955] range","detail":"{range_begin:/registry/persistentvolumes/pvc-56e00fc6-ad72-4b01-854c-4c6826fdfc77; range_end:; response_count:1; response_revision:26858; }","duration":"101.705096ms","start":"2026-10-14T20:36:05.952119Z","end":"2026-10-14T20:36:06.053824Z","steps":["trace[2135393955] 'agreement among raft nodes before linearized reading' (duration: 101.229125ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:06.250378Z","caller":"traceutil/trace.go:171","msg":"trace[76007969] transaction","detail":"{read_only:false; response_revision:26859; number_of_response:1; }","duration":"191.959777ms","start":"2026-10-14T20:36:06.058365Z","end":"2026-10-14T20:36:06.250325Z","steps":["trace[76007969] 'process raft request' (duration: 180.35067ms)","trace[76007969] 'compare' (duration: 11.387852ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:36:06.663118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.647837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T20:36:06.663286Z","caller":"traceutil/trace.go:171","msg":"trace[775801346] transaction","detail":"{read_only:false; response_revision:26860; number_of_response:1; }","duration":"406.987515ms","start":"2026-10-14T20:36:06.256265Z","end":"2026-10-14T20:36:06.663253Z","steps":["trace[775801346] 'process raft request' (duration: 193.054141ms)","trace[775801346] 'compare' (duration: 213.263209ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:36:06.663369Z","caller":"traceutil/trace.go:171","msg":"trace[551725056] transaction","detail":"{read_only:false; response_revision:26861; number_of_response:1; }","duration":"276.059062ms","start":"2026-10-14T20:36:06.387291Z","end":"2026-10-14T20:36:06.66335Z","steps":["trace[551725056] 'process raft request' (duration: 275.96394ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:36:06.663404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:36:06.256244Z","time spent":"407.107348ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T20:36:06.663584Z","caller":"traceutil/trace.go:171","msg":"trace[1574975370] linearizableReadLoop","detail":"{readStateIndex:27755; appliedIndex:27754; }","duration":"307.09597ms","start":"2026-10-14T20:36:06.356471Z","end":"2026-10-14T20:36:06.663567Z","steps":["trace[1574975370] 'read index received' (duration: 92.757577ms)","trace[1574975370] 'applied index is now lower than readState.Index' (duration: 214.336963ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:36:06.664244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.755815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T20:36:06.664332Z","caller":"traceutil/trace.go:171","msg":"trace[1967186152] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:26861; }","duration":"307.910268ms","start":"2026-10-14T20:36:06.356404Z","end":"2026-10-14T20:36:06.664314Z","steps":["trace[1967186152] 'agreement among raft nodes before linearized reading' (duration: 307.734454ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:36:06.664809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T20:36:06.356374Z","time spent":"308.305776ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"info","ts":"2026-10-14T20:36:06.686113Z","caller":"traceutil/trace.go:171","msg":"trace[1842007389] transaction","detail":"{read_only:false; response_revision:26862; number_of_response:1; }","duration":"191.257491ms","start":"2026-10-14T20:36:06.494821Z","end":"2026-10-14T20:36:06.686079Z","steps":["trace[1842007389] 'process raft request' (duration: 189.954022ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:06.686459Z","caller":"traceutil/trace.go:171","msg":"trace[822818616] transaction","detail":"{read_only:false; response_revision:26863; number_of_response:1; }","duration":"188.403018ms","start":"2026-10-14T20:36:06.498039Z","end":"2026-10-14T20:36:06.686442Z","steps":["trace[822818616] 'process raft request' (duration: 186.997557ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:06.686571Z","caller":"traceutil/trace.go:171","msg":"trace[381546133] transaction","detail":"{read_only:false; response_revision:26864; number_of_response:1; }","duration":"184.44777ms","start":"2026-10-14T20:36:06.502111Z","end":"2026-10-14T20:36:06.686559Z","steps":["trace[381546133] 'process raft request' (duration: 182.983778ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:06.686654Z","caller":"traceutil/trace.go:171","msg":"trace[1264439677] transaction","detail":"{read_only:false; response_revision:26865; number_of_response:1; }","duration":"161.02364ms","start":"2026-10-14T20:36:06.52562Z","end":"2026-10-14T20:36:06.686644Z","steps":["trace[1264439677] 'process raft request' (duration: 159.524107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:36:06.686927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.195523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-14T20:36:06.686986Z","caller":"traceutil/trace.go:171","msg":"trace[1847491261] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26868; }","duration":"206.330366ms","start":"2026-10-14T20:36:06.480637Z","end":"2026-10-14T20:36:06.686967Z","steps":["trace[1847491261] 'agreement among raft nodes before linearized reading' (duration: 206.116001ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:06.687233Z","caller":"traceutil/trace.go:171","msg":"trace[677456686] transaction","detail":"{read_only:false; response_revision:26866; number_of_response:1; }","duration":"158.637768ms","start":"2026-10-14T20:36:06.52858Z","end":"2026-10-14T20:36:06.687218Z","steps":["trace[677456686] 'process raft request' (duration: 156.878849ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:06.687341Z","caller":"traceutil/trace.go:171","msg":"trace[1588890440] transaction","detail":"{read_only:false; response_revision:26867; number_of_response:1; }","duration":"156.393869ms","start":"2026-10-14T20:36:06.530933Z","end":"2026-10-14T20:36:06.687327Z","steps":["trace[1588890440] 'process raft request' (duration: 154.626219ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:06.687438Z","caller":"traceutil/trace.go:171","msg":"trace[1534534539] transaction","detail":"{read_only:false; response_revision:26868; number_of_response:1; }","duration":"155.970769ms","start":"2026-10-14T20:36:06.531455Z","end":"2026-10-14T20:36:06.687426Z","steps":["trace[1534534539] 'process raft request' (duration: 154.145568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:36:06.687828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.896411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-14T20:36:06.687864Z","caller":"traceutil/trace.go:171","msg":"trace[1802550188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26868; }","duration":"120.979053ms","start":"2026-10-14T20:36:06.566874Z","end":"2026-10-14T20:36:06.687854Z","steps":["trace[1802550188] 'agreement among raft nodes before linearized reading' (duration: 120.84596ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T20:36:06.687887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.059199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-14T20:36:06.687938Z","caller":"traceutil/trace.go:171","msg":"trace[1094671653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26868; }","duration":"178.138701ms","start":"2026-10-14T20:36:06.509783Z","end":"2026-10-14T20:36:06.687922Z","steps":["trace[1094671653] 'agreement among raft nodes before linearized reading' (duration: 178.002418ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:06.930285Z","caller":"traceutil/trace.go:171","msg":"trace[292433104] linearizableReadLoop","detail":"{readStateIndex:27766; appliedIndex:27764; }","duration":"166.322898ms","start":"2026-10-14T20:36:06.763921Z","end":"2026-10-14T20:36:06.930244Z","steps":["trace[292433104] 'read index received' (duration: 164.438226ms)","trace[292433104] 'applied index is now lower than readState.Index' (duration: 1.883002ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T20:36:06.930541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.596455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T20:36:06.930598Z","caller":"traceutil/trace.go:171","msg":"trace[491865814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26871; }","duration":"166.733968ms","start":"2026-10-14T20:36:06.76385Z","end":"2026-10-14T20:36:06.930584Z","steps":["trace[491865814] 'agreement among raft nodes before linearized reading' (duration: 166.571074ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:06.930743Z","caller":"traceutil/trace.go:171","msg":"trace[1588330628] transaction","detail":"{read_only:false; response_revision:26870; number_of_response:1; }","duration":"233.1531ms","start":"2026-10-14T20:36:06.697521Z","end":"2026-10-14T20:36:06.930674Z","steps":["trace[1588330628] 'process raft request' (duration: 230.93555ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:06.930912Z","caller":"traceutil/trace.go:171","msg":"trace[1765690900] transaction","detail":"{read_only:false; response_revision:26871; number_of_response:1; }","duration":"232.951415ms","start":"2026-10-14T20:36:06.697942Z","end":"2026-10-14T20:36:06.930893Z","steps":["trace[1765690900] 'process raft request' (duration: 232.189749ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:07.277857Z","caller":"traceutil/trace.go:171","msg":"trace[1957128122] transaction","detail":"{read_only:false; response_revision:26872; number_of_response:1; }","duration":"267.990102ms","start":"2026-10-14T20:36:07.009832Z","end":"2026-10-14T20:36:07.277822Z","steps":["trace[1957128122] 'process raft request' (duration: 267.771577ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:36:07.283487Z","caller":"traceutil/trace.go:171","msg":"trace[815224979] transaction","detail":"{read_only:false; response_revision:26873; number_of_response:1; }","duration":"153.390671ms","start":"2026-10-14T20:36:07.130063Z","end":"2026-10-14T20:36:07.283454Z","steps":["trace[815224979] 'process raft request' (duration: 153.193776ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:37:01.653993Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24527} {"level":"info","ts":"2026-10-14T20:37:01.813241Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24527,"took":"154.482313ms","hash":2261482020,"current-db-size-bytes":58281984,"current-db-size":"58 MB","current-db-size-in-use-bytes":35397632,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-14T20:37:01.813313Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2261482020,"revision":24527,"compact-revision":21546} {"level":"info","ts":"2026-10-14T20:39:27.724953Z","caller":"traceutil/trace.go:171","msg":"trace[475294793] transaction","detail":"{read_only:false; response_revision:28851; number_of_response:1; }","duration":"133.455086ms","start":"2026-10-14T20:39:27.591442Z","end":"2026-10-14T20:39:27.724897Z","steps":["trace[475294793] 'process raft request' (duration: 133.354814ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T20:39:27.725171Z","caller":"traceutil/trace.go:171","msg":"trace[1823217183] transaction","detail":"{read_only:false; response_revision:28850; number_of_response:1; }","duration":"168.793369ms","start":"2026-10-14T20:39:27.556365Z","end":"2026-10-14T20:39:27.725158Z","steps":["trace[1823217183] 'process raft request' (duration: 117.905291ms)","trace[1823217183] 'compare' (duration: 50.329415ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T20:39:47.138753Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9ac9f866d7f9ac7b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T20:39:47.14973Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-14T20:39:47.149814Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-14T20:42:01.661631Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27447} {"level":"info","ts":"2026-10-14T20:42:01.807796Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27447,"took":"141.898314ms","hash":1363037763,"current-db-size-bytes":58732544,"current-db-size":"59 MB","current-db-size-in-use-bytes":36663296,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-14T20:42:01.807873Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1363037763,"revision":27447,"compact-revision":24527} {"level":"info","ts":"2026-10-14T20:47:01.668054Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30148} {"level":"info","ts":"2026-10-14T20:47:01.825724Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30148,"took":"151.88062ms","hash":553268467,"current-db-size-bytes":58732544,"current-db-size":"59 MB","current-db-size-in-use-bytes":30523392,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T20:47:01.825795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":553268467,"revision":30148,"compact-revision":27447} {"level":"info","ts":"2026-10-14T20:52:01.675692Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32404} {"level":"info","ts":"2026-10-14T20:52:01.843108Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32404,"took":"162.209839ms","hash":1671720572,"current-db-size-bytes":59777024,"current-db-size":"60 MB","current-db-size-in-use-bytes":30154752,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T20:52:01.843258Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1671720572,"revision":32404,"compact-revision":30148} {"level":"info","ts":"2026-10-14T20:57:01.683458Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34634} {"level":"info","ts":"2026-10-14T20:57:01.850255Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34634,"took":"148.403154ms","hash":384201747,"current-db-size-bytes":59777024,"current-db-size":"60 MB","current-db-size-in-use-bytes":31543296,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T20:57:01.850338Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":384201747,"revision":34634,"compact-revision":32404} {"level":"info","ts":"2026-10-14T21:00:54.850133Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9ac9f866d7f9ac7b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T21:00:54.858557Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-14T21:00:54.858653Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-14T21:02:01.690482Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36926} {"level":"info","ts":"2026-10-14T21:02:01.828751Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36926,"took":"134.099217ms","hash":1546206846,"current-db-size-bytes":60624896,"current-db-size":"61 MB","current-db-size-in-use-bytes":33619968,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-14T21:02:01.828861Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1546206846,"revision":36926,"compact-revision":34634} {"level":"info","ts":"2026-10-14T21:07:01.698488Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39270} {"level":"info","ts":"2026-10-14T21:07:01.841147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39270,"took":"138.093281ms","hash":344706343,"current-db-size-bytes":61046784,"current-db-size":"61 MB","current-db-size-in-use-bytes":31305728,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T21:07:01.841239Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":344706343,"revision":39270,"compact-revision":36926}