{"level":"warn","ts":"2026-10-17T10:54:26.974252Z","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-17T10:54:26.974343Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209: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://162.253.55.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209: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-17T10:54:26.974411Z","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-17T10:54:26.974423Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-10-17T10:54:26.974449Z","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-17T10:54:26.975495Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]} {"level":"info","ts":"2026-10-17T10:54:26.975662Z","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://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209: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-17T10:54:26.981327Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.174062ms"} {"level":"info","ts":"2026-10-17T10:54:26.988506Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-10-17T10:54:26.988624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-10-17T10:54:26.988653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-10-17T10:54:26.988672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-17T10:54:26.988684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-10-17T10:54:26.988737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-10-17T10:54:26.996469Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-17T10:54:26.998631Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-17T10:54:27.000405Z","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-17T10:54:27.004581Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-17T10:54:27.004869Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-17T10:54:27.004941Z","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-17T10:54:27.005088Z","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-17T10:54:27.005106Z","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-17T10:54:27.006936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-10-17T10:54:27.007297Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-10-17T10:54:27.011265Z","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-17T10:54:27.012066Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-17T10:54:27.01211Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-17T10:54:27.011369Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-10-17T10:54:27.012151Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-10-17T10:54:27.389984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-10-17T10:54:27.390054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-17T10:54:27.390087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-10-17T10:54:27.39011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-10-17T10:54:27.390137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-10-17T10:54:27.390303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-10-17T10:54:27.390334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-10-17T10:54:27.391833Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"} {"level":"info","ts":"2026-10-17T10:54:27.391836Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T10:54:27.391886Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:54:27.391932Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T10:54:27.392223Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-17T10:54:27.392426Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-17T10:54:27.393179Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:54:27.39329Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:54:27.393326Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:54:27.395274Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-10-17T10:54:27.396377Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-10-17T10:54:29.431012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-10-17T10:54:29.431432Z","caller":"traceutil/trace.go:171","msg":"trace[1363662665] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"313.918278ms","start":"2026-10-17T10:54:29.117486Z","end":"2026-10-17T10:54:29.431405Z","steps":["trace[1363662665] 'process raft request' (duration: 193.766294ms)","trace[1363662665] 'compare' (duration: 118.944235ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:54:29.431561Z","caller":"traceutil/trace.go:171","msg":"trace[1131929361] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"306.524046ms","start":"2026-10-17T10:54:29.124993Z","end":"2026-10-17T10:54:29.431517Z","steps":["trace[1131929361] 'process raft request' (duration: 306.188366ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:54:29.431688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.034784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-10-17T10:54:29.431644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:54:29.117468Z","time spent":"314.054101ms","remote":"127.0.0.1:54610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1089,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:54:29.43171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:54:29.124976Z","time spent":"306.66133ms","remote":"127.0.0.1:54626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":565,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-17T10:54:29.431727Z","caller":"traceutil/trace.go:171","msg":"trace[1462180175] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:61; }","duration":"228.149887ms","start":"2026-10-17T10:54:29.203566Z","end":"2026-10-17T10:54:29.431716Z","steps":["trace[1462180175] 'agreement among raft nodes before linearized reading' (duration: 228.057914ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:54:29.431599Z","caller":"traceutil/trace.go:171","msg":"trace[671041518] linearizableReadLoop","detail":"{readStateIndex:65; appliedIndex:63; }","duration":"227.964431ms","start":"2026-10-17T10:54:29.203622Z","end":"2026-10-17T10:54:29.431586Z","steps":["trace[671041518] 'read index received' (duration: 107.56137ms)","trace[671041518] 'applied index is now lower than readState.Index' (duration: 120.401961ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:54:29.43207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.205886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-17T10:54:29.432125Z","caller":"traceutil/trace.go:171","msg":"trace[1121876653] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:61; }","duration":"129.352392ms","start":"2026-10-17T10:54:29.302759Z","end":"2026-10-17T10:54:29.432111Z","steps":["trace[1121876653] 'agreement among raft nodes before linearized reading' (duration: 129.188406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:54:46.858685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.854757ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-17T10:54:46.858787Z","caller":"traceutil/trace.go:171","msg":"trace[32190881] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"663.579032ms","start":"2026-10-17T10:54:46.195192Z","end":"2026-10-17T10:54:46.858771Z","steps":["trace[32190881] 'read index received' (duration: 93.873µs)","trace[32190881] 'applied index is now lower than readState.Index' (duration: 663.483709ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:54:46.858952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.751028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-17T10:54:46.858998Z","caller":"traceutil/trace.go:171","msg":"trace[1064706589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:418; }","duration":"663.842071ms","start":"2026-10-17T10:54:46.195145Z","end":"2026-10-17T10:54:46.858987Z","steps":["trace[1064706589] 'agreement among raft nodes before linearized reading' (duration: 663.734697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:54:46.859029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:54:46.195125Z","time spent":"663.894962ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-17T10:54:46.859306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.898325ms","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-17T10:54:46.859346Z","caller":"traceutil/trace.go:171","msg":"trace[1322602192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:418; }","duration":"263.976347ms","start":"2026-10-17T10:54:46.59536Z","end":"2026-10-17T10:54:46.859337Z","steps":["trace[1322602192] 'agreement among raft nodes before linearized reading' (duration: 263.911435ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:09.993372Z","caller":"traceutil/trace.go:171","msg":"trace[279643098] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"100.560402ms","start":"2026-10-17T10:55:09.892782Z","end":"2026-10-17T10:55:09.993342Z","steps":["trace[279643098] 'process raft request' (duration: 100.385006ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:11.275653Z","caller":"traceutil/trace.go:171","msg":"trace[1167028172] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"179.292642ms","start":"2026-10-17T10:55:11.096323Z","end":"2026-10-17T10:55:11.275616Z","steps":["trace[1167028172] 'process raft request' (duration: 131.948033ms)","trace[1167028172] 'compare' (duration: 47.105711ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:55:11.280177Z","caller":"traceutil/trace.go:171","msg":"trace[1719855841] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"128.960659ms","start":"2026-10-17T10:55:11.151173Z","end":"2026-10-17T10:55:11.280134Z","steps":["trace[1719855841] 'process raft request' (duration: 125.425509ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:11.280426Z","caller":"traceutil/trace.go:171","msg":"trace[720071736] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"121.398183ms","start":"2026-10-17T10:55:11.158997Z","end":"2026-10-17T10:55:11.280395Z","steps":["trace[720071736] 'process raft request' (duration: 119.05945ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:11.442974Z","caller":"traceutil/trace.go:171","msg":"trace[256393977] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"119.662519ms","start":"2026-10-17T10:55:11.323284Z","end":"2026-10-17T10:55:11.442947Z","steps":["trace[256393977] 'process raft request' (duration: 88.543636ms)","trace[256393977] 'compare' (duration: 30.953638ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:55:17.215738Z","caller":"traceutil/trace.go:171","msg":"trace[426529054] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"359.35009ms","start":"2026-10-17T10:55:16.85633Z","end":"2026-10-17T10:55:17.21568Z","steps":["trace[426529054] 'read index received' (duration: 352.049818ms)","trace[426529054] 'applied index is now lower than readState.Index' (duration: 7.299232ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:55:17.215836Z","caller":"traceutil/trace.go:171","msg":"trace[1546432491] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"523.405548ms","start":"2026-10-17T10:55:16.692346Z","end":"2026-10-17T10:55:17.215752Z","steps":["trace[1546432491] 'process raft request' (duration: 516.709406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:17.215932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.492093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:55:17.215973Z","caller":"traceutil/trace.go:171","msg":"trace[999928279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"240.580416ms","start":"2026-10-17T10:55:16.975382Z","end":"2026-10-17T10:55:17.215962Z","steps":["trace[999928279] 'agreement among raft nodes before linearized reading' (duration: 240.499043ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:17.216053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:16.692318Z","time spent":"523.626115ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:55:17.216066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.716872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2pbv7\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-17T10:55:17.216153Z","caller":"traceutil/trace.go:171","msg":"trace[709775489] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2pbv7; range_end:; response_count:1; response_revision:643; }","duration":"359.851356ms","start":"2026-10-17T10:55:16.85628Z","end":"2026-10-17T10:55:17.216131Z","steps":["trace[709775489] 'agreement among raft nodes before linearized reading' (duration: 359.593458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:17.216215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:16.856262Z","time spent":"359.943679ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-2pbv7\" "} {"level":"warn","ts":"2026-10-17T10:55:17.216254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.721643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-17T10:55:17.21629Z","caller":"traceutil/trace.go:171","msg":"trace[439899123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"118.790525ms","start":"2026-10-17T10:55:17.097491Z","end":"2026-10-17T10:55:17.216282Z","steps":["trace[439899123] 'agreement among raft nodes before linearized reading' (duration: 118.722073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:17.216465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.8936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-17T10:55:17.216568Z","caller":"traceutil/trace.go:171","msg":"trace[2071823144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:643; }","duration":"142.010405ms","start":"2026-10-17T10:55:17.074532Z","end":"2026-10-17T10:55:17.216542Z","steps":["trace[2071823144] 'agreement among raft nodes before linearized reading' (duration: 141.766996ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:17.560596Z","caller":"traceutil/trace.go:171","msg":"trace[828585780] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"338.797455ms","start":"2026-10-17T10:55:17.221735Z","end":"2026-10-17T10:55:17.560532Z","steps":["trace[828585780] 'process raft request' (duration: 321.943559ms)","trace[828585780] 'compare' (duration: 16.566897ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:55:17.56089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:17.2217Z","time spent":"339.007852ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:55:17.560881Z","caller":"traceutil/trace.go:171","msg":"trace[1945753609] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"327.135773ms","start":"2026-10-17T10:55:17.233719Z","end":"2026-10-17T10:55:17.560855Z","steps":["trace[1945753609] 'process raft request' (duration: 326.688679ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:17.560999Z","caller":"traceutil/trace.go:171","msg":"trace[1132685532] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:672; }","duration":"216.633312ms","start":"2026-10-17T10:55:17.344335Z","end":"2026-10-17T10:55:17.560969Z","steps":["trace[1132685532] 'read index received' (duration: 199.365472ms)","trace[1132685532] 'applied index is now lower than readState.Index' (duration: 17.26674ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:55:17.561071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:17.233704Z","time spent":"327.257917ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:55:17.561089Z","caller":"traceutil/trace.go:171","msg":"trace[483631719] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"267.129373ms","start":"2026-10-17T10:55:17.293921Z","end":"2026-10-17T10:55:17.56105Z","steps":["trace[483631719] 'process raft request' (duration: 266.76767ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:17.561517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.13426ms","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-17T10:55:17.561616Z","caller":"traceutil/trace.go:171","msg":"trace[1315183985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"217.270664ms","start":"2026-10-17T10:55:17.344295Z","end":"2026-10-17T10:55:17.561566Z","steps":["trace[1315183985] 'agreement among raft nodes before linearized reading' (duration: 216.796989ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:19.158912Z","caller":"traceutil/trace.go:171","msg":"trace[1820464384] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"143.370178ms","start":"2026-10-17T10:55:19.015509Z","end":"2026-10-17T10:55:19.158879Z","steps":["trace[1820464384] 'process raft request' (duration: 142.516801ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:19.159106Z","caller":"traceutil/trace.go:171","msg":"trace[1967519303] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"136.472158ms","start":"2026-10-17T10:55:19.022612Z","end":"2026-10-17T10:55:19.159084Z","steps":["trace[1967519303] 'process raft request' (duration: 136.138528ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:33.526258Z","caller":"traceutil/trace.go:171","msg":"trace[781228713] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:845; }","duration":"444.803264ms","start":"2026-10-17T10:55:33.081431Z","end":"2026-10-17T10:55:33.526235Z","steps":["trace[781228713] 'read index received' (duration: 444.593366ms)","trace[781228713] 'applied index is now lower than readState.Index' (duration: 209.068µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:55:33.526338Z","caller":"traceutil/trace.go:171","msg":"trace[966494724] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"543.528419ms","start":"2026-10-17T10:55:32.982759Z","end":"2026-10-17T10:55:33.526288Z","steps":["trace[966494724] 'process raft request' (duration: 543.353953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:33.52656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.767493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-17T10:55:33.526601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.0232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-17T10:55:33.526653Z","caller":"traceutil/trace.go:171","msg":"trace[70000488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:813; }","duration":"445.256087ms","start":"2026-10-17T10:55:33.081385Z","end":"2026-10-17T10:55:33.526642Z","steps":["trace[70000488] 'agreement among raft nodes before linearized reading' (duration: 444.972399ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:33.526654Z","caller":"traceutil/trace.go:171","msg":"trace[1974926913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:813; }","duration":"165.912467ms","start":"2026-10-17T10:55:33.360724Z","end":"2026-10-17T10:55:33.526636Z","steps":["trace[1974926913] 'agreement among raft nodes before linearized reading' (duration: 165.68128ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:33.526713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:32.982737Z","time spent":"543.867049ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:55:33.52669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:33.081363Z","time spent":"445.31353ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-17T10:56:02.869355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.573032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T10:56:02.869467Z","caller":"traceutil/trace.go:171","msg":"trace[144780132] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"279.251679ms","start":"2026-10-17T10:56:02.5902Z","end":"2026-10-17T10:56:02.869451Z","steps":["trace[144780132] 'read index received' (duration: 158.549233ms)","trace[144780132] 'applied index is now lower than readState.Index' (duration: 120.701276ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:56:02.869527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.327931ms","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-17T10:56:02.869557Z","caller":"traceutil/trace.go:171","msg":"trace[975136316] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1213; }","duration":"279.397893ms","start":"2026-10-17T10:56:02.590151Z","end":"2026-10-17T10:56:02.869549Z","steps":["trace[975136316] 'agreement among raft nodes before linearized reading' (duration: 279.341941ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:56:02.869612Z","caller":"traceutil/trace.go:171","msg":"trace[1686402242] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"305.696241ms","start":"2026-10-17T10:56:02.563884Z","end":"2026-10-17T10:56:02.86958Z","steps":["trace[1686402242] 'process raft request' (duration: 184.816479ms)","trace[1686402242] 'compare' (duration: 120.459999ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:56:02.869768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:56:02.563853Z","time spent":"305.832935ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:56:15.998104Z","caller":"traceutil/trace.go:171","msg":"trace[1119492330] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"118.746413ms","start":"2026-10-17T10:56:15.879312Z","end":"2026-10-17T10:56:15.998059Z","steps":["trace[1119492330] 'process raft request' (duration: 118.469554ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:56:48.248769Z","caller":"traceutil/trace.go:171","msg":"trace[2036962550] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"198.440033ms","start":"2026-10-17T10:56:48.050295Z","end":"2026-10-17T10:56:48.248735Z","steps":["trace[2036962550] 'process raft request' (duration: 137.55135ms)","trace[2036962550] 'compare' (duration: 60.607284ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:56:48.623051Z","caller":"traceutil/trace.go:171","msg":"trace[717137797] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1953; }","duration":"185.399915ms","start":"2026-10-17T10:56:48.437625Z","end":"2026-10-17T10:56:48.623025Z","steps":["trace[717137797] 'read index received' (duration: 185.156907ms)","trace[717137797] 'applied index is now lower than readState.Index' (duration: 241.578µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:56:48.623058Z","caller":"traceutil/trace.go:171","msg":"trace[1107013609] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"233.855818ms","start":"2026-10-17T10:56:48.389167Z","end":"2026-10-17T10:56:48.623023Z","steps":["trace[1107013609] 'process raft request' (duration: 233.659012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:56:48.623185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.543119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c7ded259-4b48-4a43-87d4-d690bbb6572c\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-10-17T10:56:48.62322Z","caller":"traceutil/trace.go:171","msg":"trace[1544475492] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c7ded259-4b48-4a43-87d4-d690bbb6572c; range_end:; response_count:1; response_revision:1878; }","duration":"185.637772ms","start":"2026-10-17T10:56:48.43757Z","end":"2026-10-17T10:56:48.623208Z","steps":["trace[1544475492] 'agreement among raft nodes before linearized reading' (duration: 185.553179ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:21.36571Z","caller":"traceutil/trace.go:171","msg":"trace[1270115770] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"106.264878ms","start":"2026-10-17T10:57:21.259408Z","end":"2026-10-17T10:57:21.365673Z","steps":["trace[1270115770] 'process raft request' (duration: 40.727317ms)","trace[1270115770] 'compare' (duration: 65.371855ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:57:37.083726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.857785ms","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-17T10:57:37.083851Z","caller":"traceutil/trace.go:171","msg":"trace[1036926051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2189; }","duration":"123.073452ms","start":"2026-10-17T10:57:36.960755Z","end":"2026-10-17T10:57:37.083828Z","steps":["trace[1036926051] 'range keys from in-memory index tree' (duration: 122.634568ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:37.133976Z","caller":"traceutil/trace.go:171","msg":"trace[1864185829] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"108.337274ms","start":"2026-10-17T10:57:37.025611Z","end":"2026-10-17T10:57:37.133948Z","steps":["trace[1864185829] 'process raft request' (duration: 108.139478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:57:37.429846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.899586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T10:57:37.43003Z","caller":"traceutil/trace.go:171","msg":"trace[173683130] linearizableReadLoop","detail":"{readStateIndex:2280; appliedIndex:2278; }","duration":"123.648389ms","start":"2026-10-17T10:57:37.306363Z","end":"2026-10-17T10:57:37.430012Z","steps":["trace[173683130] 'read index received' (duration: 91.792µs)","trace[173683130] 'applied index is now lower than readState.Index' (duration: 123.555497ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:57:37.430112Z","caller":"traceutil/trace.go:171","msg":"trace[1494988019] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"341.36034ms","start":"2026-10-17T10:57:37.08874Z","end":"2026-10-17T10:57:37.4301Z","steps":["trace[1494988019] 'process raft request' (duration: 341.208765ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:37.430118Z","caller":"traceutil/trace.go:171","msg":"trace[415260156] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"394.733007ms","start":"2026-10-17T10:57:37.035348Z","end":"2026-10-17T10:57:37.430081Z","steps":["trace[415260156] 'process raft request' (duration: 195.47986ms)","trace[415260156] 'compare' (duration: 198.805444ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:57:37.430172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:57:37.088698Z","time spent":"341.442713ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:57:37.430244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.850336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-17T10:57:37.430289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:57:37.035335Z","time spent":"394.856602ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:57:37.430328Z","caller":"traceutil/trace.go:171","msg":"trace[2068236571] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2192; }","duration":"124.00558ms","start":"2026-10-17T10:57:37.306306Z","end":"2026-10-17T10:57:37.430311Z","steps":["trace[2068236571] 'agreement among raft nodes before linearized reading' (duration: 123.804894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:57:37.440578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.842117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-17T10:57:37.440636Z","caller":"traceutil/trace.go:171","msg":"trace[1367121275] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2193; }","duration":"124.940821ms","start":"2026-10-17T10:57:37.315681Z","end":"2026-10-17T10:57:37.440622Z","steps":["trace[1367121275] 'agreement among raft nodes before linearized reading' (duration: 124.747704ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:58:19.625306Z","caller":"traceutil/trace.go:171","msg":"trace[24742873] transaction","detail":"{read_only:false; response_revision:2593; number_of_response:1; }","duration":"189.65654ms","start":"2026-10-17T10:58:19.435613Z","end":"2026-10-17T10:58:19.625269Z","steps":["trace[24742873] 'process raft request' (duration: 189.436394ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:26.960031Z","caller":"traceutil/trace.go:171","msg":"trace[752771673] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"113.404714ms","start":"2026-10-17T11:03:26.846592Z","end":"2026-10-17T11:03:26.959996Z","steps":["trace[752771673] 'process raft request' (duration: 113.352892ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:26.960589Z","caller":"traceutil/trace.go:171","msg":"trace[148326488] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"116.531163ms","start":"2026-10-17T11:03:26.84401Z","end":"2026-10-17T11:03:26.960541Z","steps":["trace[148326488] 'process raft request' (duration: 115.636344ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:26.960607Z","caller":"traceutil/trace.go:171","msg":"trace[1386155050] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"116.493232ms","start":"2026-10-17T11:03:26.844084Z","end":"2026-10-17T11:03:26.960577Z","steps":["trace[1386155050] 'process raft request' (duration: 115.800801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:03:31.579642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.039277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:03:31.579737Z","caller":"traceutil/trace.go:171","msg":"trace[1419202885] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5183; }","duration":"177.14226ms","start":"2026-10-17T11:03:31.402558Z","end":"2026-10-17T11:03:31.5797Z","steps":["trace[1419202885] 'range keys from in-memory index tree' (duration: 177.002346ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:03:31.580641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.563436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bb456cdc-3e6e-42ea-b6bc-d49c2cb7ae9d\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-17T11:03:31.580765Z","caller":"traceutil/trace.go:171","msg":"trace[994406839] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bb456cdc-3e6e-42ea-b6bc-d49c2cb7ae9d; range_end:; response_count:1; response_revision:5183; }","duration":"123.758902ms","start":"2026-10-17T11:03:31.456981Z","end":"2026-10-17T11:03:31.58074Z","steps":["trace[994406839] 'range keys from in-memory index tree' (duration: 123.340269ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:31.736999Z","caller":"traceutil/trace.go:171","msg":"trace[1677081631] linearizableReadLoop","detail":"{readStateIndex:5384; appliedIndex:5383; }","duration":"152.080063ms","start":"2026-10-17T11:03:31.584891Z","end":"2026-10-17T11:03:31.736971Z","steps":["trace[1677081631] 'read index received' (duration: 151.856826ms)","trace[1677081631] 'applied index is now lower than readState.Index' (duration: 222.197µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:03:31.73708Z","caller":"traceutil/trace.go:171","msg":"trace[1605222160] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"153.127566ms","start":"2026-10-17T11:03:31.583917Z","end":"2026-10-17T11:03:31.737044Z","steps":["trace[1605222160] 'process raft request' (duration: 152.858377ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:03:31.737175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.254228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:03:31.73727Z","caller":"traceutil/trace.go:171","msg":"trace[1630590389] range","detail":"{range_begin:/registry/clusterroles/vector; range_end:; response_count:0; response_revision:5184; }","duration":"152.346951ms","start":"2026-10-17T11:03:31.584887Z","end":"2026-10-17T11:03:31.737234Z","steps":["trace[1630590389] 'agreement among raft nodes before linearized reading' (duration: 152.202056ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:03:31.816646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.653478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12429"} {"level":"warn","ts":"2026-10-17T11:03:31.816665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.678839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T11:03:31.816676Z","caller":"traceutil/trace.go:171","msg":"trace[756934051] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"231.666811ms","start":"2026-10-17T11:03:31.584972Z","end":"2026-10-17T11:03:31.816639Z","steps":["trace[756934051] 'process raft request' (duration: 231.32256ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:31.816744Z","caller":"traceutil/trace.go:171","msg":"trace[1552302668] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5185; }","duration":"100.771802ms","start":"2026-10-17T11:03:31.715935Z","end":"2026-10-17T11:03:31.816707Z","steps":["trace[1552302668] 'agreement among raft nodes before linearized reading' (duration: 100.548525ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:31.816835Z","caller":"traceutil/trace.go:171","msg":"trace[243265749] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:5185; }","duration":"117.899556ms","start":"2026-10-17T11:03:31.698889Z","end":"2026-10-17T11:03:31.816788Z","steps":["trace[243265749] 'agreement among raft nodes before linearized reading' (duration: 117.590166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:03:32.243313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.00898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:03:32.243419Z","caller":"traceutil/trace.go:171","msg":"trace[870831630] linearizableReadLoop","detail":"{readStateIndex:5389; appliedIndex:5388; }","duration":"292.602768ms","start":"2026-10-17T11:03:31.950798Z","end":"2026-10-17T11:03:32.243401Z","steps":["trace[870831630] 'read index received' (duration: 180.445204ms)","trace[870831630] 'applied index is now lower than readState.Index' (duration: 112.156164ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:03:32.243617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.817486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:6043"} {"level":"info","ts":"2026-10-17T11:03:32.243627Z","caller":"traceutil/trace.go:171","msg":"trace[445593057] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"301.47681ms","start":"2026-10-17T11:03:31.942118Z","end":"2026-10-17T11:03:32.243594Z","steps":["trace[445593057] 'process raft request' (duration: 189.10253ms)","trace[445593057] 'compare' (duration: 111.871664ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:03:32.243659Z","caller":"traceutil/trace.go:171","msg":"trace[1397238481] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5188; }","duration":"292.888087ms","start":"2026-10-17T11:03:31.950761Z","end":"2026-10-17T11:03:32.243649Z","steps":["trace[1397238481] 'agreement among raft nodes before linearized reading' (duration: 292.684631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:03:32.243774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:03:31.942098Z","time spent":"301.594344ms","remote":"127.0.0.1:54338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1956,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-17T11:03:33.676503Z","caller":"traceutil/trace.go:171","msg":"trace[1270513112] transaction","detail":"{read_only:false; response_revision:5230; number_of_response:1; }","duration":"126.733867ms","start":"2026-10-17T11:03:33.549741Z","end":"2026-10-17T11:03:33.676475Z","steps":["trace[1270513112] 'process raft request' (duration: 126.572672ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:33.676562Z","caller":"traceutil/trace.go:171","msg":"trace[966782006] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"127.361537ms","start":"2026-10-17T11:03:33.549156Z","end":"2026-10-17T11:03:33.676518Z","steps":["trace[966782006] 'process raft request' (duration: 46.27048ms)","trace[966782006] 'compare' (duration: 80.710895ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:03:33.6766Z","caller":"traceutil/trace.go:171","msg":"trace[712895458] transaction","detail":"{read_only:false; response_revision:5231; number_of_response:1; }","duration":"126.609813ms","start":"2026-10-17T11:03:33.549972Z","end":"2026-10-17T11:03:33.676582Z","steps":["trace[712895458] 'process raft request' (duration: 126.421807ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:33.676661Z","caller":"traceutil/trace.go:171","msg":"trace[275870041] transaction","detail":"{read_only:false; response_revision:5232; number_of_response:1; }","duration":"125.369784ms","start":"2026-10-17T11:03:33.551264Z","end":"2026-10-17T11:03:33.676634Z","steps":["trace[275870041] 'process raft request' (duration: 125.169337ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:46.08587Z","caller":"traceutil/trace.go:171","msg":"trace[999205502] transaction","detail":"{read_only:false; response_revision:5458; number_of_response:1; }","duration":"271.362783ms","start":"2026-10-17T11:03:45.814481Z","end":"2026-10-17T11:03:46.085844Z","steps":["trace[999205502] 'process raft request' (duration: 271.180248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:03:53.549277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.344233ms","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-17T11:03:53.549369Z","caller":"traceutil/trace.go:171","msg":"trace[573486356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5630; }","duration":"147.485397ms","start":"2026-10-17T11:03:53.401865Z","end":"2026-10-17T11:03:53.54935Z","steps":["trace[573486356] 'range keys from in-memory index tree' (duration: 147.124066ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:53.906078Z","caller":"traceutil/trace.go:171","msg":"trace[1936574628] transaction","detail":"{read_only:false; response_revision:5632; number_of_response:1; }","duration":"114.512728ms","start":"2026-10-17T11:03:53.791537Z","end":"2026-10-17T11:03:53.90605Z","steps":["trace[1936574628] 'process raft request' (duration: 114.368444ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:54.028602Z","caller":"traceutil/trace.go:171","msg":"trace[2074597911] transaction","detail":"{read_only:false; response_revision:5633; number_of_response:1; }","duration":"115.246482ms","start":"2026-10-17T11:03:53.913325Z","end":"2026-10-17T11:03:54.028572Z","steps":["trace[2074597911] 'process raft request' (duration: 115.051006ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:54.049927Z","caller":"traceutil/trace.go:171","msg":"trace[1766378468] transaction","detail":"{read_only:false; response_revision:5634; number_of_response:1; }","duration":"136.57042ms","start":"2026-10-17T11:03:53.913339Z","end":"2026-10-17T11:03:54.049909Z","steps":["trace[1766378468] 'process raft request' (duration: 136.23206ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:54.050011Z","caller":"traceutil/trace.go:171","msg":"trace[788943936] transaction","detail":"{read_only:false; response_revision:5638; number_of_response:1; }","duration":"134.784022ms","start":"2026-10-17T11:03:53.915198Z","end":"2026-10-17T11:03:54.049982Z","steps":["trace[788943936] 'process raft request' (duration: 134.607117ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:54.05021Z","caller":"traceutil/trace.go:171","msg":"trace[1115780847] transaction","detail":"{read_only:false; response_revision:5636; number_of_response:1; }","duration":"136.594661ms","start":"2026-10-17T11:03:53.91359Z","end":"2026-10-17T11:03:54.050184Z","steps":["trace[1115780847] 'process raft request' (duration: 136.116236ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:54.050284Z","caller":"traceutil/trace.go:171","msg":"trace[762913660] transaction","detail":"{read_only:false; response_revision:5635; number_of_response:1; }","duration":"136.798407ms","start":"2026-10-17T11:03:53.913459Z","end":"2026-10-17T11:03:54.050257Z","steps":["trace[762913660] 'process raft request' (duration: 136.204818ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:54.050343Z","caller":"traceutil/trace.go:171","msg":"trace[1570860488] transaction","detail":"{read_only:false; response_revision:5637; number_of_response:1; }","duration":"136.673423ms","start":"2026-10-17T11:03:53.913652Z","end":"2026-10-17T11:03:54.050325Z","steps":["trace[1570860488] 'process raft request' (duration: 136.116426ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:54.050609Z","caller":"traceutil/trace.go:171","msg":"trace[3475642] transaction","detail":"{read_only:false; response_revision:5639; number_of_response:1; }","duration":"133.953767ms","start":"2026-10-17T11:03:53.916638Z","end":"2026-10-17T11:03:54.050592Z","steps":["trace[3475642] 'process raft request' (duration: 133.203613ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:05.204212Z","caller":"traceutil/trace.go:171","msg":"trace[1517964624] transaction","detail":"{read_only:false; response_revision:5767; number_of_response:1; }","duration":"249.285631ms","start":"2026-10-17T11:04:04.954897Z","end":"2026-10-17T11:04:05.204182Z","steps":["trace[1517964624] 'process raft request' (duration: 178.675458ms)","trace[1517964624] 'compare' (duration: 70.48613ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:04:05.240211Z","caller":"traceutil/trace.go:171","msg":"trace[1024941428] transaction","detail":"{read_only:false; response_revision:5768; number_of_response:1; }","duration":"283.259211ms","start":"2026-10-17T11:04:04.956929Z","end":"2026-10-17T11:04:05.240188Z","steps":["trace[1024941428] 'process raft request' (duration: 283.088235ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:05.240457Z","caller":"traceutil/trace.go:171","msg":"trace[476670364] transaction","detail":"{read_only:false; response_revision:5769; number_of_response:1; }","duration":"283.355454ms","start":"2026-10-17T11:04:04.957092Z","end":"2026-10-17T11:04:05.240447Z","steps":["trace[476670364] 'process raft request' (duration: 283.057964ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:05.240844Z","caller":"traceutil/trace.go:171","msg":"trace[1585821189] transaction","detail":"{read_only:false; response_revision:5770; number_of_response:1; }","duration":"283.632793ms","start":"2026-10-17T11:04:04.957154Z","end":"2026-10-17T11:04:05.240787Z","steps":["trace[1585821189] 'process raft request' (duration: 283.2403ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:05.241099Z","caller":"traceutil/trace.go:171","msg":"trace[585908704] transaction","detail":"{read_only:false; response_revision:5772; number_of_response:1; }","duration":"233.655796ms","start":"2026-10-17T11:04:05.007425Z","end":"2026-10-17T11:04:05.24108Z","steps":["trace[585908704] 'process raft request' (duration: 233.47879ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:05.241079Z","caller":"traceutil/trace.go:171","msg":"trace[1193601285] transaction","detail":"{read_only:false; response_revision:5771; number_of_response:1; }","duration":"282.647122ms","start":"2026-10-17T11:04:04.958401Z","end":"2026-10-17T11:04:05.241048Z","steps":["trace[1193601285] 'process raft request' (duration: 282.259488ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:05.241111Z","caller":"traceutil/trace.go:171","msg":"trace[306308695] transaction","detail":"{read_only:false; response_revision:5773; number_of_response:1; }","duration":"231.441165ms","start":"2026-10-17T11:04:05.009651Z","end":"2026-10-17T11:04:05.241092Z","steps":["trace[306308695] 'process raft request' (duration: 231.336382ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:05.241188Z","caller":"traceutil/trace.go:171","msg":"trace[1428335726] transaction","detail":"{read_only:false; response_revision:5774; number_of_response:1; }","duration":"228.707138ms","start":"2026-10-17T11:04:05.012469Z","end":"2026-10-17T11:04:05.241176Z","steps":["trace[1428335726] 'process raft request' (duration: 228.569383ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:05.452192Z","caller":"traceutil/trace.go:171","msg":"trace[808083882] linearizableReadLoop","detail":"{readStateIndex:5991; appliedIndex:5990; }","duration":"110.390848ms","start":"2026-10-17T11:04:05.341778Z","end":"2026-10-17T11:04:05.452169Z","steps":["trace[808083882] 'read index received' (duration: 84.291469ms)","trace[808083882] 'applied index is now lower than readState.Index' (duration: 26.098399ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:04:05.452645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.855912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T11:04:05.452705Z","caller":"traceutil/trace.go:171","msg":"trace[2027466245] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:5775; }","duration":"111.008327ms","start":"2026-10-17T11:04:05.341679Z","end":"2026-10-17T11:04:05.452688Z","steps":["trace[2027466245] 'agreement among raft nodes before linearized reading' (duration: 110.877963ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:05.453213Z","caller":"traceutil/trace.go:171","msg":"trace[146349554] transaction","detail":"{read_only:false; response_revision:5775; number_of_response:1; }","duration":"162.926367ms","start":"2026-10-17T11:04:05.289445Z","end":"2026-10-17T11:04:05.452371Z","steps":["trace[146349554] 'process raft request' (duration: 136.721435ms)","trace[146349554] 'compare' (duration: 25.885522ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:04:27.424852Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2986} {"level":"info","ts":"2026-10-17T11:04:27.506322Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2986,"took":"80.004022ms","hash":2340111123,"current-db-size-bytes":56250368,"current-db-size":"56 MB","current-db-size-in-use-bytes":32296960,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T11:04:27.506381Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2340111123,"revision":2986,"compact-revision":-1} {"level":"info","ts":"2026-10-17T11:04:44.844842Z","caller":"traceutil/trace.go:171","msg":"trace[1167677712] transaction","detail":"{read_only:false; response_revision:6114; number_of_response:1; }","duration":"152.535987ms","start":"2026-10-17T11:04:44.692281Z","end":"2026-10-17T11:04:44.844817Z","steps":["trace[1167677712] 'process raft request' (duration: 152.353612ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:44.877434Z","caller":"traceutil/trace.go:171","msg":"trace[242462988] transaction","detail":"{read_only:false; response_revision:6115; number_of_response:1; }","duration":"126.742028ms","start":"2026-10-17T11:04:44.750666Z","end":"2026-10-17T11:04:44.877408Z","steps":["trace[242462988] 'process raft request' (duration: 126.559192ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:09:27.432534Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5921} {"level":"info","ts":"2026-10-17T11:09:27.530375Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5921,"took":"95.830637ms","hash":3146865961,"current-db-size-bytes":56250368,"current-db-size":"56 MB","current-db-size-in-use-bytes":25767936,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-17T11:09:27.530458Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3146865961,"revision":5921,"compact-revision":2986} {"level":"info","ts":"2026-10-17T11:09:51.753017Z","caller":"traceutil/trace.go:171","msg":"trace[1052630138] transaction","detail":"{read_only:false; response_revision:8406; number_of_response:1; }","duration":"227.546192ms","start":"2026-10-17T11:09:51.525445Z","end":"2026-10-17T11:09:51.752991Z","steps":["trace[1052630138] 'process raft request' (duration: 227.388777ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:02.058809Z","caller":"traceutil/trace.go:171","msg":"trace[878725562] transaction","detail":"{read_only:false; response_revision:8458; number_of_response:1; }","duration":"258.520584ms","start":"2026-10-17T11:10:01.80025Z","end":"2026-10-17T11:10:02.058771Z","steps":["trace[878725562] 'process raft request' (duration: 258.191554ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:02.126708Z","caller":"traceutil/trace.go:171","msg":"trace[1520468016] transaction","detail":"{read_only:false; response_revision:8459; number_of_response:1; }","duration":"102.411284ms","start":"2026-10-17T11:10:02.024233Z","end":"2026-10-17T11:10:02.126644Z","steps":["trace[1520468016] 'process raft request' (duration: 59.27235ms)","trace[1520468016] 'compare' (duration: 42.981239ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:10:02.886844Z","caller":"traceutil/trace.go:171","msg":"trace[321979527] transaction","detail":"{read_only:false; response_revision:8461; number_of_response:1; }","duration":"246.223416ms","start":"2026-10-17T11:10:02.640593Z","end":"2026-10-17T11:10:02.886816Z","steps":["trace[321979527] 'process raft request' (duration: 246.035201ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:02.923086Z","caller":"traceutil/trace.go:171","msg":"trace[2037758020] linearizableReadLoop","detail":"{readStateIndex:8766; appliedIndex:8764; }","duration":"184.244442ms","start":"2026-10-17T11:10:02.73881Z","end":"2026-10-17T11:10:02.923054Z","steps":["trace[2037758020] 'read index received' (duration: 147.899031ms)","trace[2037758020] 'applied index is now lower than readState.Index' (duration: 36.344401ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:10:02.92314Z","caller":"traceutil/trace.go:171","msg":"trace[64707141] transaction","detail":"{read_only:false; response_revision:8462; number_of_response:1; }","duration":"231.221096ms","start":"2026-10-17T11:10:02.691882Z","end":"2026-10-17T11:10:02.923103Z","steps":["trace[64707141] 'process raft request' (duration: 231.038721ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:02.923385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.551372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-17T11:10:02.923449Z","caller":"traceutil/trace.go:171","msg":"trace[997793693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8462; }","duration":"184.673387ms","start":"2026-10-17T11:10:02.73876Z","end":"2026-10-17T11:10:02.923433Z","steps":["trace[997793693] 'agreement among raft nodes before linearized reading' (duration: 184.459329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:02.923474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.108887ms","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-17T11:10:02.923576Z","caller":"traceutil/trace.go:171","msg":"trace[812907238] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8462; }","duration":"110.2305ms","start":"2026-10-17T11:10:02.813322Z","end":"2026-10-17T11:10:02.923553Z","steps":["trace[812907238] 'agreement among raft nodes before linearized reading' (duration: 109.968012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:02.92349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.824608ms","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-17T11:10:02.923724Z","caller":"traceutil/trace.go:171","msg":"trace[1302248778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8462; }","duration":"175.115967ms","start":"2026-10-17T11:10:02.748589Z","end":"2026-10-17T11:10:02.923705Z","steps":["trace[1302248778] 'agreement among raft nodes before linearized reading' (duration: 174.676953ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:05.057811Z","caller":"traceutil/trace.go:171","msg":"trace[411497215] transaction","detail":"{read_only:false; response_revision:8472; number_of_response:1; }","duration":"100.153183ms","start":"2026-10-17T11:10:04.957587Z","end":"2026-10-17T11:10:05.05774Z","steps":["trace[411497215] 'process raft request' (duration: 99.648277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:32.532539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.814381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-10-17T11:10:32.532577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.288743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:10:32.532634Z","caller":"traceutil/trace.go:171","msg":"trace[103833779] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8675; }","duration":"112.985126ms","start":"2026-10-17T11:10:32.419626Z","end":"2026-10-17T11:10:32.532611Z","steps":["trace[103833779] 'range keys from in-memory index tree' (duration: 112.643356ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:32.53265Z","caller":"traceutil/trace.go:171","msg":"trace[858049296] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:8675; }","duration":"190.423406ms","start":"2026-10-17T11:10:32.342206Z","end":"2026-10-17T11:10:32.53263Z","steps":["trace[858049296] 'count revisions from in-memory index tree' (duration: 190.195509ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:45.946765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.623133ms","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-17T11:10:45.946827Z","caller":"traceutil/trace.go:171","msg":"trace[1291035726] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:8788; }","duration":"140.724676ms","start":"2026-10-17T11:10:45.80609Z","end":"2026-10-17T11:10:45.946815Z","steps":["trace[1291035726] 'range keys from in-memory index tree' (duration: 140.441458ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:46.492474Z","caller":"traceutil/trace.go:171","msg":"trace[54268496] transaction","detail":"{read_only:false; response_revision:8789; number_of_response:1; }","duration":"152.6453ms","start":"2026-10-17T11:10:46.339803Z","end":"2026-10-17T11:10:46.492448Z","steps":["trace[54268496] 'process raft request' (duration: 152.484945ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:46.659217Z","caller":"traceutil/trace.go:171","msg":"trace[1553196773] transaction","detail":"{read_only:false; response_revision:8791; number_of_response:1; }","duration":"141.013656ms","start":"2026-10-17T11:10:46.51817Z","end":"2026-10-17T11:10:46.659184Z","steps":["trace[1553196773] 'process raft request' (duration: 43.363241ms)","trace[1553196773] 'compare' (duration: 97.280704ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:10:46.851814Z","caller":"traceutil/trace.go:171","msg":"trace[294747475] transaction","detail":"{read_only:false; response_revision:8792; number_of_response:1; }","duration":"187.753563ms","start":"2026-10-17T11:10:46.664032Z","end":"2026-10-17T11:10:46.851786Z","steps":["trace[294747475] 'process raft request' (duration: 176.914793ms)","trace[294747475] 'compare' (duration: 10.339804ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:10:46.937151Z","caller":"traceutil/trace.go:171","msg":"trace[1401646143] linearizableReadLoop","detail":"{readStateIndex:9110; appliedIndex:9108; }","duration":"195.916189ms","start":"2026-10-17T11:10:46.741215Z","end":"2026-10-17T11:10:46.937131Z","steps":["trace[1401646143] 'read index received' (duration: 99.704019ms)","trace[1401646143] 'applied index is now lower than readState.Index' (duration: 96.21134ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:10:46.937215Z","caller":"traceutil/trace.go:171","msg":"trace[111788515] transaction","detail":"{read_only:false; response_revision:8793; number_of_response:1; }","duration":"227.012675ms","start":"2026-10-17T11:10:46.710178Z","end":"2026-10-17T11:10:46.937191Z","steps":["trace[111788515] 'process raft request' (duration: 226.825079ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:46.9373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.008985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:10:46.937333Z","caller":"traceutil/trace.go:171","msg":"trace[177334610] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:8793; }","duration":"133.121239ms","start":"2026-10-17T11:10:46.804201Z","end":"2026-10-17T11:10:46.937323Z","steps":["trace[177334610] 'agreement among raft nodes before linearized reading' (duration: 133.032816ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:46.937408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.169486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12429"} {"level":"info","ts":"2026-10-17T11:10:46.937507Z","caller":"traceutil/trace.go:171","msg":"trace[1848715573] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8793; }","duration":"196.316272ms","start":"2026-10-17T11:10:46.741166Z","end":"2026-10-17T11:10:46.937482Z","steps":["trace[1848715573] 'agreement among raft nodes before linearized reading' (duration: 196.065684ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:11:05.916441Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000244d.wal"} {"level":"info","ts":"2026-10-17T11:12:07.206547Z","caller":"traceutil/trace.go:171","msg":"trace[215331032] transaction","detail":"{read_only:false; response_revision:9499; number_of_response:1; }","duration":"113.43525ms","start":"2026-10-17T11:12:07.093088Z","end":"2026-10-17T11:12:07.206523Z","steps":["trace[215331032] 'process raft request' (duration: 113.274465ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:12:19.443102Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"53de2bdd9aa7b793","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:12:19.450071Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-17T11:12:19.450186Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-17T11:12:41.150513Z","caller":"traceutil/trace.go:171","msg":"trace[430680063] transaction","detail":"{read_only:false; response_revision:9818; number_of_response:1; }","duration":"104.281879ms","start":"2026-10-17T11:12:41.046204Z","end":"2026-10-17T11:12:41.150486Z","steps":["trace[430680063] 'process raft request' (duration: 104.137224ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:14:27.439682Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8098} {"level":"info","ts":"2026-10-17T11:14:27.506093Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8098,"took":"64.462093ms","hash":1980525442,"current-db-size-bytes":56250368,"current-db-size":"56 MB","current-db-size-in-use-bytes":29409280,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-17T11:14:27.506159Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1980525442,"revision":8098,"compact-revision":5921} {"level":"info","ts":"2026-10-17T11:15:47.289423Z","caller":"traceutil/trace.go:171","msg":"trace[1148033906] transaction","detail":"{read_only:false; response_revision:11420; number_of_response:1; }","duration":"191.962534ms","start":"2026-10-17T11:15:47.097433Z","end":"2026-10-17T11:15:47.289396Z","steps":["trace[1148033906] 'process raft request' (duration: 191.81237ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:15:47.389363Z","caller":"traceutil/trace.go:171","msg":"trace[1458020738] linearizableReadLoop","detail":"{readStateIndex:11816; appliedIndex:11814; }","duration":"286.076812ms","start":"2026-10-17T11:15:47.103236Z","end":"2026-10-17T11:15:47.389312Z","steps":["trace[1458020738] 'read index received' (duration: 185.981364ms)","trace[1458020738] 'applied index is now lower than readState.Index' (duration: 100.094028ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:15:47.389411Z","caller":"traceutil/trace.go:171","msg":"trace[434413042] transaction","detail":"{read_only:false; response_revision:11421; number_of_response:1; }","duration":"287.846129ms","start":"2026-10-17T11:15:47.101532Z","end":"2026-10-17T11:15:47.389378Z","steps":["trace[434413042] 'process raft request' (duration: 210.63821ms)","trace[434413042] 'compare' (duration: 77.003093ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:15:47.389613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.349142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-17T11:15:47.389722Z","caller":"traceutil/trace.go:171","msg":"trace[1437056497] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11421; }","duration":"286.503746ms","start":"2026-10-17T11:15:47.103197Z","end":"2026-10-17T11:15:47.3897Z","steps":["trace[1437056497] 'agreement among raft nodes before linearized reading' (duration: 286.249178ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:15:47.389827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.33721ms","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-17T11:15:47.390025Z","caller":"traceutil/trace.go:171","msg":"trace[2119746780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11421; }","duration":"205.593428ms","start":"2026-10-17T11:15:47.18441Z","end":"2026-10-17T11:15:47.390004Z","steps":["trace[2119746780] 'agreement among raft nodes before linearized reading' (duration: 205.112613ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:15:47.633894Z","caller":"traceutil/trace.go:171","msg":"trace[1173475651] transaction","detail":"{read_only:false; response_revision:11423; number_of_response:1; }","duration":"239.535331ms","start":"2026-10-17T11:15:47.394333Z","end":"2026-10-17T11:15:47.633868Z","steps":["trace[1173475651] 'process raft request' (duration: 239.49082ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:15:47.634017Z","caller":"traceutil/trace.go:171","msg":"trace[765795001] transaction","detail":"{read_only:false; response_revision:11422; number_of_response:1; }","duration":"240.315596ms","start":"2026-10-17T11:15:47.393664Z","end":"2026-10-17T11:15:47.633979Z","steps":["trace[765795001] 'process raft request' (duration: 141.89511ms)","trace[765795001] 'compare' (duration: 98.119616ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:19:27.446808Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10654} {"level":"info","ts":"2026-10-17T11:19:27.537318Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10654,"took":"87.618489ms","hash":3183886942,"current-db-size-bytes":56250368,"current-db-size":"56 MB","current-db-size-in-use-bytes":27385856,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-17T11:19:27.53739Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3183886942,"revision":10654,"compact-revision":8098} {"level":"info","ts":"2026-10-17T11:21:35.625746Z","caller":"traceutil/trace.go:171","msg":"trace[83028483] linearizableReadLoop","detail":"{readStateIndex:14276; appliedIndex:14275; }","duration":"171.179998ms","start":"2026-10-17T11:21:35.454544Z","end":"2026-10-17T11:21:35.625724Z","steps":["trace[83028483] 'read index received' (duration: 170.971222ms)","trace[83028483] 'applied index is now lower than readState.Index' (duration: 207.846µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:21:35.625961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.396335ms","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-17T11:21:35.626Z","caller":"traceutil/trace.go:171","msg":"trace[148365378] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13799; }","duration":"171.452967ms","start":"2026-10-17T11:21:35.454536Z","end":"2026-10-17T11:21:35.625989Z","steps":["trace[148365378] 'agreement among raft nodes before linearized reading' (duration: 171.285792ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:21:35.625978Z","caller":"traceutil/trace.go:171","msg":"trace[1472426700] transaction","detail":"{read_only:false; response_revision:13799; number_of_response:1; }","duration":"197.071153ms","start":"2026-10-17T11:21:35.428872Z","end":"2026-10-17T11:21:35.625943Z","steps":["trace[1472426700] 'process raft request' (duration: 196.701322ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:21:38.609034Z","caller":"traceutil/trace.go:171","msg":"trace[1251471466] transaction","detail":"{read_only:false; response_revision:13815; number_of_response:1; }","duration":"116.765427ms","start":"2026-10-17T11:21:38.492243Z","end":"2026-10-17T11:21:38.609009Z","steps":["trace[1251471466] 'process raft request' (duration: 116.585921ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:21:59.6545Z","caller":"traceutil/trace.go:171","msg":"trace[18915512] transaction","detail":"{read_only:false; response_revision:13927; number_of_response:1; }","duration":"411.718663ms","start":"2026-10-17T11:21:59.242761Z","end":"2026-10-17T11:21:59.65448Z","steps":["trace[18915512] 'process raft request' (duration: 409.921497ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:21:59.654651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:21:59.242743Z","time spent":"411.836117ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:21:59.654995Z","caller":"traceutil/trace.go:171","msg":"trace[1094940050] transaction","detail":"{read_only:false; response_revision:13928; number_of_response:1; }","duration":"358.769258ms","start":"2026-10-17T11:21:59.296201Z","end":"2026-10-17T11:21:59.65497Z","steps":["trace[1094940050] 'process raft request' (duration: 358.630313ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:21:59.655148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:21:59.296174Z","time spent":"358.900332ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:22:06.525429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.026273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:22:06.52573Z","caller":"traceutil/trace.go:171","msg":"trace[1870799736] transaction","detail":"{read_only:false; response_revision:13963; number_of_response:1; }","duration":"137.875168ms","start":"2026-10-17T11:22:06.387828Z","end":"2026-10-17T11:22:06.525703Z","steps":["trace[1870799736] 'process raft request' (duration: 32.490963ms)","trace[1870799736] 'compare' (duration: 104.891679ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:22:06.894393Z","caller":"traceutil/trace.go:171","msg":"trace[1506165196] transaction","detail":"{read_only:false; response_revision:13965; number_of_response:1; }","duration":"127.595021ms","start":"2026-10-17T11:22:06.766774Z","end":"2026-10-17T11:22:06.894369Z","steps":["trace[1506165196] 'process raft request' (duration: 127.412785ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:15.527619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.575728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:22:15.527729Z","caller":"traceutil/trace.go:171","msg":"trace[1944244185] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:14023; }","duration":"142.741693ms","start":"2026-10-17T11:22:15.384961Z","end":"2026-10-17T11:22:15.527703Z","steps":["trace[1944244185] 'count revisions from in-memory index tree' (duration: 142.501846ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:22:15.672489Z","caller":"traceutil/trace.go:171","msg":"trace[266285012] transaction","detail":"{read_only:false; response_revision:14024; number_of_response:1; }","duration":"100.021114ms","start":"2026-10-17T11:22:15.572446Z","end":"2026-10-17T11:22:15.672467Z","steps":["trace[266285012] 'process raft request' (duration: 99.870469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:16.406677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.276836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:22:16.40685Z","caller":"traceutil/trace.go:171","msg":"trace[1355484263] transaction","detail":"{read_only:false; response_revision:14030; number_of_response:1; }","duration":"374.379155ms","start":"2026-10-17T11:22:16.032425Z","end":"2026-10-17T11:22:16.406804Z","steps":["trace[1355484263] 'process raft request' (duration: 101.849061ms)","trace[1355484263] 'compare' (duration: 272.07204ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:22:16.407006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:22:16.032409Z","time spent":"374.515559ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:22:17.718702Z","caller":"traceutil/trace.go:171","msg":"trace[1063255798] transaction","detail":"{read_only:false; response_revision:14035; number_of_response:1; }","duration":"131.899048ms","start":"2026-10-17T11:22:17.586772Z","end":"2026-10-17T11:22:17.718671Z","steps":["trace[1063255798] 'process raft request' (duration: 131.668191ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:22:17.762567Z","caller":"traceutil/trace.go:171","msg":"trace[1694930195] transaction","detail":"{read_only:false; response_revision:14036; number_of_response:1; }","duration":"163.968678ms","start":"2026-10-17T11:22:17.598543Z","end":"2026-10-17T11:22:17.762511Z","steps":["trace[1694930195] 'process raft request' (duration: 163.782512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:38.988775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.508355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5abe4c35-a563-44f4-8bc5-a832eeb36794\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-10-17T11:22:38.98891Z","caller":"traceutil/trace.go:171","msg":"trace[1037828200] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5abe4c35-a563-44f4-8bc5-a832eeb36794; range_end:; response_count:1; response_revision:14342; }","duration":"103.716841ms","start":"2026-10-17T11:22:38.88517Z","end":"2026-10-17T11:22:38.988886Z","steps":["trace[1037828200] 'range keys from in-memory index tree' (duration: 103.330609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:38.988839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.096998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5abe4c35-a563-44f4-8bc5-a832eeb36794\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-10-17T11:22:38.989073Z","caller":"traceutil/trace.go:171","msg":"trace[944214563] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5abe4c35-a563-44f4-8bc5-a832eeb36794; range_end:; response_count:1; response_revision:14342; }","duration":"118.356146ms","start":"2026-10-17T11:22:38.870696Z","end":"2026-10-17T11:22:38.989052Z","steps":["trace[944214563] 'range keys from in-memory index tree' (duration: 117.954795ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:22:39.383909Z","caller":"traceutil/trace.go:171","msg":"trace[1736208677] transaction","detail":"{read_only:false; response_revision:14343; number_of_response:1; }","duration":"386.518851ms","start":"2026-10-17T11:22:38.997363Z","end":"2026-10-17T11:22:39.383882Z","steps":["trace[1736208677] 'process raft request' (duration: 386.338206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:39.384058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:22:38.997342Z","time spent":"386.637125ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:22:39.384138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.509251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-17T11:22:39.384217Z","caller":"traceutil/trace.go:171","msg":"trace[1033176368] linearizableReadLoop","detail":"{readStateIndex:14844; appliedIndex:14843; }","duration":"315.595983ms","start":"2026-10-17T11:22:39.068609Z","end":"2026-10-17T11:22:39.384205Z","steps":["trace[1033176368] 'read index received' (duration: 315.036056ms)","trace[1033176368] 'applied index is now lower than readState.Index' (duration: 205.106µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:22:39.384219Z","caller":"traceutil/trace.go:171","msg":"trace[801799553] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14343; }","duration":"315.633024ms","start":"2026-10-17T11:22:39.068565Z","end":"2026-10-17T11:22:39.384198Z","steps":["trace[801799553] 'agreement among raft nodes before linearized reading' (duration: 315.420788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:39.384272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:22:39.068554Z","time spent":"315.706547ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-17T11:22:39.384454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.242793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xff29\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-17T11:22:39.384491Z","caller":"traceutil/trace.go:171","msg":"trace[644223328] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xff29; range_end:; response_count:1; response_revision:14343; }","duration":"118.316415ms","start":"2026-10-17T11:22:39.266166Z","end":"2026-10-17T11:22:39.384482Z","steps":["trace[644223328] 'agreement among raft nodes before linearized reading' (duration: 118.127339ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:22:39.675479Z","caller":"traceutil/trace.go:171","msg":"trace[744896285] linearizableReadLoop","detail":"{readStateIndex:14845; appliedIndex:14844; }","duration":"284.603237ms","start":"2026-10-17T11:22:39.39085Z","end":"2026-10-17T11:22:39.675453Z","steps":["trace[744896285] 'read index received' (duration: 265.934723ms)","trace[744896285] 'applied index is now lower than readState.Index' (duration: 18.667534ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:22:39.675572Z","caller":"traceutil/trace.go:171","msg":"trace[1520155347] transaction","detail":"{read_only:false; response_revision:14344; number_of_response:1; }","duration":"285.718693ms","start":"2026-10-17T11:22:39.389811Z","end":"2026-10-17T11:22:39.675529Z","steps":["trace[1520155347] 'process raft request' (duration: 266.952796ms)","trace[1520155347] 'compare' (duration: 18.470738ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:22:39.676028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.019604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-17T11:22:39.676113Z","caller":"traceutil/trace.go:171","msg":"trace[310787217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14344; }","duration":"188.164748ms","start":"2026-10-17T11:22:39.48793Z","end":"2026-10-17T11:22:39.676095Z","steps":["trace[310787217] 'agreement among raft nodes before linearized reading' (duration: 187.894709ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:39.676522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.646101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:66 size:633153"} {"level":"info","ts":"2026-10-17T11:22:39.676642Z","caller":"traceutil/trace.go:171","msg":"trace[1888862501] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:66; response_revision:14344; }","duration":"285.804405ms","start":"2026-10-17T11:22:39.390817Z","end":"2026-10-17T11:22:39.676622Z","steps":["trace[1888862501] 'agreement among raft nodes before linearized reading' (duration: 284.824174ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:23:40.229742Z","caller":"traceutil/trace.go:171","msg":"trace[1052419829] transaction","detail":"{read_only:false; response_revision:15013; number_of_response:1; }","duration":"191.207016ms","start":"2026-10-17T11:23:40.038491Z","end":"2026-10-17T11:23:40.229698Z","steps":["trace[1052419829] 'process raft request' (duration: 164.505955ms)","trace[1052419829] 'compare' (duration: 26.343239ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:23:40.229855Z","caller":"traceutil/trace.go:171","msg":"trace[174048317] linearizableReadLoop","detail":"{readStateIndex:15530; appliedIndex:15529; }","duration":"182.211339ms","start":"2026-10-17T11:23:40.04762Z","end":"2026-10-17T11:23:40.229832Z","steps":["trace[174048317] 'read index received' (duration: 57.413096ms)","trace[174048317] 'applied index is now lower than readState.Index' (duration: 124.795063ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:23:40.230084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.455457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12429"} {"level":"info","ts":"2026-10-17T11:23:40.230169Z","caller":"traceutil/trace.go:171","msg":"trace[1927032193] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15014; }","duration":"182.619612ms","start":"2026-10-17T11:23:40.047531Z","end":"2026-10-17T11:23:40.230151Z","steps":["trace[1927032193] 'agreement among raft nodes before linearized reading' (duration: 182.398555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:23:40.255654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.217924ms","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-17T11:23:40.255738Z","caller":"traceutil/trace.go:171","msg":"trace[1708835347] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15015; }","duration":"201.345858ms","start":"2026-10-17T11:23:40.054376Z","end":"2026-10-17T11:23:40.255722Z","steps":["trace[1708835347] 'agreement among raft nodes before linearized reading' (duration: 201.144682ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:23:40.255657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.217074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T11:23:40.255849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.592746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-10-17T11:23:40.255835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.088508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-17T11:23:40.255852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.433751ms","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-17T11:23:40.255928Z","caller":"traceutil/trace.go:171","msg":"trace[1457967399] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15015; }","duration":"201.711879ms","start":"2026-10-17T11:23:40.054197Z","end":"2026-10-17T11:23:40.255909Z","steps":["trace[1457967399] 'agreement among raft nodes before linearized reading' (duration: 201.491592ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:23:40.255948Z","caller":"traceutil/trace.go:171","msg":"trace[985232884] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15015; }","duration":"200.307235ms","start":"2026-10-17T11:23:40.055617Z","end":"2026-10-17T11:23:40.255925Z","steps":["trace[985232884] 'agreement among raft nodes before linearized reading' (duration: 199.957614ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:23:40.255966Z","caller":"traceutil/trace.go:171","msg":"trace[338271205] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15015; }","duration":"201.588305ms","start":"2026-10-17T11:23:40.054362Z","end":"2026-10-17T11:23:40.25595Z","steps":["trace[338271205] 'agreement among raft nodes before linearized reading' (duration: 201.369339ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:23:40.255933Z","caller":"traceutil/trace.go:171","msg":"trace[570983266] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15015; }","duration":"201.528574ms","start":"2026-10-17T11:23:40.054374Z","end":"2026-10-17T11:23:40.255903Z","steps":["trace[570983266] 'agreement among raft nodes before linearized reading' (duration: 201.139202ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:23:40.255834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.589884ms","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-17T11:23:40.256261Z","caller":"traceutil/trace.go:171","msg":"trace[2030010284] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15015; }","duration":"201.052298ms","start":"2026-10-17T11:23:40.055192Z","end":"2026-10-17T11:23:40.256245Z","steps":["trace[2030010284] 'agreement among raft nodes before linearized reading' (duration: 200.459409ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:23:41.10398Z","caller":"traceutil/trace.go:171","msg":"trace[480315333] linearizableReadLoop","detail":"{readStateIndex:15536; appliedIndex:15535; }","duration":"164.94488ms","start":"2026-10-17T11:23:40.939007Z","end":"2026-10-17T11:23:41.103951Z","steps":["trace[480315333] 'read index received' (duration: 164.758254ms)","trace[480315333] 'applied index is now lower than readState.Index' (duration: 184.946µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:23:41.104233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.212569ms","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-17T11:23:41.104265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.613013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:23:41.104314Z","caller":"traceutil/trace.go:171","msg":"trace[1380705827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15018; }","duration":"165.342423ms","start":"2026-10-17T11:23:40.938952Z","end":"2026-10-17T11:23:41.104295Z","steps":["trace[1380705827] 'agreement among raft nodes before linearized reading' (duration: 165.098665ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:23:41.10435Z","caller":"traceutil/trace.go:171","msg":"trace[1559460099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15018; }","duration":"128.790569ms","start":"2026-10-17T11:23:40.975514Z","end":"2026-10-17T11:23:41.104304Z","steps":["trace[1559460099] 'agreement among raft nodes before linearized reading' (duration: 128.660235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:23:41.104453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.494359ms","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-17T11:23:41.104647Z","caller":"traceutil/trace.go:171","msg":"trace[2128840066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15018; }","duration":"128.740178ms","start":"2026-10-17T11:23:40.975884Z","end":"2026-10-17T11:23:41.104625Z","steps":["trace[2128840066] 'agreement among raft nodes before linearized reading' (duration: 128.273433ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:27.453524Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12822} {"level":"info","ts":"2026-10-17T11:24:27.536082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12822,"took":"79.877622ms","hash":2521554111,"current-db-size-bytes":56250368,"current-db-size":"56 MB","current-db-size-in-use-bytes":30253056,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-17T11:24:27.536148Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2521554111,"revision":12822,"compact-revision":10654} {"level":"info","ts":"2026-10-17T11:29:27.460717Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15262} {"level":"info","ts":"2026-10-17T11:29:27.567761Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15262,"took":"104.662902ms","hash":176499271,"current-db-size-bytes":56250368,"current-db-size":"56 MB","current-db-size-in-use-bytes":31416320,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T11:29:27.56786Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":176499271,"revision":15262,"compact-revision":12822} {"level":"warn","ts":"2026-10-17T11:29:44.189561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.655903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-17T11:29:44.189641Z","caller":"traceutil/trace.go:171","msg":"trace[1232392605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17701; }","duration":"202.754746ms","start":"2026-10-17T11:29:43.98687Z","end":"2026-10-17T11:29:44.189625Z","steps":["trace[1232392605] 'range keys from in-memory index tree' (duration: 202.504828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:29:44.190002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.040739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-ddbkk\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-10-17T11:29:44.190071Z","caller":"traceutil/trace.go:171","msg":"trace[2068092679] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-ddbkk; range_end:; response_count:1; response_revision:17701; }","duration":"148.141512ms","start":"2026-10-17T11:29:44.041914Z","end":"2026-10-17T11:29:44.190056Z","steps":["trace[2068092679] 'range keys from in-memory index tree' (duration: 147.73068ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:29:50.03921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.294608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:29:50.039482Z","caller":"traceutil/trace.go:171","msg":"trace[145179655] transaction","detail":"{read_only:false; response_revision:17733; number_of_response:1; }","duration":"184.490015ms","start":"2026-10-17T11:29:49.854972Z","end":"2026-10-17T11:29:50.039462Z","steps":["trace[145179655] 'process raft request' (duration: 184.374172ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:29:50.039551Z","caller":"traceutil/trace.go:171","msg":"trace[161500108] transaction","detail":"{read_only:false; response_revision:17732; number_of_response:1; }","duration":"403.004142ms","start":"2026-10-17T11:29:49.636438Z","end":"2026-10-17T11:29:50.039442Z","steps":["trace[161500108] 'process raft request' (duration: 29.363073ms)","trace[161500108] 'compare' (duration: 372.982928ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:29:50.039618Z","caller":"traceutil/trace.go:171","msg":"trace[163426038] linearizableReadLoop","detail":"{readStateIndex:18339; appliedIndex:18338; }","duration":"343.203984ms","start":"2026-10-17T11:29:49.696373Z","end":"2026-10-17T11:29:50.039577Z","steps":["trace[163426038] 'read index received' (duration: 52.923µs)","trace[163426038] 'applied index is now lower than readState.Index' (duration: 343.145871ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:29:50.04009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.700709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-qgzlf\" ","response":"range_response_count:1 size:20910"} {"level":"info","ts":"2026-10-17T11:29:50.040137Z","caller":"traceutil/trace.go:171","msg":"trace[186336744] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-qgzlf; range_end:; response_count:1; response_revision:17733; }","duration":"343.800692ms","start":"2026-10-17T11:29:49.696322Z","end":"2026-10-17T11:29:50.040122Z","steps":["trace[186336744] 'agreement among raft nodes before linearized reading' (duration: 343.430531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:29:50.040169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:29:49.696301Z","time spent":"343.861055ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20932,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-qgzlf\" "} {"level":"warn","ts":"2026-10-17T11:29:50.04064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:29:49.636408Z","time spent":"403.275469ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:29:50.300959Z","caller":"traceutil/trace.go:171","msg":"trace[335827715] transaction","detail":"{read_only:false; response_revision:17734; number_of_response:1; }","duration":"238.509801ms","start":"2026-10-17T11:29:50.062403Z","end":"2026-10-17T11:29:50.300913Z","steps":["trace[335827715] 'process raft request' (duration: 238.212031ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:33:01.419096Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"53de2bdd9aa7b793","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:33:01.425308Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-17T11:33:01.425476Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-17T11:34:27.470605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17582} {"level":"info","ts":"2026-10-17T11:34:27.564456Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17582,"took":"91.437766ms","hash":3656859737,"current-db-size-bytes":56250368,"current-db-size":"56 MB","current-db-size-in-use-bytes":34172928,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-17T11:34:27.564572Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3656859737,"revision":17582,"compact-revision":15262} {"level":"info","ts":"2026-10-17T11:34:58.739201Z","caller":"traceutil/trace.go:171","msg":"trace[926793245] transaction","detail":"{read_only:false; response_revision:20187; number_of_response:1; }","duration":"164.058334ms","start":"2026-10-17T11:34:58.575091Z","end":"2026-10-17T11:34:58.73915Z","steps":["trace[926793245] 'process raft request' (duration: 163.892469ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:34:58.966509Z","caller":"traceutil/trace.go:171","msg":"trace[1899277240] transaction","detail":"{read_only:false; response_revision:20188; number_of_response:1; }","duration":"131.570051ms","start":"2026-10-17T11:34:58.834912Z","end":"2026-10-17T11:34:58.966482Z","steps":["trace[1899277240] 'process raft request' (duration: 103.149828ms)","trace[1899277240] 'compare' (duration: 27.895216ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:34:59.108505Z","caller":"traceutil/trace.go:171","msg":"trace[401974921] linearizableReadLoop","detail":"{readStateIndex:20877; appliedIndex:20876; }","duration":"133.281466ms","start":"2026-10-17T11:34:58.975204Z","end":"2026-10-17T11:34:59.108485Z","steps":["trace[401974921] 'read index received' (duration: 131.301283ms)","trace[401974921] 'applied index is now lower than readState.Index' (duration: 1.979383ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:34:59.108634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.426751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:34:59.108611Z","caller":"traceutil/trace.go:171","msg":"trace[1699682541] transaction","detail":"{read_only:false; response_revision:20189; number_of_response:1; }","duration":"136.097385ms","start":"2026-10-17T11:34:58.972465Z","end":"2026-10-17T11:34:59.108562Z","steps":["trace[1699682541] 'process raft request' (duration: 134.107952ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:34:59.108659Z","caller":"traceutil/trace.go:171","msg":"trace[977841295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20189; }","duration":"133.529273ms","start":"2026-10-17T11:34:58.975123Z","end":"2026-10-17T11:34:59.108652Z","steps":["trace[977841295] 'agreement among raft nodes before linearized reading' (duration: 133.43844ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:35:03.472378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.783484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:35:03.472493Z","caller":"traceutil/trace.go:171","msg":"trace[107128628] transaction","detail":"{read_only:false; response_revision:20227; number_of_response:1; }","duration":"213.214496ms","start":"2026-10-17T11:35:03.25926Z","end":"2026-10-17T11:35:03.472474Z","steps":["trace[107128628] 'process raft request' (duration: 61.227846ms)","trace[107128628] 'compare' (duration: 151.67314ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:35:03.488822Z","caller":"traceutil/trace.go:171","msg":"trace[1582125363] transaction","detail":"{read_only:false; response_revision:20228; number_of_response:1; }","duration":"208.96629ms","start":"2026-10-17T11:35:03.279792Z","end":"2026-10-17T11:35:03.488758Z","steps":["trace[1582125363] 'process raft request' (duration: 208.795525ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:35:04.1767Z","caller":"traceutil/trace.go:171","msg":"trace[1791832382] transaction","detail":"{read_only:false; response_revision:20229; number_of_response:1; }","duration":"163.470345ms","start":"2026-10-17T11:35:04.013203Z","end":"2026-10-17T11:35:04.176673Z","steps":["trace[1791832382] 'process raft request' (duration: 163.303179ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:35:04.178139Z","caller":"traceutil/trace.go:171","msg":"trace[1550888712] linearizableReadLoop","detail":"{readStateIndex:20919; appliedIndex:20917; }","duration":"106.883756ms","start":"2026-10-17T11:35:04.071237Z","end":"2026-10-17T11:35:04.178121Z","steps":["trace[1550888712] 'read index received' (duration: 105.276436ms)","trace[1550888712] 'applied index is now lower than readState.Index' (duration: 1.60575ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:35:04.178258Z","caller":"traceutil/trace.go:171","msg":"trace[873836104] transaction","detail":"{read_only:false; response_revision:20230; number_of_response:1; }","duration":"163.315249ms","start":"2026-10-17T11:35:04.014909Z","end":"2026-10-17T11:35:04.178224Z","steps":["trace[873836104] 'process raft request' (duration: 163.118763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:35:04.178366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.093413ms","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-17T11:35:04.17853Z","caller":"traceutil/trace.go:171","msg":"trace[437124244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20230; }","duration":"107.335422ms","start":"2026-10-17T11:35:04.071184Z","end":"2026-10-17T11:35:04.178519Z","steps":["trace[437124244] 'agreement among raft nodes before linearized reading' (duration: 107.039191ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:35:04.345476Z","caller":"traceutil/trace.go:171","msg":"trace[98685403] transaction","detail":"{read_only:false; response_revision:20231; number_of_response:1; }","duration":"162.568836ms","start":"2026-10-17T11:35:04.18288Z","end":"2026-10-17T11:35:04.345448Z","steps":["trace[98685403] 'process raft request' (duration: 149.889943ms)","trace[98685403] 'compare' (duration: 12.466106ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:35:04.413853Z","caller":"traceutil/trace.go:171","msg":"trace[1645060294] transaction","detail":"{read_only:false; response_revision:20232; number_of_response:1; }","duration":"230.538967ms","start":"2026-10-17T11:35:04.183289Z","end":"2026-10-17T11:35:04.413828Z","steps":["trace[1645060294] 'process raft request' (duration: 230.410422ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:35:04.877916Z","caller":"traceutil/trace.go:171","msg":"trace[385867714] transaction","detail":"{read_only:false; response_revision:20233; number_of_response:1; }","duration":"102.081644ms","start":"2026-10-17T11:35:04.775816Z","end":"2026-10-17T11:35:04.877898Z","steps":["trace[385867714] 'process raft request' (duration: 101.95812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:39:06.69568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.653991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:39:06.696105Z","caller":"traceutil/trace.go:171","msg":"trace[707066699] transaction","detail":"{read_only:false; response_revision:22717; number_of_response:1; }","duration":"183.065318ms","start":"2026-10-17T11:39:06.513009Z","end":"2026-10-17T11:39:06.696074Z","steps":["trace[707066699] 'process raft request' (duration: 35.937292ms)","trace[707066699] 'compare' (duration: 145.961558ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:39:07.132047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.507841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:39:07.132208Z","caller":"traceutil/trace.go:171","msg":"trace[635529837] transaction","detail":"{read_only:false; response_revision:22720; number_of_response:1; }","duration":"258.466263ms","start":"2026-10-17T11:39:06.87373Z","end":"2026-10-17T11:39:07.132196Z","steps":["trace[635529837] 'process raft request' (duration: 258.393911ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:39:07.132236Z","caller":"traceutil/trace.go:171","msg":"trace[257163674] transaction","detail":"{read_only:false; response_revision:22719; number_of_response:1; }","duration":"418.995144ms","start":"2026-10-17T11:39:06.713174Z","end":"2026-10-17T11:39:07.132169Z","steps":["trace[257163674] 'process raft request' (duration: 89.302948ms)","trace[257163674] 'compare' (duration: 328.684705ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:39:07.132248Z","caller":"traceutil/trace.go:171","msg":"trace[116120684] linearizableReadLoop","detail":"{readStateIndex:23481; appliedIndex:23480; }","duration":"385.710546ms","start":"2026-10-17T11:39:06.746511Z","end":"2026-10-17T11:39:07.132221Z","steps":["trace[116120684] 'read index received' (duration: 55.955798ms)","trace[116120684] 'applied index is now lower than readState.Index' (duration: 329.752568ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:39:07.132458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:39:06.713149Z","time spent":"419.185971ms","remote":"127.0.0.1:54300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-17T11:39:07.132458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.908172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-10-17T11:39:07.132532Z","caller":"traceutil/trace.go:171","msg":"trace[1752519398] range","detail":"{range_begin:/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi; range_end:; response_count:1; response_revision:22720; }","duration":"386.044787ms","start":"2026-10-17T11:39:06.746478Z","end":"2026-10-17T11:39:07.132523Z","steps":["trace[1752519398] 'agreement among raft nodes before linearized reading' (duration: 385.83292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:39:07.132565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:39:06.74646Z","time spent":"386.09201ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":666,"request content":"key:\"/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi\" "} {"level":"warn","ts":"2026-10-17T11:39:07.132614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.275299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:39:07.132671Z","caller":"traceutil/trace.go:171","msg":"trace[1132726960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22720; }","duration":"157.360462ms","start":"2026-10-17T11:39:06.97529Z","end":"2026-10-17T11:39:07.132651Z","steps":["trace[1132726960] 'agreement among raft nodes before linearized reading' (duration: 157.254428ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:39:27.47917Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20025} {"level":"info","ts":"2026-10-17T11:39:27.612126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20025,"took":"121.258688ms","hash":4178076785,"current-db-size-bytes":56250368,"current-db-size":"56 MB","current-db-size-in-use-bytes":41889792,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-17T11:39:27.612226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4178076785,"revision":20025,"compact-revision":17582} {"level":"info","ts":"2026-10-17T11:42:34.445262Z","caller":"traceutil/trace.go:171","msg":"trace[585320054] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"218.94844ms","start":"2026-10-17T11:42:34.226285Z","end":"2026-10-17T11:42:34.445233Z","steps":["trace[585320054] 'process raft request' (duration: 218.740283ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:42:34.505711Z","caller":"traceutil/trace.go:171","msg":"trace[2022832606] linearizableReadLoop","detail":"{readStateIndex:25580; appliedIndex:25578; }","duration":"100.281052ms","start":"2026-10-17T11:42:34.4054Z","end":"2026-10-17T11:42:34.505681Z","steps":["trace[2022832606] 'read index received' (duration: 39.635798ms)","trace[2022832606] 'applied index is now lower than readState.Index' (duration: 60.644244ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:42:34.505847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.416876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:42:34.505849Z","caller":"traceutil/trace.go:171","msg":"trace[2001184481] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"260.227048ms","start":"2026-10-17T11:42:34.245573Z","end":"2026-10-17T11:42:34.5058Z","steps":["trace[2001184481] 'process raft request' (duration: 259.96664ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:42:34.505896Z","caller":"traceutil/trace.go:171","msg":"trace[829601818] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:24766; }","duration":"100.520489ms","start":"2026-10-17T11:42:34.405357Z","end":"2026-10-17T11:42:34.505878Z","steps":["trace[829601818] 'agreement among raft nodes before linearized reading' (duration: 100.425266ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:42:34.883204Z","caller":"traceutil/trace.go:171","msg":"trace[2081725909] transaction","detail":"{read_only:false; response_revision:24767; number_of_response:1; }","duration":"430.039289ms","start":"2026-10-17T11:42:34.453121Z","end":"2026-10-17T11:42:34.883161Z","steps":["trace[2081725909] 'process raft request' (duration: 342.613855ms)","trace[2081725909] 'compare' (duration: 87.173476ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:42:34.883383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.477279ms","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-17T11:42:34.883563Z","caller":"traceutil/trace.go:171","msg":"trace[453257438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24770; }","duration":"278.789489ms","start":"2026-10-17T11:42:34.604761Z","end":"2026-10-17T11:42:34.883551Z","steps":["trace[453257438] 'agreement among raft nodes before linearized reading' (duration: 278.500389ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:42:34.883382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:42:34.453095Z","time spent":"430.199994ms","remote":"127.0.0.1:54480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1168,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:42:34.883218Z","caller":"traceutil/trace.go:171","msg":"trace[166811460] linearizableReadLoop","detail":"{readStateIndex:25584; appliedIndex:25580; }","duration":"278.298683ms","start":"2026-10-17T11:42:34.604903Z","end":"2026-10-17T11:42:34.883202Z","steps":["trace[166811460] 'read index received' (duration: 190.851567ms)","trace[166811460] 'applied index is now lower than readState.Index' (duration: 87.446366ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:42:34.883479Z","caller":"traceutil/trace.go:171","msg":"trace[1910555612] transaction","detail":"{read_only:false; response_revision:24769; number_of_response:1; }","duration":"429.10489ms","start":"2026-10-17T11:42:34.454359Z","end":"2026-10-17T11:42:34.883464Z","steps":["trace[1910555612] 'process raft request' (duration: 428.762659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:42:34.883826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:42:34.454347Z","time spent":"429.42826ms","remote":"127.0.0.1:54516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:42:34.883478Z","caller":"traceutil/trace.go:171","msg":"trace[1165441290] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"425.772144ms","start":"2026-10-17T11:42:34.457688Z","end":"2026-10-17T11:42:34.88346Z","steps":["trace[1165441290] 'process raft request' (duration: 425.473234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:42:34.883964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:42:34.45767Z","time spent":"426.242239ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7536,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:42:34.883478Z","caller":"traceutil/trace.go:171","msg":"trace[1113389109] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"429.982057ms","start":"2026-10-17T11:42:34.453479Z","end":"2026-10-17T11:42:34.883462Z","steps":["trace[1113389109] 'process raft request' (duration: 429.596145ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:42:34.884055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:42:34.453469Z","time spent":"430.548295ms","remote":"127.0.0.1:54392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:42:35.236274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.084172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:42:35.236382Z","caller":"traceutil/trace.go:171","msg":"trace[580447933] linearizableReadLoop","detail":"{readStateIndex:25585; appliedIndex:25584; }","duration":"352.652383ms","start":"2026-10-17T11:42:34.883708Z","end":"2026-10-17T11:42:35.23636Z","steps":["trace[580447933] 'read index received' (duration: 232.422816ms)","trace[580447933] 'applied index is now lower than readState.Index' (duration: 120.227507ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:42:35.236835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.997703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:42:35.236881Z","caller":"traceutil/trace.go:171","msg":"trace[740078056] transaction","detail":"{read_only:false; response_revision:24771; number_of_response:1; }","duration":"438.301671ms","start":"2026-10-17T11:42:34.798553Z","end":"2026-10-17T11:42:35.236855Z","steps":["trace[740078056] 'process raft request' (duration: 317.568099ms)","trace[740078056] 'compare' (duration: 119.952278ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:42:35.236937Z","caller":"traceutil/trace.go:171","msg":"trace[1230359748] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24771; }","duration":"428.18514ms","start":"2026-10-17T11:42:34.808725Z","end":"2026-10-17T11:42:35.23691Z","steps":["trace[1230359748] 'agreement among raft nodes before linearized reading' (duration: 427.976543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:42:35.236993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:42:34.798488Z","time spent":"438.450186ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:42:35.236999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:42:34.808707Z","time spent":"428.274322ms","remote":"127.0.0.1:55564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"warn","ts":"2026-10-17T11:42:35.237547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.942521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-17T11:42:35.237614Z","caller":"traceutil/trace.go:171","msg":"trace[1439329261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24771; }","duration":"628.090506ms","start":"2026-10-17T11:42:34.609509Z","end":"2026-10-17T11:42:35.2376Z","steps":["trace[1439329261] 'agreement among raft nodes before linearized reading' (duration: 627.237519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:42:35.237653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:42:34.609483Z","time spent":"628.159409ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-17T11:42:35.24377Z","caller":"traceutil/trace.go:171","msg":"trace[856057473] transaction","detail":"{read_only:false; response_revision:24772; number_of_response:1; }","duration":"349.300517ms","start":"2026-10-17T11:42:34.89444Z","end":"2026-10-17T11:42:35.243741Z","steps":["trace[856057473] 'process raft request' (duration: 349.049319ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:42:35.244046Z","caller":"traceutil/trace.go:171","msg":"trace[419316183] transaction","detail":"{read_only:false; response_revision:24775; number_of_response:1; }","duration":"221.898123ms","start":"2026-10-17T11:42:35.022122Z","end":"2026-10-17T11:42:35.24402Z","steps":["trace[419316183] 'process raft request' (duration: 221.654556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:42:35.24414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.697497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-17T11:42:35.244203Z","caller":"traceutil/trace.go:171","msg":"trace[423684187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24775; }","duration":"325.80898ms","start":"2026-10-17T11:42:34.918377Z","end":"2026-10-17T11:42:35.244186Z","steps":["trace[423684187] 'agreement among raft nodes before linearized reading' (duration: 325.579863ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:42:35.244226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.055208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:42:35.244285Z","caller":"traceutil/trace.go:171","msg":"trace[1757106251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24775; }","duration":"268.136821ms","start":"2026-10-17T11:42:34.976133Z","end":"2026-10-17T11:42:35.24427Z","steps":["trace[1757106251] 'agreement among raft nodes before linearized reading' (duration: 268.051938ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:42:35.244325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.568773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-17T11:42:35.244363Z","caller":"traceutil/trace.go:171","msg":"trace[1999607461] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24775; }","duration":"313.627495ms","start":"2026-10-17T11:42:34.930724Z","end":"2026-10-17T11:42:35.244352Z","steps":["trace[1999607461] 'agreement among raft nodes before linearized reading' (duration: 313.534822ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:42:35.24424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:42:34.918359Z","time spent":"325.871723ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-17T11:42:35.244386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:42:34.93071Z","time spent":"313.670186ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-17T11:42:35.244373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.331853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-17T11:42:35.244079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:42:34.894417Z","time spent":"349.546155ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:42:35.244534Z","caller":"traceutil/trace.go:171","msg":"trace[927294291] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24775; }","duration":"185.473927ms","start":"2026-10-17T11:42:35.058983Z","end":"2026-10-17T11:42:35.244457Z","steps":["trace[927294291] 'agreement among raft nodes before linearized reading' (duration: 185.298241ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:42:35.243831Z","caller":"traceutil/trace.go:171","msg":"trace[674642380] transaction","detail":"{read_only:false; response_revision:24773; number_of_response:1; }","duration":"345.759515ms","start":"2026-10-17T11:42:34.898055Z","end":"2026-10-17T11:42:35.243814Z","steps":["trace[674642380] 'process raft request' (duration: 345.60139ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:42:35.243839Z","caller":"traceutil/trace.go:171","msg":"trace[654540027] transaction","detail":"{read_only:false; response_revision:24774; number_of_response:1; }","duration":"225.812558ms","start":"2026-10-17T11:42:35.018006Z","end":"2026-10-17T11:42:35.243818Z","steps":["trace[654540027] 'process raft request' (duration: 225.728135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:42:35.244707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:42:34.898028Z","time spent":"346.581611ms","remote":"127.0.0.1:54628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7836,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:43:30.226426Z","caller":"traceutil/trace.go:171","msg":"trace[1013558923] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"119.40203ms","start":"2026-10-17T11:43:30.10699Z","end":"2026-10-17T11:43:30.226392Z","steps":["trace[1013558923] 'process raft request' (duration: 119.172093ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:43:35.612391Z","caller":"traceutil/trace.go:171","msg":"trace[1571837504] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"152.099097ms","start":"2026-10-17T11:43:35.460261Z","end":"2026-10-17T11:43:35.61236Z","steps":["trace[1571837504] 'process raft request' (duration: 151.896561ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:43:35.84299Z","caller":"traceutil/trace.go:171","msg":"trace[2011864885] transaction","detail":"{read_only:false; response_revision:25316; number_of_response:1; }","duration":"380.257069ms","start":"2026-10-17T11:43:35.462701Z","end":"2026-10-17T11:43:35.842958Z","steps":["trace[2011864885] 'process raft request' (duration: 379.995091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:43:35.843346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:43:35.462672Z","time spent":"380.514197ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:43:35.843478Z","caller":"traceutil/trace.go:171","msg":"trace[1938778179] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"380.517058ms","start":"2026-10-17T11:43:35.462934Z","end":"2026-10-17T11:43:35.843452Z","steps":["trace[1938778179] 'process raft request' (duration: 379.96268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:43:35.843577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.382262ms","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-17T11:43:35.843628Z","caller":"traceutil/trace.go:171","msg":"trace[1172762602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25319; }","duration":"310.482875ms","start":"2026-10-17T11:43:35.533134Z","end":"2026-10-17T11:43:35.843616Z","steps":["trace[1172762602] 'agreement among raft nodes before linearized reading' (duration: 310.299019ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:43:35.843636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:43:35.462918Z","time spent":"380.643852ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:43:35.843672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:43:35.533115Z","time spent":"310.542797ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-17T11:43:35.843755Z","caller":"traceutil/trace.go:171","msg":"trace[327073923] transaction","detail":"{read_only:false; response_revision:25318; number_of_response:1; }","duration":"344.825265ms","start":"2026-10-17T11:43:35.498919Z","end":"2026-10-17T11:43:35.843744Z","steps":["trace[327073923] 'process raft request' (duration: 344.262997ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:43:35.843796Z","caller":"traceutil/trace.go:171","msg":"trace[417862919] transaction","detail":"{read_only:false; response_revision:25319; number_of_response:1; }","duration":"264.315399ms","start":"2026-10-17T11:43:35.579469Z","end":"2026-10-17T11:43:35.843784Z","steps":["trace[417862919] 'process raft request' (duration: 263.843854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:43:35.843811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:43:35.498896Z","time spent":"344.885427ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:43:35.843384Z","caller":"traceutil/trace.go:171","msg":"trace[855801744] linearizableReadLoop","detail":"{readStateIndex:26145; appliedIndex:26141; }","duration":"310.174475ms","start":"2026-10-17T11:43:35.533185Z","end":"2026-10-17T11:43:35.843359Z","steps":["trace[855801744] 'read index received' (duration: 78.960526ms)","trace[855801744] 'applied index is now lower than readState.Index' (duration: 231.212939ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:43:35.844139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.627186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-17T11:43:35.844182Z","caller":"traceutil/trace.go:171","msg":"trace[690920099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25319; }","duration":"302.702748ms","start":"2026-10-17T11:43:35.541467Z","end":"2026-10-17T11:43:35.84417Z","steps":["trace[690920099] 'agreement among raft nodes before linearized reading' (duration: 302.557934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:43:35.844209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:43:35.541434Z","time spent":"302.76862ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-17T11:43:35.844231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.416282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-17T11:43:35.844267Z","caller":"traceutil/trace.go:171","msg":"trace[1172454148] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:25319; }","duration":"258.488264ms","start":"2026-10-17T11:43:35.585768Z","end":"2026-10-17T11:43:35.844257Z","steps":["trace[1172454148] 'agreement among raft nodes before linearized reading' (duration: 258.287818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:43:35.844338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.103864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-17T11:43:35.844373Z","caller":"traceutil/trace.go:171","msg":"trace[760987947] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25319; }","duration":"288.166656ms","start":"2026-10-17T11:43:35.556196Z","end":"2026-10-17T11:43:35.844362Z","steps":["trace[760987947] 'agreement among raft nodes before linearized reading' (duration: 288.085424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:43:35.844561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.674049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-17T11:43:35.844571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.577827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-17T11:43:35.8446Z","caller":"traceutil/trace.go:171","msg":"trace[691916439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25319; }","duration":"108.780054ms","start":"2026-10-17T11:43:35.73581Z","end":"2026-10-17T11:43:35.84459Z","steps":["trace[691916439] 'agreement among raft nodes before linearized reading' (duration: 108.650259ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:43:35.844605Z","caller":"traceutil/trace.go:171","msg":"trace[668315473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25319; }","duration":"298.623708ms","start":"2026-10-17T11:43:35.545972Z","end":"2026-10-17T11:43:35.844595Z","steps":["trace[668315473] 'agreement among raft nodes before linearized reading' (duration: 298.501504ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:43:36.019811Z","caller":"traceutil/trace.go:171","msg":"trace[616259422] transaction","detail":"{read_only:false; response_revision:25320; number_of_response:1; }","duration":"169.621424ms","start":"2026-10-17T11:43:35.85016Z","end":"2026-10-17T11:43:36.019781Z","steps":["trace[616259422] 'process raft request' (duration: 159.703229ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:43:36.041834Z","caller":"traceutil/trace.go:171","msg":"trace[571498690] transaction","detail":"{read_only:false; response_revision:25324; number_of_response:1; }","duration":"189.840506ms","start":"2026-10-17T11:43:35.851964Z","end":"2026-10-17T11:43:36.041804Z","steps":["trace[571498690] 'process raft request' (duration: 189.441062ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:43:36.04218Z","caller":"traceutil/trace.go:171","msg":"trace[1396117556] transaction","detail":"{read_only:false; response_revision:25321; number_of_response:1; }","duration":"191.919892ms","start":"2026-10-17T11:43:35.850244Z","end":"2026-10-17T11:43:36.042164Z","steps":["trace[1396117556] 'process raft request' (duration: 190.637701ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:43:36.042271Z","caller":"traceutil/trace.go:171","msg":"trace[1833927304] transaction","detail":"{read_only:false; response_revision:25322; number_of_response:1; }","duration":"190.423414ms","start":"2026-10-17T11:43:35.851837Z","end":"2026-10-17T11:43:36.04226Z","steps":["trace[1833927304] 'process raft request' (duration: 189.490804ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:43:36.042415Z","caller":"traceutil/trace.go:171","msg":"trace[543003149] transaction","detail":"{read_only:false; response_revision:25323; number_of_response:1; }","duration":"190.449605ms","start":"2026-10-17T11:43:35.851946Z","end":"2026-10-17T11:43:36.042396Z","steps":["trace[543003149] 'process raft request' (duration: 189.426512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:43:36.582183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.573836ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-17T11:43:36.582847Z","caller":"traceutil/trace.go:171","msg":"trace[294188165] linearizableReadLoop","detail":"{readStateIndex:26153; appliedIndex:26151; }","duration":"320.593256ms","start":"2026-10-17T11:43:36.262194Z","end":"2026-10-17T11:43:36.582787Z","steps":["trace[294188165] 'read index received' (duration: 109.832736ms)","trace[294188165] 'applied index is now lower than readState.Index' (duration: 210.75835ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:43:36.582888Z","caller":"traceutil/trace.go:171","msg":"trace[1517510477] transaction","detail":"{read_only:false; response_revision:25325; number_of_response:1; }","duration":"325.962857ms","start":"2026-10-17T11:43:36.256901Z","end":"2026-10-17T11:43:36.582863Z","steps":["trace[1517510477] 'process raft request' (duration: 325.405039ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:43:36.583228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:43:36.256874Z","time spent":"326.197944ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:43:36.815015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.753735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-17T11:43:36.815162Z","caller":"traceutil/trace.go:171","msg":"trace[1794141761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25325; }","duration":"552.967942ms","start":"2026-10-17T11:43:36.262155Z","end":"2026-10-17T11:43:36.815123Z","steps":["trace[1794141761] 'agreement among raft nodes before linearized reading' (duration: 320.807653ms)","trace[1794141761] 'range keys from in-memory index tree' (duration: 231.740395ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:43:36.815231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:43:36.262141Z","time spent":"553.077886ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-17T11:43:36.867983Z","caller":"traceutil/trace.go:171","msg":"trace[1117407292] transaction","detail":"{read_only:false; response_revision:25326; number_of_response:1; }","duration":"116.041782ms","start":"2026-10-17T11:43:36.751908Z","end":"2026-10-17T11:43:36.867949Z","steps":["trace[1117407292] 'process raft request' (duration: 115.812355ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:44:04.717127Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000676e.wal"} {"level":"info","ts":"2026-10-17T11:44:27.490831Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22909} {"level":"info","ts":"2026-10-17T11:44:27.641771Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22909,"took":"146.942455ms","hash":1540258296,"current-db-size-bytes":57102336,"current-db-size":"57 MB","current-db-size-in-use-bytes":36380672,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-17T11:44:27.641978Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1540258296,"revision":22909,"compact-revision":20025} {"level":"info","ts":"2026-10-17T11:44:50.649835Z","caller":"traceutil/trace.go:171","msg":"trace[1476562548] linearizableReadLoop","detail":"{readStateIndex:26884; appliedIndex:26883; }","duration":"132.210566ms","start":"2026-10-17T11:44:50.517598Z","end":"2026-10-17T11:44:50.649808Z","steps":["trace[1476562548] 'read index received' (duration: 122.453507ms)","trace[1476562548] 'applied index is now lower than readState.Index' (duration: 9.755899ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:44:50.650063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.445923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-17T11:44:50.650102Z","caller":"traceutil/trace.go:171","msg":"trace[2002698902] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26039; }","duration":"132.527866ms","start":"2026-10-17T11:44:50.517561Z","end":"2026-10-17T11:44:50.650089Z","steps":["trace[2002698902] 'agreement among raft nodes before linearized reading' (duration: 132.34492ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:44:50.650276Z","caller":"traceutil/trace.go:171","msg":"trace[1789458893] transaction","detail":"{read_only:false; response_revision:26039; number_of_response:1; }","duration":"169.510389ms","start":"2026-10-17T11:44:50.480755Z","end":"2026-10-17T11:44:50.650266Z","steps":["trace[1789458893] 'process raft request' (duration: 159.251204ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:45:46.372208Z","caller":"traceutil/trace.go:171","msg":"trace[404568735] transaction","detail":"{read_only:false; response_revision:26599; number_of_response:1; }","duration":"111.517449ms","start":"2026-10-17T11:45:46.260659Z","end":"2026-10-17T11:45:46.372176Z","steps":["trace[404568735] 'process raft request' (duration: 111.321613ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:45:46.519334Z","caller":"traceutil/trace.go:171","msg":"trace[793636964] transaction","detail":"{read_only:false; response_revision:26603; number_of_response:1; }","duration":"105.313052ms","start":"2026-10-17T11:45:46.413996Z","end":"2026-10-17T11:45:46.519309Z","steps":["trace[793636964] 'process raft request' (duration: 103.366481ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:47:29.564093Z","caller":"traceutil/trace.go:171","msg":"trace[635291242] transaction","detail":"{read_only:false; response_revision:27670; number_of_response:1; }","duration":"103.992981ms","start":"2026-10-17T11:47:29.460075Z","end":"2026-10-17T11:47:29.564068Z","steps":["trace[635291242] 'process raft request' (duration: 103.844966ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:49:27.498043Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25867} {"level":"info","ts":"2026-10-17T11:49:27.656411Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25867,"took":"154.328774ms","hash":3442039908,"current-db-size-bytes":57102336,"current-db-size":"57 MB","current-db-size-in-use-bytes":34877440,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-17T11:49:27.65648Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3442039908,"revision":25867,"compact-revision":22909} {"level":"info","ts":"2026-10-17T11:50:33.238972Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"53de2bdd9aa7b793","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:50:33.244738Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-17T11:50:33.244878Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-17T11:54:27.504737Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28561} {"level":"info","ts":"2026-10-17T11:54:27.650005Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28561,"took":"140.109222ms","hash":3742053589,"current-db-size-bytes":57102336,"current-db-size":"57 MB","current-db-size-in-use-bytes":30216192,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-17T11:54:27.650094Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3742053589,"revision":28561,"compact-revision":25867} {"level":"info","ts":"2026-10-17T11:59:27.511266Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30776} {"level":"info","ts":"2026-10-17T11:59:27.630073Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30776,"took":"114.194104ms","hash":4019826130,"current-db-size-bytes":57102336,"current-db-size":"57 MB","current-db-size-in-use-bytes":31141888,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T11:59:27.630222Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4019826130,"revision":30776,"compact-revision":28561} {"level":"info","ts":"2026-10-17T12:03:10.415385Z","caller":"traceutil/trace.go:171","msg":"trace[2019353525] transaction","detail":"{read_only:false; response_revision:34861; number_of_response:1; }","duration":"110.358282ms","start":"2026-10-17T12:03:10.305Z","end":"2026-10-17T12:03:10.415358Z","steps":["trace[2019353525] 'process raft request' (duration: 86.397252ms)","trace[2019353525] 'compare' (duration: 23.792915ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T12:03:10.416086Z","caller":"traceutil/trace.go:171","msg":"trace[1551625981] transaction","detail":"{read_only:false; response_revision:34862; number_of_response:1; }","duration":"103.329859ms","start":"2026-10-17T12:03:10.312251Z","end":"2026-10-17T12:03:10.415581Z","steps":["trace[1551625981] 'process raft request' (duration: 103.066611ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:03:10.615936Z","caller":"traceutil/trace.go:171","msg":"trace[1391372997] transaction","detail":"{read_only:false; response_revision:34869; number_of_response:1; }","duration":"107.062328ms","start":"2026-10-17T12:03:10.508839Z","end":"2026-10-17T12:03:10.615901Z","steps":["trace[1391372997] 'process raft request' (duration: 106.625404ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:04:27.522288Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33045} {"level":"info","ts":"2026-10-17T12:04:27.693427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33045,"took":"156.166196ms","hash":247511052,"current-db-size-bytes":58146816,"current-db-size":"58 MB","current-db-size-in-use-bytes":31379456,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T12:04:27.693531Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":247511052,"revision":33045,"compact-revision":30776} {"level":"info","ts":"2026-10-17T12:09:27.528512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35435} {"level":"info","ts":"2026-10-17T12:09:27.664225Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35435,"took":"132.938349ms","hash":1861392618,"current-db-size-bytes":59420672,"current-db-size":"59 MB","current-db-size-in-use-bytes":33353728,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-17T12:09:27.664931Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1861392618,"revision":35435,"compact-revision":33045} {"level":"info","ts":"2026-10-17T12:11:47.207972Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"53de2bdd9aa7b793","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T12:11:47.214851Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-17T12:11:47.214963Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-17T12:14:27.536212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37694} {"level":"info","ts":"2026-10-17T12:14:27.68021Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37694,"took":"138.746006ms","hash":4127542001,"current-db-size-bytes":60698624,"current-db-size":"61 MB","current-db-size-in-use-bytes":32354304,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T12:14:27.680286Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4127542001,"revision":37694,"compact-revision":35435}