{"level":"warn","ts":"2026-10-27T14:48:28.898524Z","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-27T14:48:28.898706Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.4: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.4:2380","--initial-cluster=instance=https://162.253.55.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.4: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-27T14:48:28.898824Z","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-27T14:48:28.898846Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.4:2380"]} {"level":"info","ts":"2026-10-27T14:48:28.898957Z","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-27T14:48:28.900096Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.4:2379"]} {"level":"info","ts":"2026-10-27T14:48:28.900236Z","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.4:2380"],"listen-peer-urls":["https://162.253.55.4:2380"],"advertise-client-urls":["https://162.253.55.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.4: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-27T14:48:28.904685Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.057165ms"} {"level":"info","ts":"2026-10-27T14:48:28.910902Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"caafea9f270da559","cluster-id":"271ff399bb4addea"} {"level":"info","ts":"2026-10-27T14:48:28.911011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 switched to configuration voters=()"} {"level":"info","ts":"2026-10-27T14:48:28.911056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became follower at term 0"} {"level":"info","ts":"2026-10-27T14:48:28.911083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft caafea9f270da559 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-27T14:48:28.9111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became follower at term 1"} {"level":"info","ts":"2026-10-27T14:48:28.911159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 switched to configuration voters=(14605150085861713241)"} {"level":"warn","ts":"2026-10-27T14:48:28.924404Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-27T14:48:28.926797Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-27T14:48:28.931594Z","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-27T14:48:28.934641Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"caafea9f270da559","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-27T14:48:28.93488Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"caafea9f270da559","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-27T14:48:28.935033Z","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-27T14:48:28.935151Z","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-27T14:48:28.935189Z","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-27T14:48:28.938192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 switched to configuration voters=(14605150085861713241)"} {"level":"info","ts":"2026-10-27T14:48:28.938467Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"271ff399bb4addea","local-member-id":"caafea9f270da559","added-peer-id":"caafea9f270da559","added-peer-peer-urls":["https://162.253.55.4:2380"]} {"level":"info","ts":"2026-10-27T14:48:28.940918Z","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-27T14:48:28.941041Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.4:2380"} {"level":"info","ts":"2026-10-27T14:48:28.941082Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.4:2380"} {"level":"info","ts":"2026-10-27T14:48:28.941569Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"caafea9f270da559","initial-advertise-peer-urls":["https://162.253.55.4:2380"],"listen-peer-urls":["https://162.253.55.4:2380"],"advertise-client-urls":["https://162.253.55.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-27T14:48:28.941666Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-27T14:48:29.212006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 is starting a new election at term 1"} {"level":"info","ts":"2026-10-27T14:48:29.212072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-27T14:48:29.212097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 received MsgPreVoteResp from caafea9f270da559 at term 1"} {"level":"info","ts":"2026-10-27T14:48:29.212121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became candidate at term 2"} {"level":"info","ts":"2026-10-27T14:48:29.212133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 received MsgVoteResp from caafea9f270da559 at term 2"} {"level":"info","ts":"2026-10-27T14:48:29.212155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became leader at term 2"} {"level":"info","ts":"2026-10-27T14:48:29.21217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: caafea9f270da559 elected leader caafea9f270da559 at term 2"} {"level":"info","ts":"2026-10-27T14:48:29.213343Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"caafea9f270da559","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.4:2379]}","request-path":"/0/members/caafea9f270da559/attributes","cluster-id":"271ff399bb4addea","publish-timeout":"7s"} {"level":"info","ts":"2026-10-27T14:48:29.213509Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-27T14:48:29.21359Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-27T14:48:29.213397Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T14:48:29.215326Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"271ff399bb4addea","local-member-id":"caafea9f270da559","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T14:48:29.216Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T14:48:29.216181Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-27T14:48:29.216206Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-27T14:48:29.216049Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T14:48:29.218099Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.4:2379"} {"level":"info","ts":"2026-10-27T14:48:29.21812Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-27T14:48:47.21243Z","caller":"traceutil/trace.go:171","msg":"trace[976355749] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"163.432742ms","start":"2026-10-27T14:48:47.04897Z","end":"2026-10-27T14:48:47.212403Z","steps":["trace[976355749] 'read index received' (duration: 163.236228ms)","trace[976355749] 'applied index is now lower than readState.Index' (duration: 195.784µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T14:48:47.212586Z","caller":"traceutil/trace.go:171","msg":"trace[316781295] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"342.288645ms","start":"2026-10-27T14:48:46.870285Z","end":"2026-10-27T14:48:47.212574Z","steps":["trace[316781295] 'process raft request' (duration: 341.956587ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:48:47.212712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.955911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-27T14:48:47.212799Z","caller":"traceutil/trace.go:171","msg":"trace[1328074511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:416; }","duration":"102.147495ms","start":"2026-10-27T14:48:47.110638Z","end":"2026-10-27T14:48:47.212786Z","steps":["trace[1328074511] 'agreement among raft nodes before linearized reading' (duration: 101.976491ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:48:47.212979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.022445ms","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-27T14:48:47.213014Z","caller":"traceutil/trace.go:171","msg":"trace[310942371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:416; }","duration":"164.101207ms","start":"2026-10-27T14:48:47.048904Z","end":"2026-10-27T14:48:47.213006Z","steps":["trace[310942371] 'agreement among raft nodes before linearized reading' (duration: 164.041896ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:48:47.213236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T14:48:46.870263Z","time spent":"342.347496ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T14:49:19.730127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.754898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T14:49:19.730345Z","caller":"traceutil/trace.go:171","msg":"trace[2088515744] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"301.307329ms","start":"2026-10-27T14:49:19.429018Z","end":"2026-10-27T14:49:19.730325Z","steps":["trace[2088515744] 'process raft request' (duration: 173.130511ms)","trace[2088515744] 'compare' (duration: 127.635525ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T14:49:19.730451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T14:49:19.429004Z","time spent":"301.411041ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T14:49:19.797043Z","caller":"traceutil/trace.go:171","msg":"trace[199624542] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"356.901586ms","start":"2026-10-27T14:49:19.440107Z","end":"2026-10-27T14:49:19.797008Z","steps":["trace[199624542] 'process raft request' (duration: 356.668451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:49:19.797214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T14:49:19.44009Z","time spent":"357.026739ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T14:49:19.797231Z","caller":"traceutil/trace.go:171","msg":"trace[1255654256] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"321.639459ms","start":"2026-10-27T14:49:19.475561Z","end":"2026-10-27T14:49:19.797201Z","steps":["trace[1255654256] 'process raft request' (duration: 321.362312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:49:19.797416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T14:49:19.475533Z","time spent":"321.830214ms","remote":"127.0.0.1:44768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-27T14:49:20.53755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.651733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T14:49:20.537651Z","caller":"traceutil/trace.go:171","msg":"trace[371339369] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"387.197779ms","start":"2026-10-27T14:49:20.150436Z","end":"2026-10-27T14:49:20.537633Z","steps":["trace[371339369] 'process raft request' (duration: 276.375402ms)","trace[371339369] 'compare' (duration: 110.548851ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T14:49:20.537705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T14:49:20.150406Z","time spent":"387.273081ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T14:49:20.653515Z","caller":"traceutil/trace.go:171","msg":"trace[1966660627] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"170.152082ms","start":"2026-10-27T14:49:20.483339Z","end":"2026-10-27T14:49:20.653491Z","steps":["trace[1966660627] 'read index received' (duration: 169.435715ms)","trace[1966660627] 'applied index is now lower than readState.Index' (duration: 715.147µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T14:49:20.653605Z","caller":"traceutil/trace.go:171","msg":"trace[1493549163] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"437.959835ms","start":"2026-10-27T14:49:20.215625Z","end":"2026-10-27T14:49:20.653585Z","steps":["trace[1493549163] 'process raft request' (duration: 437.344821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:49:20.653664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.312075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T14:49:20.653693Z","caller":"traceutil/trace.go:171","msg":"trace[1373344725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"170.390557ms","start":"2026-10-27T14:49:20.483294Z","end":"2026-10-27T14:49:20.653685Z","steps":["trace[1373344725] 'agreement among raft nodes before linearized reading' (duration: 170.317036ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:49:20.653725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T14:49:20.215595Z","time spent":"438.060918ms","remote":"127.0.0.1:44482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T14:49:22.264035Z","caller":"traceutil/trace.go:171","msg":"trace[965896164] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"103.788314ms","start":"2026-10-27T14:49:22.160222Z","end":"2026-10-27T14:49:22.264011Z","steps":["trace[965896164] 'process raft request' (duration: 103.653ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:49:35.965579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.705478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T14:49:35.965736Z","caller":"traceutil/trace.go:171","msg":"trace[2038562027] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:845; }","duration":"157.446437ms","start":"2026-10-27T14:49:35.808271Z","end":"2026-10-27T14:49:35.965717Z","steps":["trace[2038562027] 'read index received' (duration: 48.431µs)","trace[2038562027] 'applied index is now lower than readState.Index' (duration: 157.396556ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T14:49:35.965874Z","caller":"traceutil/trace.go:171","msg":"trace[187290057] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"181.401111ms","start":"2026-10-27T14:49:35.784428Z","end":"2026-10-27T14:49:35.965829Z","steps":["trace[187290057] 'process raft request' (duration: 12.365976ms)","trace[187290057] 'compare' (duration: 168.560134ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T14:49:35.965995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.703132ms","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-27T14:49:35.966049Z","caller":"traceutil/trace.go:171","msg":"trace[56156131] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:814; }","duration":"157.780944ms","start":"2026-10-27T14:49:35.808253Z","end":"2026-10-27T14:49:35.966034Z","steps":["trace[56156131] 'agreement among raft nodes before linearized reading' (duration: 157.533968ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:49:36.193047Z","caller":"traceutil/trace.go:171","msg":"trace[236496754] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"346.021695ms","start":"2026-10-27T14:49:35.846997Z","end":"2026-10-27T14:49:36.193019Z","steps":["trace[236496754] 'process raft request' (duration: 345.862901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:49:36.193238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T14:49:35.846965Z","time spent":"346.175079ms","remote":"127.0.0.1:44598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T14:49:36.193449Z","caller":"traceutil/trace.go:171","msg":"trace[1702185829] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:847; }","duration":"227.631732ms","start":"2026-10-27T14:49:35.965792Z","end":"2026-10-27T14:49:36.193423Z","steps":["trace[1702185829] 'read index received' (duration: 227.625342ms)","trace[1702185829] 'applied index is now lower than readState.Index' (duration: 5.08µs)"],"step_count":2} {"level":"warn","ts":"2026-10-27T14:49:36.193695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.33492ms","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-27T14:49:36.193765Z","caller":"traceutil/trace.go:171","msg":"trace[777535650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:815; }","duration":"317.445003ms","start":"2026-10-27T14:49:35.876303Z","end":"2026-10-27T14:49:36.193748Z","steps":["trace[777535650] 'agreement among raft nodes before linearized reading' (duration: 317.236758ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:49:36.193828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T14:49:35.876282Z","time spent":"317.536665ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-27T14:49:36.195292Z","caller":"traceutil/trace.go:171","msg":"trace[2086273708] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"224.393357ms","start":"2026-10-27T14:49:35.970878Z","end":"2026-10-27T14:49:36.195272Z","steps":["trace[2086273708] 'process raft request' (duration: 224.145371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:49:36.195332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.216334ms","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-27T14:49:36.195421Z","caller":"traceutil/trace.go:171","msg":"trace[460098295] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:816; }","duration":"164.358647ms","start":"2026-10-27T14:49:36.031044Z","end":"2026-10-27T14:49:36.195403Z","steps":["trace[460098295] 'agreement among raft nodes before linearized reading' (duration: 164.119362ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:49:45.379815Z","caller":"traceutil/trace.go:171","msg":"trace[2110895612] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"101.282096ms","start":"2026-10-27T14:49:45.278479Z","end":"2026-10-27T14:49:45.379761Z","steps":["trace[2110895612] 'process raft request' (duration: 87.572169ms)","trace[2110895612] 'compare' (duration: 13.273227ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T14:50:06.941626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.519466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T14:50:06.941768Z","caller":"traceutil/trace.go:171","msg":"trace[1815279358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"138.71454ms","start":"2026-10-27T14:50:06.803025Z","end":"2026-10-27T14:50:06.941739Z","steps":["trace[1815279358] 'range keys from in-memory index tree' (duration: 138.356642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:50:06.942103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.25232ms","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-27T14:50:06.942224Z","caller":"traceutil/trace.go:171","msg":"trace[1365399554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1216; }","duration":"165.436274ms","start":"2026-10-27T14:50:06.776762Z","end":"2026-10-27T14:50:06.942199Z","steps":["trace[1365399554] 'range keys from in-memory index tree' (duration: 164.987774ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:50:06.942375Z","caller":"traceutil/trace.go:171","msg":"trace[282571020] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"105.115928ms","start":"2026-10-27T14:50:06.837223Z","end":"2026-10-27T14:50:06.942339Z","steps":["trace[282571020] 'process raft request' (duration: 104.895943ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:50:58.539275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.171577ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-27T14:50:58.539615Z","caller":"traceutil/trace.go:171","msg":"trace[1768869036] linearizableReadLoop","detail":"{readStateIndex:1989; appliedIndex:1988; }","duration":"450.188123ms","start":"2026-10-27T14:50:58.089392Z","end":"2026-10-27T14:50:58.53958Z","steps":["trace[1768869036] 'read index received' (duration: 81.720929ms)","trace[1768869036] 'applied index is now lower than readState.Index' (duration: 368.463884ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T14:50:58.539699Z","caller":"traceutil/trace.go:171","msg":"trace[1039586779] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"447.643414ms","start":"2026-10-27T14:50:58.092035Z","end":"2026-10-27T14:50:58.539678Z","steps":["trace[1039586779] 'process raft request' (duration: 447.46124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:50:58.539767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.348587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T14:50:58.539843Z","caller":"traceutil/trace.go:171","msg":"trace[1493003910] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1912; }","duration":"450.50254ms","start":"2026-10-27T14:50:58.08932Z","end":"2026-10-27T14:50:58.539822Z","steps":["trace[1493003910] 'agreement among raft nodes before linearized reading' (duration: 450.340457ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:50:58.53984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T14:50:58.092014Z","time spent":"447.737117ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T14:50:58.539915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.717015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"warn","ts":"2026-10-27T14:50:58.539919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T14:50:58.089289Z","time spent":"450.611743ms","remote":"127.0.0.1:47432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-10-27T14:50:58.540329Z","caller":"traceutil/trace.go:171","msg":"trace[2003008332] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1912; }","duration":"203.864838ms","start":"2026-10-27T14:50:58.336121Z","end":"2026-10-27T14:50:58.539986Z","steps":["trace[2003008332] 'agreement among raft nodes before linearized reading' (duration: 203.617333ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:50:59.023172Z","caller":"traceutil/trace.go:171","msg":"trace[1352692660] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"310.665045ms","start":"2026-10-27T14:50:58.712463Z","end":"2026-10-27T14:50:59.023128Z","steps":["trace[1352692660] 'process raft request' (duration: 241.128045ms)","trace[1352692660] 'compare' (duration: 69.247723ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T14:50:59.02335Z","caller":"traceutil/trace.go:171","msg":"trace[1225555938] linearizableReadLoop","detail":"{readStateIndex:1993; appliedIndex:1991; }","duration":"308.744211ms","start":"2026-10-27T14:50:58.714568Z","end":"2026-10-27T14:50:59.023312Z","steps":["trace[1225555938] 'read index received' (duration: 239.045438ms)","trace[1225555938] 'applied index is now lower than readState.Index' (duration: 69.697303ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T14:50:59.023405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T14:50:58.712436Z","time spent":"310.839869ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T14:50:59.023663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.790192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T14:50:59.023716Z","caller":"traceutil/trace.go:171","msg":"trace[1338100534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1915; }","duration":"218.919325ms","start":"2026-10-27T14:50:58.804782Z","end":"2026-10-27T14:50:59.023702Z","steps":["trace[1338100534] 'agreement among raft nodes before linearized reading' (duration: 218.840663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:50:59.023696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.095089ms","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-27T14:50:59.02379Z","caller":"traceutil/trace.go:171","msg":"trace[851208466] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1915; }","duration":"309.241212ms","start":"2026-10-27T14:50:58.714527Z","end":"2026-10-27T14:50:59.023768Z","steps":["trace[851208466] 'agreement among raft nodes before linearized reading' (duration: 308.980516ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:50:59.023761Z","caller":"traceutil/trace.go:171","msg":"trace[1960797533] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"310.824269ms","start":"2026-10-27T14:50:58.712877Z","end":"2026-10-27T14:50:59.023701Z","steps":["trace[1960797533] 'process raft request' (duration: 310.192214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:50:59.023865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T14:50:58.714514Z","time spent":"309.313194ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-27T14:50:59.024002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T14:50:58.712843Z","time spent":"311.015972ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T14:50:59.024097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.394394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f06f5d64-bf75-4101-904e-6d8129611405\" ","response":"range_response_count:1 size:3745"} {"level":"info","ts":"2026-10-27T14:50:59.024228Z","caller":"traceutil/trace.go:171","msg":"trace[550103864] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f06f5d64-bf75-4101-904e-6d8129611405; range_end:; response_count:1; response_revision:1915; }","duration":"125.618679ms","start":"2026-10-27T14:50:58.89857Z","end":"2026-10-27T14:50:59.024189Z","steps":["trace[550103864] 'agreement among raft nodes before linearized reading' (duration: 125.200809ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:51:07.350254Z","caller":"traceutil/trace.go:171","msg":"trace[936815308] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"141.643755ms","start":"2026-10-27T14:51:07.208577Z","end":"2026-10-27T14:51:07.350221Z","steps":["trace[936815308] 'process raft request' (duration: 141.330428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:51:12.199087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.872492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-27T14:51:12.199187Z","caller":"traceutil/trace.go:171","msg":"trace[747867968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2030; }","duration":"106.024576ms","start":"2026-10-27T14:51:12.093141Z","end":"2026-10-27T14:51:12.199166Z","steps":["trace[747867968] 'range keys from in-memory index tree' (duration: 105.593927ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:51:38.216504Z","caller":"traceutil/trace.go:171","msg":"trace[2080701045] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"112.141997ms","start":"2026-10-27T14:51:38.104339Z","end":"2026-10-27T14:51:38.216481Z","steps":["trace[2080701045] 'process raft request' (duration: 112.003054ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:51:38.309813Z","caller":"traceutil/trace.go:171","msg":"trace[1758167565] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"203.886245ms","start":"2026-10-27T14:51:38.105901Z","end":"2026-10-27T14:51:38.309787Z","steps":["trace[1758167565] 'process raft request' (duration: 203.571308ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:51:38.312522Z","caller":"traceutil/trace.go:171","msg":"trace[1552674105] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"145.046313ms","start":"2026-10-27T14:51:38.167443Z","end":"2026-10-27T14:51:38.312489Z","steps":["trace[1552674105] 'process raft request' (duration: 144.719736ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:51:44.021741Z","caller":"traceutil/trace.go:171","msg":"trace[1419842783] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"126.22389ms","start":"2026-10-27T14:51:43.895484Z","end":"2026-10-27T14:51:44.021708Z","steps":["trace[1419842783] 'process raft request' (duration: 126.052586ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:51:49.598122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.076294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-27T14:51:49.598268Z","caller":"traceutil/trace.go:171","msg":"trace[1164854616] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2239; }","duration":"135.305459ms","start":"2026-10-27T14:51:49.462935Z","end":"2026-10-27T14:51:49.598241Z","steps":["trace[1164854616] 'count revisions from in-memory index tree' (duration: 134.829878ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:51:55.7465Z","caller":"traceutil/trace.go:171","msg":"trace[933351486] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"141.408279ms","start":"2026-10-27T14:51:55.605065Z","end":"2026-10-27T14:51:55.746473Z","steps":["trace[933351486] 'process raft request' (duration: 141.216305ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:51:55.829713Z","caller":"traceutil/trace.go:171","msg":"trace[1296148222] linearizableReadLoop","detail":"{readStateIndex:2362; appliedIndex:2360; }","duration":"117.571211ms","start":"2026-10-27T14:51:55.712117Z","end":"2026-10-27T14:51:55.829688Z","steps":["trace[1296148222] 'read index received' (duration: 34.153804ms)","trace[1296148222] 'applied index is now lower than readState.Index' (duration: 83.415997ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T14:51:55.829839Z","caller":"traceutil/trace.go:171","msg":"trace[670564471] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"191.865399ms","start":"2026-10-27T14:51:55.637923Z","end":"2026-10-27T14:51:55.829789Z","steps":["trace[670564471] 'process raft request' (duration: 165.528754ms)","trace[670564471] 'compare' (duration: 26.081229ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T14:51:55.829966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.829028ms","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-27T14:51:55.830053Z","caller":"traceutil/trace.go:171","msg":"trace[1662772648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2271; }","duration":"117.94981ms","start":"2026-10-27T14:51:55.712067Z","end":"2026-10-27T14:51:55.830017Z","steps":["trace[1662772648] 'agreement among raft nodes before linearized reading' (duration: 117.733445ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:52:42.2579Z","caller":"traceutil/trace.go:171","msg":"trace[719276586] linearizableReadLoop","detail":"{readStateIndex:2798; appliedIndex:2797; }","duration":"109.218097ms","start":"2026-10-27T14:52:42.148648Z","end":"2026-10-27T14:52:42.257867Z","steps":["trace[719276586] 'read index received' (duration: 108.942351ms)","trace[719276586] 'applied index is now lower than readState.Index' (duration: 274.116µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T14:52:42.257959Z","caller":"traceutil/trace.go:171","msg":"trace[52253952] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"253.862356ms","start":"2026-10-27T14:52:42.004051Z","end":"2026-10-27T14:52:42.257913Z","steps":["trace[52253952] 'process raft request' (duration: 253.59202ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:52:42.258244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.572815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-27T14:52:42.258357Z","caller":"traceutil/trace.go:171","msg":"trace[1098194754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2692; }","duration":"109.739989ms","start":"2026-10-27T14:52:42.148592Z","end":"2026-10-27T14:52:42.258332Z","steps":["trace[1098194754] 'agreement among raft nodes before linearized reading' (duration: 109.408711ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:53:14.647659Z","caller":"traceutil/trace.go:171","msg":"trace[1393615196] transaction","detail":"{read_only:false; response_revision:2890; number_of_response:1; }","duration":"205.631899ms","start":"2026-10-27T14:53:14.441987Z","end":"2026-10-27T14:53:14.647619Z","steps":["trace[1393615196] 'process raft request' (duration: 129.160913ms)","trace[1393615196] 'compare' (duration: 76.253211ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T14:53:14.655953Z","caller":"traceutil/trace.go:171","msg":"trace[222371906] transaction","detail":"{read_only:false; response_revision:2891; number_of_response:1; }","duration":"130.520626ms","start":"2026-10-27T14:53:14.52539Z","end":"2026-10-27T14:53:14.655911Z","steps":["trace[222371906] 'process raft request' (duration: 130.323081ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:53:15.01363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.564643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T14:53:15.013737Z","caller":"traceutil/trace.go:171","msg":"trace[497548000] linearizableReadLoop","detail":"{readStateIndex:3007; appliedIndex:3006; }","duration":"208.026514ms","start":"2026-10-27T14:53:14.805692Z","end":"2026-10-27T14:53:15.013719Z","steps":["trace[497548000] 'read index received' (duration: 51.235816ms)","trace[497548000] 'applied index is now lower than readState.Index' (duration: 156.789248ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T14:53:15.013817Z","caller":"traceutil/trace.go:171","msg":"trace[961753527] transaction","detail":"{read_only:false; response_revision:2893; number_of_response:1; }","duration":"320.315651ms","start":"2026-10-27T14:53:14.693489Z","end":"2026-10-27T14:53:15.013804Z","steps":["trace[961753527] 'process raft request' (duration: 163.458581ms)","trace[961753527] 'compare' (duration: 156.398899ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T14:53:15.013881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T14:53:14.693469Z","time spent":"320.380462ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T14:53:15.014114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.411793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T14:53:15.014232Z","caller":"traceutil/trace.go:171","msg":"trace[639677890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2893; }","duration":"208.593647ms","start":"2026-10-27T14:53:14.805616Z","end":"2026-10-27T14:53:15.01421Z","steps":["trace[639677890] 'agreement among raft nodes before linearized reading' (duration: 208.438004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:56:37.788151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.746568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jpznp\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-10-27T14:56:37.788247Z","caller":"traceutil/trace.go:171","msg":"trace[1231625493] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jpznp; range_end:; response_count:1; response_revision:4160; }","duration":"106.88605ms","start":"2026-10-27T14:56:37.681345Z","end":"2026-10-27T14:56:37.788231Z","steps":["trace[1231625493] 'range keys from in-memory index tree' (duration: 106.577384ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:56:37.977223Z","caller":"traceutil/trace.go:171","msg":"trace[380421127] transaction","detail":"{read_only:false; response_revision:4161; number_of_response:1; }","duration":"153.014458ms","start":"2026-10-27T14:56:37.824185Z","end":"2026-10-27T14:56:37.9772Z","steps":["trace[380421127] 'process raft request' (duration: 152.892885ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:56:37.987359Z","caller":"traceutil/trace.go:171","msg":"trace[374038774] transaction","detail":"{read_only:false; response_revision:4162; number_of_response:1; }","duration":"125.584389ms","start":"2026-10-27T14:56:37.861758Z","end":"2026-10-27T14:56:37.987342Z","steps":["trace[374038774] 'process raft request' (duration: 125.501407ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:13.763602Z","caller":"traceutil/trace.go:171","msg":"trace[1360579030] linearizableReadLoop","detail":"{readStateIndex:5591; appliedIndex:5590; }","duration":"226.253318ms","start":"2026-10-27T14:58:13.537319Z","end":"2026-10-27T14:58:13.763573Z","steps":["trace[1360579030] 'read index received' (duration: 226.150095ms)","trace[1360579030] 'applied index is now lower than readState.Index' (duration: 101.553µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T14:58:13.763664Z","caller":"traceutil/trace.go:171","msg":"trace[1791200686] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"228.157571ms","start":"2026-10-27T14:58:13.53546Z","end":"2026-10-27T14:58:13.763618Z","steps":["trace[1791200686] 'process raft request' (duration: 227.912065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:58:13.763914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.570045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-27T14:58:13.763961Z","caller":"traceutil/trace.go:171","msg":"trace[990653524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5380; }","duration":"226.665497ms","start":"2026-10-27T14:58:13.537284Z","end":"2026-10-27T14:58:13.763949Z","steps":["trace[990653524] 'agreement among raft nodes before linearized reading' (duration: 226.437342ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:13.909137Z","caller":"traceutil/trace.go:171","msg":"trace[351379819] linearizableReadLoop","detail":"{readStateIndex:5593; appliedIndex:5591; }","duration":"145.413904ms","start":"2026-10-27T14:58:13.763689Z","end":"2026-10-27T14:58:13.909103Z","steps":["trace[351379819] 'read index received' (duration: 143.524991ms)","trace[351379819] 'applied index is now lower than readState.Index' (duration: 1.886933ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T14:58:13.909236Z","caller":"traceutil/trace.go:171","msg":"trace[1796750837] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"191.619593ms","start":"2026-10-27T14:58:13.717606Z","end":"2026-10-27T14:58:13.909226Z","steps":["trace[1796750837] 'process raft request' (duration: 191.429879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:58:13.909396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.490438ms","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-27T14:58:13.909429Z","caller":"traceutil/trace.go:171","msg":"trace[2120584221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5382; }","duration":"245.58511ms","start":"2026-10-27T14:58:13.663833Z","end":"2026-10-27T14:58:13.909418Z","steps":["trace[2120584221] 'agreement among raft nodes before linearized reading' (duration: 245.424587ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:13.909168Z","caller":"traceutil/trace.go:171","msg":"trace[2116407265] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"370.011523ms","start":"2026-10-27T14:58:13.539128Z","end":"2026-10-27T14:58:13.90914Z","steps":["trace[2116407265] 'process raft request' (duration: 368.001477ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:58:13.909632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T14:58:13.539115Z","time spent":"370.372632ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T14:58:13.960162Z","caller":"traceutil/trace.go:171","msg":"trace[1650184273] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"190.984269ms","start":"2026-10-27T14:58:13.76915Z","end":"2026-10-27T14:58:13.960134Z","steps":["trace[1650184273] 'process raft request' (duration: 190.687772ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:13.960259Z","caller":"traceutil/trace.go:171","msg":"trace[2110339944] transaction","detail":"{read_only:false; response_revision:5385; number_of_response:1; }","duration":"190.555729ms","start":"2026-10-27T14:58:13.769668Z","end":"2026-10-27T14:58:13.960224Z","steps":["trace[2110339944] 'process raft request' (duration: 190.401876ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:13.960288Z","caller":"traceutil/trace.go:171","msg":"trace[1621816139] transaction","detail":"{read_only:false; response_revision:5384; number_of_response:1; }","duration":"190.683852ms","start":"2026-10-27T14:58:13.769566Z","end":"2026-10-27T14:58:13.96025Z","steps":["trace[1621816139] 'process raft request' (duration: 190.440826ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:13.960415Z","caller":"traceutil/trace.go:171","msg":"trace[1936258944] transaction","detail":"{read_only:false; response_revision:5386; number_of_response:1; }","duration":"189.635137ms","start":"2026-10-27T14:58:13.770759Z","end":"2026-10-27T14:58:13.960394Z","steps":["trace[1936258944] 'process raft request' (duration: 189.387961ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:58:13.960592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.545982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-27T14:58:13.96068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.462379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-tzglc-tls\" ","response":"range_response_count:1 size:2182"} {"level":"info","ts":"2026-10-27T14:58:13.960761Z","caller":"traceutil/trace.go:171","msg":"trace[1426361656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5386; }","duration":"157.750887ms","start":"2026-10-27T14:58:13.802983Z","end":"2026-10-27T14:58:13.960734Z","steps":["trace[1426361656] 'agreement among raft nodes before linearized reading' (duration: 157.485951ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:13.960793Z","caller":"traceutil/trace.go:171","msg":"trace[137606062] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-tzglc-tls; range_end:; response_count:1; response_revision:5386; }","duration":"191.602813ms","start":"2026-10-27T14:58:13.769172Z","end":"2026-10-27T14:58:13.960775Z","steps":["trace[137606062] 'agreement among raft nodes before linearized reading' (duration: 191.339067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:58:13.96064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.004693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-27T14:58:13.960874Z","caller":"traceutil/trace.go:171","msg":"trace[1455870185] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5386; }","duration":"124.29392ms","start":"2026-10-27T14:58:13.836565Z","end":"2026-10-27T14:58:13.960859Z","steps":["trace[1455870185] 'agreement among raft nodes before linearized reading' (duration: 123.961462ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:29.409999Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2973} {"level":"warn","ts":"2026-10-27T14:58:29.867252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.709325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-88ccfb6d-2mwv5\" ","response":"range_response_count:1 size:11836"} {"level":"info","ts":"2026-10-27T14:58:29.86737Z","caller":"traceutil/trace.go:171","msg":"trace[882761397] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-88ccfb6d-2mwv5; range_end:; response_count:1; response_revision:5661; }","duration":"266.881989ms","start":"2026-10-27T14:58:29.600453Z","end":"2026-10-27T14:58:29.867335Z","steps":["trace[882761397] 'range keys from in-memory index tree' (duration: 266.380227ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:29.936861Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2973,"took":"524.536586ms","hash":1887409907,"current-db-size-bytes":54906880,"current-db-size":"55 MB","current-db-size-in-use-bytes":31375360,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-27T14:58:29.936959Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1887409907,"revision":2973,"compact-revision":-1} {"level":"info","ts":"2026-10-27T14:58:32.263518Z","caller":"traceutil/trace.go:171","msg":"trace[633771876] transaction","detail":"{read_only:false; response_revision:5687; number_of_response:1; }","duration":"192.637087ms","start":"2026-10-27T14:58:32.070818Z","end":"2026-10-27T14:58:32.263455Z","steps":["trace[633771876] 'process raft request' (duration: 168.255738ms)","trace[633771876] 'compare' (duration: 24.237106ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T14:58:32.266305Z","caller":"traceutil/trace.go:171","msg":"trace[455732978] linearizableReadLoop","detail":"{readStateIndex:5908; appliedIndex:5906; }","duration":"181.091592ms","start":"2026-10-27T14:58:32.085193Z","end":"2026-10-27T14:58:32.266285Z","steps":["trace[455732978] 'read index received' (duration: 153.877488ms)","trace[455732978] 'applied index is now lower than readState.Index' (duration: 27.213054ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T14:58:32.266671Z","caller":"traceutil/trace.go:171","msg":"trace[1872732730] transaction","detail":"{read_only:false; response_revision:5688; number_of_response:1; }","duration":"194.12585ms","start":"2026-10-27T14:58:32.072524Z","end":"2026-10-27T14:58:32.26665Z","steps":["trace[1872732730] 'process raft request' (duration: 193.6809ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:58:32.266938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.914626ms","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-27T14:58:32.267032Z","caller":"traceutil/trace.go:171","msg":"trace[1775683611] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5688; }","duration":"170.156971ms","start":"2026-10-27T14:58:32.096853Z","end":"2026-10-27T14:58:32.26701Z","steps":["trace[1775683611] 'agreement among raft nodes before linearized reading' (duration: 169.846214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:58:32.267236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.970801ms","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-27T14:58:32.267412Z","caller":"traceutil/trace.go:171","msg":"trace[1598157800] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5688; }","duration":"182.202787ms","start":"2026-10-27T14:58:32.08514Z","end":"2026-10-27T14:58:32.267343Z","steps":["trace[1598157800] 'agreement among raft nodes before linearized reading' (duration: 181.600043ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:37.575864Z","caller":"traceutil/trace.go:171","msg":"trace[1780087753] linearizableReadLoop","detail":"{readStateIndex:6046; appliedIndex:6045; }","duration":"193.832137ms","start":"2026-10-27T14:58:37.382004Z","end":"2026-10-27T14:58:37.575836Z","steps":["trace[1780087753] 'read index received' (duration: 193.647553ms)","trace[1780087753] 'applied index is now lower than readState.Index' (duration: 183.124µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T14:58:37.575938Z","caller":"traceutil/trace.go:171","msg":"trace[1552514600] transaction","detail":"{read_only:false; response_revision:5822; number_of_response:1; }","duration":"205.73151ms","start":"2026-10-27T14:58:37.370165Z","end":"2026-10-27T14:58:37.575897Z","steps":["trace[1552514600] 'process raft request' (duration: 205.476774ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:58:37.576189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.904358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"warn","ts":"2026-10-27T14:58:37.576234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.195166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-40c596f3-7a78-4a55-b524-a9c527708689\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-10-27T14:58:37.576266Z","caller":"traceutil/trace.go:171","msg":"trace[724162233] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5822; }","duration":"166.021941ms","start":"2026-10-27T14:58:37.410228Z","end":"2026-10-27T14:58:37.57625Z","steps":["trace[724162233] 'agreement among raft nodes before linearized reading' (duration: 165.819056ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:37.576957Z","caller":"traceutil/trace.go:171","msg":"trace[1653944248] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-40c596f3-7a78-4a55-b524-a9c527708689; range_end:; response_count:1; response_revision:5822; }","duration":"194.287527ms","start":"2026-10-27T14:58:37.381979Z","end":"2026-10-27T14:58:37.576267Z","steps":["trace[1653944248] 'agreement among raft nodes before linearized reading' (duration: 194.017591ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:37.756323Z","caller":"traceutil/trace.go:171","msg":"trace[467527844] transaction","detail":"{read_only:false; response_revision:5823; number_of_response:1; }","duration":"161.908496ms","start":"2026-10-27T14:58:37.594375Z","end":"2026-10-27T14:58:37.756283Z","steps":["trace[467527844] 'process raft request' (duration: 161.65443ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:38.050295Z","caller":"traceutil/trace.go:171","msg":"trace[164612797] transaction","detail":"{read_only:false; response_revision:5825; number_of_response:1; }","duration":"287.02842ms","start":"2026-10-27T14:58:37.763233Z","end":"2026-10-27T14:58:38.050261Z","steps":["trace[164612797] 'process raft request' (duration: 286.774164ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:38.050848Z","caller":"traceutil/trace.go:171","msg":"trace[593060012] transaction","detail":"{read_only:false; response_revision:5826; number_of_response:1; }","duration":"287.421469ms","start":"2026-10-27T14:58:37.763364Z","end":"2026-10-27T14:58:38.050786Z","steps":["trace[593060012] 'process raft request' (duration: 286.814795ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:38.05111Z","caller":"traceutil/trace.go:171","msg":"trace[749192389] transaction","detail":"{read_only:false; response_revision:5828; number_of_response:1; }","duration":"287.615394ms","start":"2026-10-27T14:58:37.76347Z","end":"2026-10-27T14:58:38.051086Z","steps":["trace[749192389] 'process raft request' (duration: 287.338637ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:38.051311Z","caller":"traceutil/trace.go:171","msg":"trace[659523584] transaction","detail":"{read_only:false; response_revision:5827; number_of_response:1; }","duration":"287.879889ms","start":"2026-10-27T14:58:37.763413Z","end":"2026-10-27T14:58:38.051293Z","steps":["trace[659523584] 'process raft request' (duration: 287.140052ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:38.051331Z","caller":"traceutil/trace.go:171","msg":"trace[688463943] transaction","detail":"{read_only:false; response_revision:5829; number_of_response:1; }","duration":"286.804835ms","start":"2026-10-27T14:58:37.764509Z","end":"2026-10-27T14:58:38.051313Z","steps":["trace[688463943] 'process raft request' (duration: 286.416656ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:38.051478Z","caller":"traceutil/trace.go:171","msg":"trace[2059806949] linearizableReadLoop","detail":"{readStateIndex:6053; appliedIndex:6047; }","duration":"247.949495ms","start":"2026-10-27T14:58:37.803045Z","end":"2026-10-27T14:58:38.050994Z","steps":["trace[2059806949] 'read index received' (duration: 24.069011ms)","trace[2059806949] 'applied index is now lower than readState.Index' (duration: 223.879164ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T14:58:38.051678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.62466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T14:58:38.051729Z","caller":"traceutil/trace.go:171","msg":"trace[601939952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5829; }","duration":"248.743133ms","start":"2026-10-27T14:58:37.802969Z","end":"2026-10-27T14:58:38.051712Z","steps":["trace[601939952] 'agreement among raft nodes before linearized reading' (duration: 248.638851ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T14:58:38.223373Z","caller":"traceutil/trace.go:171","msg":"trace[740574373] transaction","detail":"{read_only:false; response_revision:5831; number_of_response:1; }","duration":"148.847368ms","start":"2026-10-27T14:58:38.074487Z","end":"2026-10-27T14:58:38.223335Z","steps":["trace[740574373] 'process raft request' (duration: 63.110135ms)","trace[740574373] 'compare' (duration: 85.512657ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T14:59:32.792306Z","caller":"traceutil/trace.go:171","msg":"trace[1595791028] linearizableReadLoop","detail":"{readStateIndex:6580; appliedIndex:6579; }","duration":"105.957235ms","start":"2026-10-27T14:59:32.686308Z","end":"2026-10-27T14:59:32.792266Z","steps":["trace[1595791028] 'read index received' (duration: 33.977237ms)","trace[1595791028] 'applied index is now lower than readState.Index' (duration: 71.978458ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T14:59:32.792357Z","caller":"traceutil/trace.go:171","msg":"trace[1478208583] transaction","detail":"{read_only:false; response_revision:6343; number_of_response:1; }","duration":"107.824299ms","start":"2026-10-27T14:59:32.6845Z","end":"2026-10-27T14:59:32.792325Z","steps":["trace[1478208583] 'process raft request' (duration: 74.472965ms)","trace[1478208583] 'compare' (duration: 33.112458ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T14:59:32.792759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.424557ms","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-27T14:59:32.792909Z","caller":"traceutil/trace.go:171","msg":"trace[757688517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6343; }","duration":"106.56565ms","start":"2026-10-27T14:59:32.68628Z","end":"2026-10-27T14:59:32.792846Z","steps":["trace[757688517] 'agreement among raft nodes before linearized reading' (duration: 106.14862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T14:59:32.792961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.414543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-10-27T14:59:32.793027Z","caller":"traceutil/trace.go:171","msg":"trace[1157082181] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:6343; }","duration":"105.551246ms","start":"2026-10-27T14:59:32.68746Z","end":"2026-10-27T14:59:32.793011Z","steps":["trace[1157082181] 'agreement among raft nodes before linearized reading' (duration: 105.301271ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:03:29.418489Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5655} {"level":"info","ts":"2026-10-27T15:03:29.521587Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5655,"took":"100.851368ms","hash":2928082464,"current-db-size-bytes":54906880,"current-db-size":"55 MB","current-db-size-in-use-bytes":27680768,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-27T15:03:29.521675Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2928082464,"revision":5655,"compact-revision":2973} {"level":"info","ts":"2026-10-27T15:05:16.963298Z","caller":"traceutil/trace.go:171","msg":"trace[1306634830] linearizableReadLoop","detail":"{readStateIndex:9140; appliedIndex:9139; }","duration":"148.661042ms","start":"2026-10-27T15:05:16.814606Z","end":"2026-10-27T15:05:16.963268Z","steps":["trace[1306634830] 'read index received' (duration: 148.408096ms)","trace[1306634830] 'applied index is now lower than readState.Index' (duration: 252.166µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:05:16.963453Z","caller":"traceutil/trace.go:171","msg":"trace[1101765469] transaction","detail":"{read_only:false; response_revision:8820; number_of_response:1; }","duration":"629.35784ms","start":"2026-10-27T15:05:16.334054Z","end":"2026-10-27T15:05:16.963412Z","steps":["trace[1101765469] 'process raft request' (duration: 629.052843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:05:16.963602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.968899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-27T15:05:16.963702Z","caller":"traceutil/trace.go:171","msg":"trace[678202960] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8820; }","duration":"149.134923ms","start":"2026-10-27T15:05:16.814547Z","end":"2026-10-27T15:05:16.963682Z","steps":["trace[678202960] 'agreement among raft nodes before linearized reading' (duration: 148.872297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:05:16.963738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:05:16.334026Z","time spent":"629.536415ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:05:20.19503Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023c8.wal"} {"level":"warn","ts":"2026-10-27T15:05:31.267878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.54446ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T15:05:31.268122Z","caller":"traceutil/trace.go:171","msg":"trace[2093251399] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8890; }","duration":"117.824077ms","start":"2026-10-27T15:05:31.150267Z","end":"2026-10-27T15:05:31.268092Z","steps":["trace[2093251399] 'range keys from in-memory index tree' (duration: 117.513739ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:05:31.268258Z","caller":"traceutil/trace.go:171","msg":"trace[2036677942] linearizableReadLoop","detail":"{readStateIndex:9214; appliedIndex:9213; }","duration":"144.943057ms","start":"2026-10-27T15:05:31.123276Z","end":"2026-10-27T15:05:31.268219Z","steps":["trace[2036677942] 'read index received' (duration: 75.519108ms)","trace[2036677942] 'applied index is now lower than readState.Index' (duration: 69.421768ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:05:31.268444Z","caller":"traceutil/trace.go:171","msg":"trace[1186200810] transaction","detail":"{read_only:false; response_revision:8891; number_of_response:1; }","duration":"157.77662ms","start":"2026-10-27T15:05:31.110647Z","end":"2026-10-27T15:05:31.268424Z","steps":["trace[1186200810] 'process raft request' (duration: 88.301171ms)","trace[1186200810] 'compare' (duration: 69.032649ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T15:05:31.268737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.370417ms","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-27T15:05:31.26879Z","caller":"traceutil/trace.go:171","msg":"trace[1508368987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8891; }","duration":"107.49985ms","start":"2026-10-27T15:05:31.161278Z","end":"2026-10-27T15:05:31.268778Z","steps":["trace[1508368987] 'agreement among raft nodes before linearized reading' (duration: 107.307316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:05:31.268981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.631193ms","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-27T15:05:31.269111Z","caller":"traceutil/trace.go:171","msg":"trace[846884106] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8891; }","duration":"145.853047ms","start":"2026-10-27T15:05:31.123227Z","end":"2026-10-27T15:05:31.26908Z","steps":["trace[846884106] 'agreement among raft nodes before linearized reading' (duration: 145.453548ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:06:02.418748Z","caller":"traceutil/trace.go:171","msg":"trace[18599167] transaction","detail":"{read_only:false; response_revision:9114; number_of_response:1; }","duration":"189.261501ms","start":"2026-10-27T15:06:02.229461Z","end":"2026-10-27T15:06:02.418722Z","steps":["trace[18599167] 'process raft request' (duration: 189.052756ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:06:02.631287Z","caller":"traceutil/trace.go:171","msg":"trace[1848914992] transaction","detail":"{read_only:false; response_revision:9115; number_of_response:1; }","duration":"328.152688ms","start":"2026-10-27T15:06:02.303065Z","end":"2026-10-27T15:06:02.631218Z","steps":["trace[1848914992] 'process raft request' (duration: 260.04831ms)","trace[1848914992] 'compare' (duration: 67.607097ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:06:02.631306Z","caller":"traceutil/trace.go:171","msg":"trace[1668925790] transaction","detail":"{read_only:false; response_revision:9116; number_of_response:1; }","duration":"175.72308ms","start":"2026-10-27T15:06:02.455541Z","end":"2026-10-27T15:06:02.631265Z","steps":["trace[1668925790] 'process raft request' (duration: 175.561447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:06:02.631517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:06:02.303036Z","time spent":"328.358383ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:06:05.697351Z","caller":"traceutil/trace.go:171","msg":"trace[729687870] linearizableReadLoop","detail":"{readStateIndex:9464; appliedIndex:9463; }","duration":"126.022564ms","start":"2026-10-27T15:06:05.571287Z","end":"2026-10-27T15:06:05.697309Z","steps":["trace[729687870] 'read index received' (duration: 125.820599ms)","trace[729687870] 'applied index is now lower than readState.Index' (duration: 200.325µs)"],"step_count":2} {"level":"warn","ts":"2026-10-27T15:06:05.697652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.342761ms","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-27T15:06:05.697764Z","caller":"traceutil/trace.go:171","msg":"trace[262273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9131; }","duration":"126.519615ms","start":"2026-10-27T15:06:05.57122Z","end":"2026-10-27T15:06:05.69774Z","steps":["trace[262273] 'agreement among raft nodes before linearized reading' (duration: 126.222838ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:06:05.698243Z","caller":"traceutil/trace.go:171","msg":"trace[331910020] transaction","detail":"{read_only:false; response_revision:9131; number_of_response:1; }","duration":"202.104515ms","start":"2026-10-27T15:06:05.496119Z","end":"2026-10-27T15:06:05.698224Z","steps":["trace[331910020] 'process raft request' (duration: 200.948028ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:06:05.703573Z","caller":"traceutil/trace.go:171","msg":"trace[1852518633] transaction","detail":"{read_only:false; response_revision:9132; number_of_response:1; }","duration":"116.053695ms","start":"2026-10-27T15:06:05.587485Z","end":"2026-10-27T15:06:05.703539Z","steps":["trace[1852518633] 'process raft request' (duration: 115.876561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:06:05.70361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.954202ms","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-27T15:06:05.703649Z","caller":"traceutil/trace.go:171","msg":"trace[627663791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9132; }","duration":"119.024774ms","start":"2026-10-27T15:06:05.584612Z","end":"2026-10-27T15:06:05.703637Z","steps":["trace[627663791] 'agreement among raft nodes before linearized reading' (duration: 118.922271ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:06:05.703686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.815539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-27T15:06:05.703602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.183419ms","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-27T15:06:05.703795Z","caller":"traceutil/trace.go:171","msg":"trace[1263702757] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9132; }","duration":"130.400074ms","start":"2026-10-27T15:06:05.573379Z","end":"2026-10-27T15:06:05.703779Z","steps":["trace[1263702757] 'agreement among raft nodes before linearized reading' (duration: 130.108917ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:06:05.703721Z","caller":"traceutil/trace.go:171","msg":"trace[1295224763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9132; }","duration":"125.87191ms","start":"2026-10-27T15:06:05.577838Z","end":"2026-10-27T15:06:05.70371Z","steps":["trace[1295224763] 'agreement among raft nodes before linearized reading' (duration: 125.795879ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:06:15.432622Z","caller":"traceutil/trace.go:171","msg":"trace[1148464309] transaction","detail":"{read_only:false; response_revision:9184; number_of_response:1; }","duration":"135.848828ms","start":"2026-10-27T15:06:15.296738Z","end":"2026-10-27T15:06:15.432586Z","steps":["trace[1148464309] 'process raft request' (duration: 135.629793ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:07:06.102178Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T15:07:06.108531Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-27T15:07:06.108719Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-27T15:08:29.426501Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7977} {"level":"info","ts":"2026-10-27T15:08:29.514447Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7977,"took":"85.578878ms","hash":2189795434,"current-db-size-bytes":54906880,"current-db-size":"55 MB","current-db-size-in-use-bytes":25292800,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-27T15:08:29.514512Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2189795434,"revision":7977,"compact-revision":5655} {"level":"info","ts":"2026-10-27T15:13:29.52449Z","caller":"traceutil/trace.go:171","msg":"trace[1395233869] transaction","detail":"{read_only:false; response_revision:12508; number_of_response:1; }","duration":"139.356496ms","start":"2026-10-27T15:13:29.385106Z","end":"2026-10-27T15:13:29.524462Z","steps":["trace[1395233869] 'process raft request' (duration: 139.192523ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:13:29.726107Z","caller":"traceutil/trace.go:171","msg":"trace[527094248] linearizableReadLoop","detail":"{readStateIndex:12948; appliedIndex:12946; }","duration":"294.888043ms","start":"2026-10-27T15:13:29.431187Z","end":"2026-10-27T15:13:29.726075Z","steps":["trace[527094248] 'read index received' (duration: 93.101809ms)","trace[527094248] 'applied index is now lower than readState.Index' (duration: 201.785124ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:13:29.726176Z","caller":"traceutil/trace.go:171","msg":"trace[1316810370] transaction","detail":"{read_only:false; response_revision:12509; number_of_response:1; }","duration":"297.803659ms","start":"2026-10-27T15:13:29.428331Z","end":"2026-10-27T15:13:29.726134Z","steps":["trace[1316810370] 'process raft request' (duration: 284.362642ms)","trace[1316810370] 'compare' (duration: 13.272004ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T15:13:29.72638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.180789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jpznp\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-10-27T15:13:29.726424Z","caller":"traceutil/trace.go:171","msg":"trace[1548111591] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jpznp; range_end:; response_count:1; response_revision:12509; }","duration":"295.286422ms","start":"2026-10-27T15:13:29.431129Z","end":"2026-10-27T15:13:29.726416Z","steps":["trace[1548111591] 'agreement among raft nodes before linearized reading' (duration: 295.081607ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:13:29.730531Z","caller":"traceutil/trace.go:171","msg":"trace[199158697] transaction","detail":"{read_only:false; response_revision:12510; number_of_response:1; }","duration":"146.51303ms","start":"2026-10-27T15:13:29.58401Z","end":"2026-10-27T15:13:29.730523Z","steps":["trace[199158697] 'process raft request' (duration: 146.430588ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:13:29.883333Z","caller":"traceutil/trace.go:171","msg":"trace[1579189306] transaction","detail":"{read_only:false; response_revision:12511; number_of_response:1; }","duration":"170.396786ms","start":"2026-10-27T15:13:29.712827Z","end":"2026-10-27T15:13:29.883224Z","steps":["trace[1579189306] 'process raft request' (duration: 154.011771ms)","trace[1579189306] 'compare' (duration: 16.18331ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:13:29.893108Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10357} {"level":"info","ts":"2026-10-27T15:13:29.893249Z","caller":"traceutil/trace.go:171","msg":"trace[1327617355] compact","detail":"{revision:10357; response_revision:12511; }","duration":"166.131519ms","start":"2026-10-27T15:13:29.727089Z","end":"2026-10-27T15:13:29.893221Z","steps":["trace[1327617355] 'process raft request' (duration: 156.038698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:13:29.893574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.326288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12426"} {"level":"info","ts":"2026-10-27T15:13:29.893637Z","caller":"traceutil/trace.go:171","msg":"trace[1806132289] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12511; }","duration":"269.443821ms","start":"2026-10-27T15:13:29.624168Z","end":"2026-10-27T15:13:29.893612Z","steps":["trace[1806132289] 'agreement among raft nodes before linearized reading' (duration: 269.200055ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:13:29.893182Z","caller":"traceutil/trace.go:171","msg":"trace[430275458] linearizableReadLoop","detail":"{readStateIndex:12950; appliedIndex:12948; }","duration":"166.969448ms","start":"2026-10-27T15:13:29.726194Z","end":"2026-10-27T15:13:29.893164Z","steps":["trace[430275458] 'read index received' (duration: 4.203946ms)","trace[430275458] 'applied index is now lower than readState.Index' (duration: 162.764461ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T15:13:29.894545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.460972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:48 size:450032"} {"level":"info","ts":"2026-10-27T15:13:29.894593Z","caller":"traceutil/trace.go:171","msg":"trace[135021355] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:48; response_revision:12511; }","duration":"161.548754ms","start":"2026-10-27T15:13:29.733033Z","end":"2026-10-27T15:13:29.894581Z","steps":["trace[135021355] 'agreement among raft nodes before linearized reading' (duration: 160.964951ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:13:30.134934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10357,"took":"237.639703ms","hash":3818117747,"current-db-size-bytes":54906880,"current-db-size":"55 MB","current-db-size-in-use-bytes":25665536,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-27T15:13:30.13503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3818117747,"revision":10357,"compact-revision":7977} {"level":"info","ts":"2026-10-27T15:13:30.851387Z","caller":"traceutil/trace.go:171","msg":"trace[2040115228] transaction","detail":"{read_only:false; response_revision:12518; number_of_response:1; }","duration":"192.580683ms","start":"2026-10-27T15:13:30.658769Z","end":"2026-10-27T15:13:30.851349Z","steps":["trace[2040115228] 'process raft request' (duration: 192.02499ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:13:30.963701Z","caller":"traceutil/trace.go:171","msg":"trace[245776193] linearizableReadLoop","detail":"{readStateIndex:12959; appliedIndex:12957; }","duration":"161.089483ms","start":"2026-10-27T15:13:30.802582Z","end":"2026-10-27T15:13:30.963672Z","steps":["trace[245776193] 'read index received' (duration: 48.157211ms)","trace[245776193] 'applied index is now lower than readState.Index' (duration: 112.931192ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:13:30.96393Z","caller":"traceutil/trace.go:171","msg":"trace[79986380] transaction","detail":"{read_only:false; response_revision:12519; number_of_response:1; }","duration":"297.703317ms","start":"2026-10-27T15:13:30.666212Z","end":"2026-10-27T15:13:30.963915Z","steps":["trace[79986380] 'process raft request' (duration: 224.153345ms)","trace[79986380] 'compare' (duration: 73.011489ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T15:13:30.964133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.539093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T15:13:30.964166Z","caller":"traceutil/trace.go:171","msg":"trace[269536166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12519; }","duration":"161.624995ms","start":"2026-10-27T15:13:30.802533Z","end":"2026-10-27T15:13:30.964158Z","steps":["trace[269536166] 'agreement among raft nodes before linearized reading' (duration: 161.551374ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:18:29.901623Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12509} {"level":"info","ts":"2026-10-27T15:18:29.992942Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12509,"took":"86.746144ms","hash":1162857973,"current-db-size-bytes":54906880,"current-db-size":"55 MB","current-db-size-in-use-bytes":27815936,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-27T15:18:29.993044Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1162857973,"revision":12509,"compact-revision":10357} {"level":"info","ts":"2026-10-27T15:20:14.708682Z","caller":"traceutil/trace.go:171","msg":"trace[1899480522] transaction","detail":"{read_only:false; response_revision:15184; number_of_response:1; }","duration":"185.841529ms","start":"2026-10-27T15:20:14.522812Z","end":"2026-10-27T15:20:14.708653Z","steps":["trace[1899480522] 'process raft request' (duration: 185.642555ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:20:18.313021Z","caller":"traceutil/trace.go:171","msg":"trace[1912943657] transaction","detail":"{read_only:false; response_revision:15203; number_of_response:1; }","duration":"132.626352ms","start":"2026-10-27T15:20:18.180354Z","end":"2026-10-27T15:20:18.31298Z","steps":["trace[1912943657] 'process raft request' (duration: 132.386546ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:20:39.558341Z","caller":"traceutil/trace.go:171","msg":"trace[978383600] transaction","detail":"{read_only:false; response_revision:15315; number_of_response:1; }","duration":"164.122912ms","start":"2026-10-27T15:20:39.39419Z","end":"2026-10-27T15:20:39.558313Z","steps":["trace[978383600] 'process raft request' (duration: 163.939688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:20:49.278431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.718944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-27T15:20:49.27847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.791879ms","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-27T15:20:49.278542Z","caller":"traceutil/trace.go:171","msg":"trace[1051085533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15362; }","duration":"104.853267ms","start":"2026-10-27T15:20:49.173664Z","end":"2026-10-27T15:20:49.278518Z","steps":["trace[1051085533] 'range keys from in-memory index tree' (duration: 104.5586ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:20:49.278566Z","caller":"traceutil/trace.go:171","msg":"trace[1158586458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15362; }","duration":"112.952672ms","start":"2026-10-27T15:20:49.165588Z","end":"2026-10-27T15:20:49.27854Z","steps":["trace[1158586458] 'range keys from in-memory index tree' (duration: 112.571624ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:20:49.278567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.491849ms","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-27T15:20:49.278656Z","caller":"traceutil/trace.go:171","msg":"trace[788033179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15362; }","duration":"111.637182ms","start":"2026-10-27T15:20:49.166997Z","end":"2026-10-27T15:20:49.278634Z","steps":["trace[788033179] 'range keys from in-memory index tree' (duration: 111.256354ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:20:57.293003Z","caller":"traceutil/trace.go:171","msg":"trace[90206353] transaction","detail":"{read_only:false; response_revision:15417; number_of_response:1; }","duration":"164.385869ms","start":"2026-10-27T15:20:57.128587Z","end":"2026-10-27T15:20:57.292973Z","steps":["trace[90206353] 'process raft request' (duration: 164.162114ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:20:57.351617Z","caller":"traceutil/trace.go:171","msg":"trace[717837424] transaction","detail":"{read_only:false; response_revision:15418; number_of_response:1; }","duration":"215.990428ms","start":"2026-10-27T15:20:57.135574Z","end":"2026-10-27T15:20:57.351565Z","steps":["trace[717837424] 'process raft request' (duration: 215.663191ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:20:58.155106Z","caller":"traceutil/trace.go:171","msg":"trace[1358227259] linearizableReadLoop","detail":"{readStateIndex:15967; appliedIndex:15966; }","duration":"351.594558ms","start":"2026-10-27T15:20:57.803484Z","end":"2026-10-27T15:20:58.155079Z","steps":["trace[1358227259] 'read index received' (duration: 351.301032ms)","trace[1358227259] 'applied index is now lower than readState.Index' (duration: 288.146µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:20:58.155229Z","caller":"traceutil/trace.go:171","msg":"trace[712659697] transaction","detail":"{read_only:false; response_revision:15423; number_of_response:1; }","duration":"357.943093ms","start":"2026-10-27T15:20:57.797275Z","end":"2026-10-27T15:20:58.155218Z","steps":["trace[712659697] 'process raft request' (duration: 357.620356ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:20:58.15534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:20:57.797245Z","time spent":"358.011205ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T15:20:58.155487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.595624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-27T15:20:58.155504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.983927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T15:20:58.155646Z","caller":"traceutil/trace.go:171","msg":"trace[1349313753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15423; }","duration":"352.178192ms","start":"2026-10-27T15:20:57.803437Z","end":"2026-10-27T15:20:58.155615Z","steps":["trace[1349313753] 'agreement among raft nodes before linearized reading' (duration: 351.914286ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:20:58.155641Z","caller":"traceutil/trace.go:171","msg":"trace[1558417854] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:15423; }","duration":"206.788668ms","start":"2026-10-27T15:20:57.948813Z","end":"2026-10-27T15:20:58.155602Z","steps":["trace[1558417854] 'agreement among raft nodes before linearized reading' (duration: 206.496302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:20:58.155689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:20:57.803413Z","time spent":"352.267424ms","remote":"127.0.0.1:44378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-27T15:20:58.392059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.819526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jpznp\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-10-27T15:20:58.39219Z","caller":"traceutil/trace.go:171","msg":"trace[481316604] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jpznp; range_end:; response_count:1; response_revision:15423; }","duration":"199.013531ms","start":"2026-10-27T15:20:58.193142Z","end":"2026-10-27T15:20:58.392155Z","steps":["trace[481316604] 'range keys from in-memory index tree' (duration: 198.504978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:21:23.21682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.133942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.4\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-27T15:21:23.216956Z","caller":"traceutil/trace.go:171","msg":"trace[35159888] range","detail":"{range_begin:/registry/masterleases/162.253.55.4; range_end:; response_count:1; response_revision:15748; }","duration":"108.331727ms","start":"2026-10-27T15:21:23.108601Z","end":"2026-10-27T15:21:23.216932Z","steps":["trace[35159888] 'range keys from in-memory index tree' (duration: 107.922308ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:21:23.679769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.400372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T15:21:23.679913Z","caller":"traceutil/trace.go:171","msg":"trace[759813949] linearizableReadLoop","detail":"{readStateIndex:16307; appliedIndex:16305; }","duration":"397.348565ms","start":"2026-10-27T15:21:23.282545Z","end":"2026-10-27T15:21:23.679894Z","steps":["trace[759813949] 'read index received' (duration: 12.201969ms)","trace[759813949] 'applied index is now lower than readState.Index' (duration: 385.145216ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:21:23.679988Z","caller":"traceutil/trace.go:171","msg":"trace[1613216029] transaction","detail":"{read_only:false; response_revision:15749; number_of_response:1; }","duration":"457.881178ms","start":"2026-10-27T15:21:23.222048Z","end":"2026-10-27T15:21:23.679929Z","steps":["trace[1613216029] 'process raft request' (duration: 348.204401ms)","trace[1613216029] 'compare' (duration: 109.148355ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T15:21:23.68003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.483418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T15:21:23.680092Z","caller":"traceutil/trace.go:171","msg":"trace[710367471] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:15749; }","duration":"397.57582ms","start":"2026-10-27T15:21:23.282497Z","end":"2026-10-27T15:21:23.680073Z","steps":["trace[710367471] 'agreement among raft nodes before linearized reading' (duration: 397.475067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:21:23.680149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:21:23.282479Z","time spent":"397.655242ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-27T15:21:23.68044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:21:23.222021Z","time spent":"458.066903ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:21:23.707393Z","caller":"traceutil/trace.go:171","msg":"trace[27194196] transaction","detail":"{read_only:false; response_revision:15750; number_of_response:1; }","duration":"411.419267ms","start":"2026-10-27T15:21:23.295941Z","end":"2026-10-27T15:21:23.70736Z","steps":["trace[27194196] 'process raft request' (duration: 411.184851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:21:23.707539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.204458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-27T15:21:23.707602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:21:23.295926Z","time spent":"411.570381ms","remote":"127.0.0.1:44426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":117,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T15:21:23.707619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.09603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e39357c0-640a-4bad-9bf7-e46e982dfee4\" ","response":"range_response_count:1 size:3635"} {"level":"info","ts":"2026-10-27T15:21:23.70767Z","caller":"traceutil/trace.go:171","msg":"trace[487763895] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e39357c0-640a-4bad-9bf7-e46e982dfee4; range_end:; response_count:1; response_revision:15750; }","duration":"167.182302ms","start":"2026-10-27T15:21:23.540472Z","end":"2026-10-27T15:21:23.707655Z","steps":["trace[487763895] 'agreement among raft nodes before linearized reading' (duration: 167.027449ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:21:23.707686Z","caller":"traceutil/trace.go:171","msg":"trace[2039969302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15750; }","duration":"148.362732ms","start":"2026-10-27T15:21:23.559274Z","end":"2026-10-27T15:21:23.707637Z","steps":["trace[2039969302] 'agreement among raft nodes before linearized reading' (duration: 148.040105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:21:23.707713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.903312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-27T15:21:23.707788Z","caller":"traceutil/trace.go:171","msg":"trace[253049222] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15750; }","duration":"260.030625ms","start":"2026-10-27T15:21:23.447737Z","end":"2026-10-27T15:21:23.707767Z","steps":["trace[253049222] 'agreement among raft nodes before linearized reading' (duration: 259.81498ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:21:23.707779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.862652ms","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-27T15:21:23.707927Z","caller":"traceutil/trace.go:171","msg":"trace[1861757838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15750; }","duration":"194.098638ms","start":"2026-10-27T15:21:23.513801Z","end":"2026-10-27T15:21:23.7079Z","steps":["trace[1861757838] 'agreement among raft nodes before linearized reading' (duration: 193.672638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:21:23.707453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.025307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-27T15:21:23.708173Z","caller":"traceutil/trace.go:171","msg":"trace[67438217] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:15750; }","duration":"397.749024ms","start":"2026-10-27T15:21:23.310385Z","end":"2026-10-27T15:21:23.708134Z","steps":["trace[67438217] 'agreement among raft nodes before linearized reading' (duration: 396.915275ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:21:23.708299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:21:23.310374Z","time spent":"397.871777ms","remote":"127.0.0.1:44780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":89,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-10-27T15:21:23.708425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.963043ms","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-27T15:21:23.708573Z","caller":"traceutil/trace.go:171","msg":"trace[1237410596] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15750; }","duration":"148.196909ms","start":"2026-10-27T15:21:23.560346Z","end":"2026-10-27T15:21:23.708543Z","steps":["trace[1237410596] 'agreement among raft nodes before linearized reading' (duration: 147.061143ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:21:23.847751Z","caller":"traceutil/trace.go:171","msg":"trace[137774720] transaction","detail":"{read_only:false; response_revision:15755; number_of_response:1; }","duration":"129.210934ms","start":"2026-10-27T15:21:23.718513Z","end":"2026-10-27T15:21:23.847724Z","steps":["trace[137774720] 'process raft request' (duration: 95.896612ms)","trace[137774720] 'compare' (duration: 32.705168ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:22:16.965577Z","caller":"traceutil/trace.go:171","msg":"trace[85631051] transaction","detail":"{read_only:false; response_revision:16388; number_of_response:1; }","duration":"130.022843ms","start":"2026-10-27T15:22:16.835524Z","end":"2026-10-27T15:22:16.965547Z","steps":["trace[85631051] 'process raft request' (duration: 129.676595ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:22:50.899162Z","caller":"traceutil/trace.go:171","msg":"trace[1266943788] transaction","detail":"{read_only:false; response_revision:16567; number_of_response:1; }","duration":"103.612329ms","start":"2026-10-27T15:22:50.79549Z","end":"2026-10-27T15:22:50.899102Z","steps":["trace[1266943788] 'process raft request' (duration: 103.178489ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:22:51.231428Z","caller":"traceutil/trace.go:171","msg":"trace[1001519629] transaction","detail":"{read_only:false; response_revision:16568; number_of_response:1; }","duration":"126.414899ms","start":"2026-10-27T15:22:51.104988Z","end":"2026-10-27T15:22:51.231403Z","steps":["trace[1001519629] 'process raft request' (duration: 126.244355ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:22:51.747193Z","caller":"traceutil/trace.go:171","msg":"trace[911728294] linearizableReadLoop","detail":"{readStateIndex:17150; appliedIndex:17149; }","duration":"152.153999ms","start":"2026-10-27T15:22:51.595014Z","end":"2026-10-27T15:22:51.747168Z","steps":["trace[911728294] 'read index received' (duration: 146.442909ms)","trace[911728294] 'applied index is now lower than readState.Index' (duration: 5.70547ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:22:51.747565Z","caller":"traceutil/trace.go:171","msg":"trace[604683058] transaction","detail":"{read_only:false; response_revision:16570; number_of_response:1; }","duration":"285.285803ms","start":"2026-10-27T15:22:51.462267Z","end":"2026-10-27T15:22:51.747553Z","steps":["trace[604683058] 'process raft request' (duration: 279.227875ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:22:51.748063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.040179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-ps6zm\" ","response":"range_response_count:1 size:9790"} {"level":"info","ts":"2026-10-27T15:22:51.748322Z","caller":"traceutil/trace.go:171","msg":"trace[1918027545] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-ps6zm; range_end:; response_count:1; response_revision:16570; }","duration":"153.374046ms","start":"2026-10-27T15:22:51.594936Z","end":"2026-10-27T15:22:51.74831Z","steps":["trace[1918027545] 'agreement among raft nodes before linearized reading' (duration: 153.002868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:22:51.748233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.471153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jpznp\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-27T15:22:51.748903Z","caller":"traceutil/trace.go:171","msg":"trace[1146828782] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jpznp; range_end:; response_count:1; response_revision:16570; }","duration":"110.242071ms","start":"2026-10-27T15:22:51.638652Z","end":"2026-10-27T15:22:51.748894Z","steps":["trace[1146828782] 'agreement among raft nodes before linearized reading' (duration: 109.34811ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:23:29.911119Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14536} {"level":"info","ts":"2026-10-27T15:23:29.997138Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14536,"took":"82.776722ms","hash":1928216595,"current-db-size-bytes":54906880,"current-db-size":"55 MB","current-db-size-in-use-bytes":29855744,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-27T15:23:29.997225Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1928216595,"revision":14536,"compact-revision":12509} {"level":"info","ts":"2026-10-27T15:28:29.921798Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16769} {"level":"info","ts":"2026-10-27T15:28:30.032279Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16769,"took":"103.862755ms","hash":1021067011,"current-db-size-bytes":54906880,"current-db-size":"55 MB","current-db-size-in-use-bytes":31526912,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T15:28:30.032407Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1021067011,"revision":16769,"compact-revision":14536} {"level":"info","ts":"2026-10-27T15:28:59.286668Z","caller":"traceutil/trace.go:171","msg":"trace[1362937945] transaction","detail":"{read_only:false; response_revision:19249; number_of_response:1; }","duration":"231.858891ms","start":"2026-10-27T15:28:59.054773Z","end":"2026-10-27T15:28:59.286632Z","steps":["trace[1362937945] 'process raft request' (duration: 231.663517ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:29:06.827533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.991917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-27T15:29:06.827641Z","caller":"traceutil/trace.go:171","msg":"trace[2127172357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19291; }","duration":"191.183662ms","start":"2026-10-27T15:29:06.636437Z","end":"2026-10-27T15:29:06.827621Z","steps":["trace[2127172357] 'range keys from in-memory index tree' (duration: 190.771642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:29:06.82795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.473484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-27T15:29:06.828037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.372599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-27T15:29:06.828046Z","caller":"traceutil/trace.go:171","msg":"trace[1095116182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19291; }","duration":"200.608327ms","start":"2026-10-27T15:29:06.627417Z","end":"2026-10-27T15:29:06.828026Z","steps":["trace[1095116182] 'range keys from in-memory index tree' (duration: 200.294719ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:29:06.828074Z","caller":"traceutil/trace.go:171","msg":"trace[956638924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19291; }","duration":"192.453481ms","start":"2026-10-27T15:29:06.635607Z","end":"2026-10-27T15:29:06.828061Z","steps":["trace[956638924] 'range keys from in-memory index tree' (duration: 192.195185ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:29:06.827948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.424833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-bgjbn\" ","response":"range_response_count:1 size:20886"} {"level":"info","ts":"2026-10-27T15:29:06.828196Z","caller":"traceutil/trace.go:171","msg":"trace[1026061444] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-bgjbn; range_end:; response_count:1; response_revision:19291; }","duration":"221.703529ms","start":"2026-10-27T15:29:06.606443Z","end":"2026-10-27T15:29:06.828146Z","steps":["trace[1026061444] 'range keys from in-memory index tree' (duration: 221.212148ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:29:06.993343Z","caller":"traceutil/trace.go:171","msg":"trace[1375673839] transaction","detail":"{read_only:false; response_revision:19293; number_of_response:1; }","duration":"157.48381ms","start":"2026-10-27T15:29:06.835826Z","end":"2026-10-27T15:29:06.99331Z","steps":["trace[1375673839] 'process raft request' (duration: 114.920358ms)","trace[1375673839] 'compare' (duration: 42.384858ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:29:06.995376Z","caller":"traceutil/trace.go:171","msg":"trace[866709174] transaction","detail":"{read_only:false; response_revision:19294; number_of_response:1; }","duration":"157.140541ms","start":"2026-10-27T15:29:06.838197Z","end":"2026-10-27T15:29:06.995338Z","steps":["trace[866709174] 'process raft request' (duration: 155.073714ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:29:06.997472Z","caller":"traceutil/trace.go:171","msg":"trace[426143203] transaction","detail":"{read_only:false; response_revision:19295; number_of_response:1; }","duration":"142.973688ms","start":"2026-10-27T15:29:06.854456Z","end":"2026-10-27T15:29:06.997429Z","steps":["trace[426143203] 'process raft request' (duration: 140.733607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:29:07.47627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.943366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T15:29:07.476539Z","caller":"traceutil/trace.go:171","msg":"trace[321040402] transaction","detail":"{read_only:false; response_revision:19296; number_of_response:1; }","duration":"581.74544ms","start":"2026-10-27T15:29:06.894757Z","end":"2026-10-27T15:29:07.476502Z","steps":["trace[321040402] 'process raft request' (duration: 205.491717ms)","trace[321040402] 'compare' (duration: 375.68256ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:29:07.476598Z","caller":"traceutil/trace.go:171","msg":"trace[711602919] transaction","detail":"{read_only:false; response_revision:19297; number_of_response:1; }","duration":"156.892267ms","start":"2026-10-27T15:29:07.319692Z","end":"2026-10-27T15:29:07.476584Z","steps":["trace[711602919] 'process raft request' (duration: 156.816355ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:29:07.476766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:29:06.894728Z","time spent":"581.898444ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:29:07.477088Z","caller":"traceutil/trace.go:171","msg":"trace[772326413] linearizableReadLoop","detail":"{readStateIndex:19968; appliedIndex:19967; }","duration":"445.039335ms","start":"2026-10-27T15:29:07.031999Z","end":"2026-10-27T15:29:07.477038Z","steps":["trace[772326413] 'read index received' (duration: 68.164798ms)","trace[772326413] 'applied index is now lower than readState.Index' (duration: 376.869117ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T15:29:07.477316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.303402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-ps6zm\" ","response":"range_response_count:1 size:9790"} {"level":"info","ts":"2026-10-27T15:29:07.477356Z","caller":"traceutil/trace.go:171","msg":"trace[418982409] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-ps6zm; range_end:; response_count:1; response_revision:19297; }","duration":"445.397544ms","start":"2026-10-27T15:29:07.03194Z","end":"2026-10-27T15:29:07.477338Z","steps":["trace[418982409] 'agreement among raft nodes before linearized reading' (duration: 445.22139ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:29:07.477383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:29:07.031915Z","time spent":"445.461765ms","remote":"127.0.0.1:44614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9813,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-ps6zm\" "} {"level":"warn","ts":"2026-10-27T15:29:08.061943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.454549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T15:29:08.062246Z","caller":"traceutil/trace.go:171","msg":"trace[953848915] linearizableReadLoop","detail":"{readStateIndex:19970; appliedIndex:19969; }","duration":"579.5271ms","start":"2026-10-27T15:29:07.482515Z","end":"2026-10-27T15:29:08.062042Z","steps":["trace[953848915] 'read index received' (duration: 362.825165ms)","trace[953848915] 'applied index is now lower than readState.Index' (duration: 216.700375ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:29:08.06233Z","caller":"traceutil/trace.go:171","msg":"trace[1404780793] transaction","detail":"{read_only:false; response_revision:19298; number_of_response:1; }","duration":"704.686583ms","start":"2026-10-27T15:29:07.357631Z","end":"2026-10-27T15:29:08.062318Z","steps":["trace[1404780793] 'process raft request' (duration: 487.732152ms)","trace[1404780793] 'compare' (duration: 215.844115ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T15:29:08.062394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:29:07.357592Z","time spent":"704.769575ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T15:29:08.063601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.058875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:949460"} {"level":"info","ts":"2026-10-27T15:29:08.063777Z","caller":"traceutil/trace.go:171","msg":"trace[2022476000] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:19298; }","duration":"581.190179ms","start":"2026-10-27T15:29:07.482491Z","end":"2026-10-27T15:29:08.063681Z","steps":["trace[2022476000] 'agreement among raft nodes before linearized reading' (duration: 579.840918ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:29:08.063944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:29:07.482475Z","time spent":"581.445854ms","remote":"127.0.0.1:44614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":94,"response size":949483,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-10-27T15:29:08.133728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.496959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:417673"} {"level":"info","ts":"2026-10-27T15:29:08.133819Z","caller":"traceutil/trace.go:171","msg":"trace[2000381937] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:19299; }","duration":"411.662603ms","start":"2026-10-27T15:29:07.722137Z","end":"2026-10-27T15:29:08.133799Z","steps":["trace[2000381937] 'agreement among raft nodes before linearized reading' (duration: 411.333155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:29:08.13386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:29:07.722114Z","time spent":"411.737444ms","remote":"127.0.0.1:60668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":417696,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"info","ts":"2026-10-27T15:29:08.134016Z","caller":"traceutil/trace.go:171","msg":"trace[292775702] transaction","detail":"{read_only:false; response_revision:19299; number_of_response:1; }","duration":"321.792408ms","start":"2026-10-27T15:29:07.812139Z","end":"2026-10-27T15:29:08.133931Z","steps":["trace[292775702] 'process raft request' (duration: 321.183484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:29:08.134238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.191743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-27T15:29:08.134283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:29:07.812129Z","time spent":"321.971372ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:29:08.13435Z","caller":"traceutil/trace.go:171","msg":"trace[310785916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19299; }","duration":"331.340446ms","start":"2026-10-27T15:29:07.802978Z","end":"2026-10-27T15:29:08.134318Z","steps":["trace[310785916] 'agreement among raft nodes before linearized reading' (duration: 331.126061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:29:08.134407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:29:07.802959Z","time spent":"331.434688ms","remote":"127.0.0.1:44378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-27T15:29:08.13456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.579083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-qcszn\" ","response":"range_response_count:1 size:22093"} {"level":"info","ts":"2026-10-27T15:29:08.134658Z","caller":"traceutil/trace.go:171","msg":"trace[186457516] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-qcszn; range_end:; response_count:1; response_revision:19299; }","duration":"185.712526ms","start":"2026-10-27T15:29:07.948927Z","end":"2026-10-27T15:29:08.134639Z","steps":["trace[186457516] 'agreement among raft nodes before linearized reading' (duration: 185.137053ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:29:11.502835Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T15:29:11.510655Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-27T15:29:11.510826Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-27T15:33:29.929977Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19043} {"level":"info","ts":"2026-10-27T15:33:30.040132Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19043,"took":"106.214846ms","hash":4288299965,"current-db-size-bytes":54906880,"current-db-size":"55 MB","current-db-size-in-use-bytes":34062336,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-27T15:33:30.04021Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4288299965,"revision":19043,"compact-revision":16769} {"level":"info","ts":"2026-10-27T15:35:26.177144Z","caller":"traceutil/trace.go:171","msg":"trace[1237436681] transaction","detail":"{read_only:false; response_revision:22201; number_of_response:1; }","duration":"451.020583ms","start":"2026-10-27T15:35:25.726093Z","end":"2026-10-27T15:35:26.177113Z","steps":["trace[1237436681] 'process raft request' (duration: 450.855269ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:35:26.177307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:35:25.726067Z","time spent":"451.155807ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:35:26.300069Z","caller":"traceutil/trace.go:171","msg":"trace[1609929845] linearizableReadLoop","detail":"{readStateIndex:22971; appliedIndex:22969; }","duration":"496.690146ms","start":"2026-10-27T15:35:25.803348Z","end":"2026-10-27T15:35:26.300038Z","steps":["trace[1609929845] 'read index received' (duration: 373.605855ms)","trace[1609929845] 'applied index is now lower than readState.Index' (duration: 123.082511ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T15:35:26.300213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.853171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T15:35:26.300249Z","caller":"traceutil/trace.go:171","msg":"trace[1952451468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22202; }","duration":"496.950283ms","start":"2026-10-27T15:35:25.80329Z","end":"2026-10-27T15:35:26.30024Z","steps":["trace[1952451468] 'agreement among raft nodes before linearized reading' (duration: 496.859241ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:35:26.300287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:35:25.80323Z","time spent":"497.049805ms","remote":"127.0.0.1:44378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-27T15:35:26.300355Z","caller":"traceutil/trace.go:171","msg":"trace[1028375031] transaction","detail":"{read_only:false; response_revision:22202; number_of_response:1; }","duration":"568.922146ms","start":"2026-10-27T15:35:25.731373Z","end":"2026-10-27T15:35:26.300296Z","steps":["trace[1028375031] 'process raft request' (duration: 551.719463ms)","trace[1028375031] 'compare' (duration: 16.739343ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T15:35:26.300554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:35:25.731361Z","time spent":"569.091171ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T15:35:26.649347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.757161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T15:35:26.649456Z","caller":"traceutil/trace.go:171","msg":"trace[1155911246] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:22202; }","duration":"128.919375ms","start":"2026-10-27T15:35:26.520509Z","end":"2026-10-27T15:35:26.649429Z","steps":["trace[1155911246] 'count revisions from in-memory index tree' (duration: 128.538557ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:35:27.02854Z","caller":"traceutil/trace.go:171","msg":"trace[996461009] transaction","detail":"{read_only:false; response_revision:22204; number_of_response:1; }","duration":"179.000309ms","start":"2026-10-27T15:35:26.849507Z","end":"2026-10-27T15:35:27.028508Z","steps":["trace[996461009] 'process raft request' (duration: 170.812242ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:35:27.411306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.131706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T15:35:27.411494Z","caller":"traceutil/trace.go:171","msg":"trace[1755935610] transaction","detail":"{read_only:false; response_revision:22205; number_of_response:1; }","duration":"271.447041ms","start":"2026-10-27T15:35:27.140026Z","end":"2026-10-27T15:35:27.411473Z","steps":["trace[1755935610] 'process raft request' (duration: 119.05002ms)","trace[1755935610] 'compare' (duration: 151.876729ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:35:27.436727Z","caller":"traceutil/trace.go:171","msg":"trace[1610415667] linearizableReadLoop","detail":"{readStateIndex:22975; appliedIndex:22973; }","duration":"243.100483ms","start":"2026-10-27T15:35:27.193559Z","end":"2026-10-27T15:35:27.43666Z","steps":["trace[1610415667] 'read index received' (duration: 65.536317ms)","trace[1610415667] 'applied index is now lower than readState.Index' (duration: 177.562886ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:35:27.436841Z","caller":"traceutil/trace.go:171","msg":"trace[309455228] transaction","detail":"{read_only:false; response_revision:22206; number_of_response:1; }","duration":"290.155208ms","start":"2026-10-27T15:35:27.146629Z","end":"2026-10-27T15:35:27.436784Z","steps":["trace[309455228] 'process raft request' (duration: 289.883072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:35:27.436957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.371929ms","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-27T15:35:27.437002Z","caller":"traceutil/trace.go:171","msg":"trace[19744800] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22206; }","duration":"243.466722ms","start":"2026-10-27T15:35:27.193521Z","end":"2026-10-27T15:35:27.436988Z","steps":["trace[19744800] 'agreement among raft nodes before linearized reading' (duration: 243.307558ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:35:27.724561Z","caller":"traceutil/trace.go:171","msg":"trace[2036846431] transaction","detail":"{read_only:false; response_revision:22208; number_of_response:1; }","duration":"282.070043ms","start":"2026-10-27T15:35:27.442465Z","end":"2026-10-27T15:35:27.724535Z","steps":["trace[2036846431] 'process raft request' (duration: 281.92514ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:35:27.724658Z","caller":"traceutil/trace.go:171","msg":"trace[1785275648] transaction","detail":"{read_only:false; response_revision:22209; number_of_response:1; }","duration":"281.655384ms","start":"2026-10-27T15:35:27.44297Z","end":"2026-10-27T15:35:27.724626Z","steps":["trace[1785275648] 'process raft request' (duration: 281.525081ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:35:27.724598Z","caller":"traceutil/trace.go:171","msg":"trace[1902735745] transaction","detail":"{read_only:false; response_revision:22207; number_of_response:1; }","duration":"282.965084ms","start":"2026-10-27T15:35:27.441599Z","end":"2026-10-27T15:35:27.724564Z","steps":["trace[1902735745] 'process raft request' (duration: 194.598725ms)","trace[1902735745] 'compare' (duration: 87.860507ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:36:44.317348Z","caller":"traceutil/trace.go:171","msg":"trace[645614495] transaction","detail":"{read_only:false; response_revision:22874; number_of_response:1; }","duration":"112.020698ms","start":"2026-10-27T15:36:44.205287Z","end":"2026-10-27T15:36:44.317308Z","steps":["trace[645614495] 'process raft request' (duration: 111.817554ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:36:44.317565Z","caller":"traceutil/trace.go:171","msg":"trace[1070539551] transaction","detail":"{read_only:false; response_revision:22875; number_of_response:1; }","duration":"111.302412ms","start":"2026-10-27T15:36:44.206223Z","end":"2026-10-27T15:36:44.317526Z","steps":["trace[1070539551] 'process raft request' (duration: 111.023166ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:38:29.939523Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21353} {"level":"info","ts":"2026-10-27T15:38:30.079823Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21353,"took":"136.078098ms","hash":2633557759,"current-db-size-bytes":54906880,"current-db-size":"55 MB","current-db-size-in-use-bytes":30769152,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-27T15:38:30.079912Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2633557759,"revision":21353,"compact-revision":19043} {"level":"info","ts":"2026-10-27T15:39:43.406377Z","caller":"traceutil/trace.go:171","msg":"trace[75899118] linearizableReadLoop","detail":"{readStateIndex:25585; appliedIndex:25584; }","duration":"230.66252ms","start":"2026-10-27T15:39:43.17568Z","end":"2026-10-27T15:39:43.406342Z","steps":["trace[75899118] 'read index received' (duration: 230.523826ms)","trace[75899118] 'applied index is now lower than readState.Index' (duration: 133.643µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:39:43.406571Z","caller":"traceutil/trace.go:171","msg":"trace[732972822] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"388.867194ms","start":"2026-10-27T15:39:43.017657Z","end":"2026-10-27T15:39:43.406524Z","steps":["trace[732972822] 'process raft request' (duration: 388.488725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:39:43.406829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.11515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.4\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-27T15:39:43.406946Z","caller":"traceutil/trace.go:171","msg":"trace[1277968587] range","detail":"{range_begin:/registry/masterleases/162.253.55.4; range_end:; response_count:1; response_revision:24739; }","duration":"231.312574ms","start":"2026-10-27T15:39:43.175619Z","end":"2026-10-27T15:39:43.406931Z","steps":["trace[1277968587] 'agreement among raft nodes before linearized reading' (duration: 230.956486ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:39:43.40685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:39:43.017616Z","time spent":"389.069448ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:39:43.445401Z","caller":"traceutil/trace.go:171","msg":"trace[813366952] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"100.113956ms","start":"2026-10-27T15:39:43.34524Z","end":"2026-10-27T15:39:43.445354Z","steps":["trace[813366952] 'process raft request' (duration: 99.82883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:39:43.445467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.399593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-27T15:39:43.445534Z","caller":"traceutil/trace.go:171","msg":"trace[259824852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24740; }","duration":"107.535486ms","start":"2026-10-27T15:39:43.337983Z","end":"2026-10-27T15:39:43.445518Z","steps":["trace[259824852] 'agreement among raft nodes before linearized reading' (duration: 107.27231ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:40:53.630732Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000672e.wal"} {"level":"info","ts":"2026-10-27T15:41:16.158411Z","caller":"traceutil/trace.go:171","msg":"trace[995903248] linearizableReadLoop","detail":"{readStateIndex:26610; appliedIndex:26609; }","duration":"121.798672ms","start":"2026-10-27T15:41:16.036586Z","end":"2026-10-27T15:41:16.158384Z","steps":["trace[995903248] 'read index received' (duration: 121.550197ms)","trace[995903248] 'applied index is now lower than readState.Index' (duration: 247.035µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:41:16.158517Z","caller":"traceutil/trace.go:171","msg":"trace[487445610] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"205.317221ms","start":"2026-10-27T15:41:15.953166Z","end":"2026-10-27T15:41:16.158484Z","steps":["trace[487445610] 'process raft request' (duration: 205.042824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:41:16.158737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.109789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-27T15:41:16.158739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.021544ms","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-27T15:41:16.158799Z","caller":"traceutil/trace.go:171","msg":"trace[257722708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25738; }","duration":"122.254552ms","start":"2026-10-27T15:41:16.036528Z","end":"2026-10-27T15:41:16.158783Z","steps":["trace[257722708] 'agreement among raft nodes before linearized reading' (duration: 122.023527ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:41:16.158814Z","caller":"traceutil/trace.go:171","msg":"trace[740648662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25738; }","duration":"107.156408ms","start":"2026-10-27T15:41:16.051639Z","end":"2026-10-27T15:41:16.158795Z","steps":["trace[740648662] 'agreement among raft nodes before linearized reading' (duration: 106.952223ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:42:29.846543Z","caller":"traceutil/trace.go:171","msg":"trace[712221038] transaction","detail":"{read_only:false; response_revision:26395; number_of_response:1; }","duration":"350.597566ms","start":"2026-10-27T15:42:29.495919Z","end":"2026-10-27T15:42:29.846517Z","steps":["trace[712221038] 'process raft request' (duration: 350.518764ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:42:29.846797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:42:29.495894Z","time spent":"350.748839ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:42:29.846823Z","caller":"traceutil/trace.go:171","msg":"trace[2066136481] transaction","detail":"{read_only:false; response_revision:26394; number_of_response:1; }","duration":"370.51045ms","start":"2026-10-27T15:42:29.47627Z","end":"2026-10-27T15:42:29.84678Z","steps":["trace[2066136481] 'process raft request' (duration: 316.716901ms)","trace[2066136481] 'compare' (duration: 53.238576ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T15:42:29.847046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:42:29.476254Z","time spent":"370.692985ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:42:29.847156Z","caller":"traceutil/trace.go:171","msg":"trace[616984535] linearizableReadLoop","detail":"{readStateIndex:27283; appliedIndex:27282; }","duration":"370.281885ms","start":"2026-10-27T15:42:29.476841Z","end":"2026-10-27T15:42:29.847123Z","steps":["trace[616984535] 'read index received' (duration: 316.153479ms)","trace[616984535] 'applied index is now lower than readState.Index' (duration: 54.126216ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T15:42:29.847476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.606422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:1 size:7344"} {"level":"info","ts":"2026-10-27T15:42:29.847571Z","caller":"traceutil/trace.go:171","msg":"trace[266528465] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:1; response_revision:26395; }","duration":"370.742335ms","start":"2026-10-27T15:42:29.476806Z","end":"2026-10-27T15:42:29.847548Z","steps":["trace[266528465] 'agreement among raft nodes before linearized reading' (duration: 370.459939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:42:29.847585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.596113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-27T15:42:29.847613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:42:29.47679Z","time spent":"370.814027ms","remote":"127.0.0.1:44678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":7367,"request content":"key:\"/registry/jobs/openstack/magnum-ks-user\" "} {"level":"info","ts":"2026-10-27T15:42:29.847653Z","caller":"traceutil/trace.go:171","msg":"trace[1507718516] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:26396; }","duration":"370.700084ms","start":"2026-10-27T15:42:29.476933Z","end":"2026-10-27T15:42:29.847633Z","steps":["trace[1507718516] 'agreement among raft nodes before linearized reading' (duration: 370.558491ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:42:29.847688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:42:29.476916Z","time spent":"370.764056ms","remote":"127.0.0.1:44678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" "} {"level":"warn","ts":"2026-10-27T15:42:29.847742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.027759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:6012"} {"level":"info","ts":"2026-10-27T15:42:29.847803Z","caller":"traceutil/trace.go:171","msg":"trace[553827179] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26396; }","duration":"363.108371ms","start":"2026-10-27T15:42:29.484676Z","end":"2026-10-27T15:42:29.847784Z","steps":["trace[553827179] 'agreement among raft nodes before linearized reading' (duration: 362.896686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:42:29.847856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:42:29.484665Z","time spent":"363.180173ms","remote":"127.0.0.1:49194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":6035,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-10-27T15:42:30.321399Z","caller":"traceutil/trace.go:171","msg":"trace[1807321130] transaction","detail":"{read_only:false; response_revision:26399; number_of_response:1; }","duration":"104.375163ms","start":"2026-10-27T15:42:30.216995Z","end":"2026-10-27T15:42:30.321371Z","steps":["trace[1807321130] 'process raft request' (duration: 104.323562ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:42:30.321483Z","caller":"traceutil/trace.go:171","msg":"trace[1755653390] transaction","detail":"{read_only:false; response_revision:26398; number_of_response:1; }","duration":"105.410207ms","start":"2026-10-27T15:42:30.216038Z","end":"2026-10-27T15:42:30.321449Z","steps":["trace[1755653390] 'process raft request' (duration: 101.14268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:43:24.333433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.874601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-27T15:43:24.333549Z","caller":"traceutil/trace.go:171","msg":"trace[772622705] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26893; }","duration":"185.049436ms","start":"2026-10-27T15:43:24.148476Z","end":"2026-10-27T15:43:24.333526Z","steps":["trace[772622705] 'range keys from in-memory index tree' (duration: 184.663446ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:43:29.873327Z","caller":"traceutil/trace.go:171","msg":"trace[1505605252] transaction","detail":"{read_only:false; response_revision:26939; number_of_response:1; }","duration":"296.856518ms","start":"2026-10-27T15:43:29.576436Z","end":"2026-10-27T15:43:29.873292Z","steps":["trace[1505605252] 'process raft request' (duration: 284.022085ms)","trace[1505605252] 'compare' (duration: 12.381533ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:43:29.887837Z","caller":"traceutil/trace.go:171","msg":"trace[1380209493] linearizableReadLoop","detail":"{readStateIndex:27844; appliedIndex:27842; }","duration":"210.400204ms","start":"2026-10-27T15:43:29.677411Z","end":"2026-10-27T15:43:29.887812Z","steps":["trace[1380209493] 'read index received' (duration: 183.06982ms)","trace[1380209493] 'applied index is now lower than readState.Index' (duration: 27.329274ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:43:29.887929Z","caller":"traceutil/trace.go:171","msg":"trace[1042272057] transaction","detail":"{read_only:false; response_revision:26940; number_of_response:1; }","duration":"224.702711ms","start":"2026-10-27T15:43:29.663195Z","end":"2026-10-27T15:43:29.887898Z","steps":["trace[1042272057] 'process raft request' (duration: 224.402794ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:43:29.88827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.828774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T15:43:29.888343Z","caller":"traceutil/trace.go:171","msg":"trace[1013734735] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:26940; }","duration":"210.977477ms","start":"2026-10-27T15:43:29.677346Z","end":"2026-10-27T15:43:29.888324Z","steps":["trace[1013734735] 'agreement among raft nodes before linearized reading' (duration: 210.578328ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:43:29.889291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.123171ms","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-27T15:43:29.889692Z","caller":"traceutil/trace.go:171","msg":"trace[526499660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26940; }","duration":"137.600232ms","start":"2026-10-27T15:43:29.752071Z","end":"2026-10-27T15:43:29.889671Z","steps":["trace[526499660] 'agreement among raft nodes before linearized reading' (duration: 135.868683ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:43:30.446676Z","caller":"traceutil/trace.go:171","msg":"trace[427210782] transaction","detail":"{read_only:false; response_revision:26941; number_of_response:1; }","duration":"550.403288ms","start":"2026-10-27T15:43:29.896247Z","end":"2026-10-27T15:43:30.446651Z","steps":["trace[427210782] 'process raft request' (duration: 550.273635ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:43:30.446826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:43:29.896202Z","time spent":"550.554082ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:43:30.487534Z","caller":"traceutil/trace.go:171","msg":"trace[1701976672] transaction","detail":"{read_only:false; response_revision:26942; number_of_response:1; }","duration":"577.63156ms","start":"2026-10-27T15:43:29.909867Z","end":"2026-10-27T15:43:30.487498Z","steps":["trace[1701976672] 'process raft request' (duration: 577.432215ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:43:30.487745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:43:29.909846Z","time spent":"577.764052ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:43:30.487831Z","caller":"traceutil/trace.go:171","msg":"trace[72404063] linearizableReadLoop","detail":"{readStateIndex:27848; appliedIndex:27845; }","duration":"114.364852ms","start":"2026-10-27T15:43:30.373443Z","end":"2026-10-27T15:43:30.487808Z","steps":["trace[72404063] 'read index received' (duration: 73.184822ms)","trace[72404063] 'applied index is now lower than readState.Index' (duration: 41.17888ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:43:30.487915Z","caller":"traceutil/trace.go:171","msg":"trace[238120771] transaction","detail":"{read_only:false; response_revision:26944; number_of_response:1; }","duration":"384.266584ms","start":"2026-10-27T15:43:30.103613Z","end":"2026-10-27T15:43:30.487879Z","steps":["trace[238120771] 'process raft request' (duration: 384.120301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:43:30.487969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.527465ms","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-27T15:43:30.488004Z","caller":"traceutil/trace.go:171","msg":"trace[1341326244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26944; }","duration":"114.606027ms","start":"2026-10-27T15:43:30.373385Z","end":"2026-10-27T15:43:30.487991Z","steps":["trace[1341326244] 'agreement among raft nodes before linearized reading' (duration: 114.486764ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:43:30.487977Z","caller":"traceutil/trace.go:171","msg":"trace[866268944] transaction","detail":"{read_only:false; response_revision:26943; number_of_response:1; }","duration":"545.634848ms","start":"2026-10-27T15:43:29.942284Z","end":"2026-10-27T15:43:30.487919Z","steps":["trace[866268944] 'process raft request' (duration: 545.172788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:43:30.488075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:43:30.103578Z","time spent":"384.408147ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T15:43:30.488145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:43:29.942253Z","time spent":"545.802592ms","remote":"127.0.0.1:44396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":26,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:43:30.737006Z","caller":"traceutil/trace.go:171","msg":"trace[1635439747] compact","detail":"{revision:23577; response_revision:26945; }","duration":"247.738026ms","start":"2026-10-27T15:43:30.489242Z","end":"2026-10-27T15:43:30.73698Z","steps":["trace[1635439747] 'process raft request' (duration: 176.351496ms)","trace[1635439747] 'check and update compact revision' (duration: 71.271678ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:43:30.737122Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23577} {"level":"info","ts":"2026-10-27T15:43:30.738037Z","caller":"traceutil/trace.go:171","msg":"trace[1727250071] transaction","detail":"{read_only:false; response_revision:26946; number_of_response:1; }","duration":"245.111367ms","start":"2026-10-27T15:43:30.492882Z","end":"2026-10-27T15:43:30.737994Z","steps":["trace[1727250071] 'process raft request' (duration: 244.583885ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:43:30.738381Z","caller":"traceutil/trace.go:171","msg":"trace[1965466505] transaction","detail":"{read_only:false; response_revision:26947; number_of_response:1; }","duration":"174.690899ms","start":"2026-10-27T15:43:30.563659Z","end":"2026-10-27T15:43:30.73835Z","steps":["trace[1965466505] 'process raft request' (duration: 174.261709ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:43:30.738787Z","caller":"traceutil/trace.go:171","msg":"trace[755034813] transaction","detail":"{read_only:false; response_revision:26948; number_of_response:1; }","duration":"171.68206ms","start":"2026-10-27T15:43:30.56709Z","end":"2026-10-27T15:43:30.738772Z","steps":["trace[755034813] 'process raft request' (duration: 171.2371ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:43:30.862779Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23577,"took":"120.170365ms","hash":2163333874,"current-db-size-bytes":54906880,"current-db-size":"55 MB","current-db-size-in-use-bytes":45236224,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-27T15:43:30.862901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2163333874,"revision":23577,"compact-revision":21353} {"level":"info","ts":"2026-10-27T15:44:27.308136Z","caller":"traceutil/trace.go:171","msg":"trace[440144679] transaction","detail":"{read_only:false; response_revision:27461; number_of_response:1; }","duration":"153.246949ms","start":"2026-10-27T15:44:27.154303Z","end":"2026-10-27T15:44:27.30755Z","steps":["trace[440144679] 'process raft request' (duration: 136.968148ms)","trace[440144679] 'compare' (duration: 15.734009ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:44:32.923509Z","caller":"traceutil/trace.go:171","msg":"trace[2011523626] transaction","detail":"{read_only:false; response_revision:27498; number_of_response:1; }","duration":"200.793865ms","start":"2026-10-27T15:44:32.722684Z","end":"2026-10-27T15:44:32.923478Z","steps":["trace[2011523626] 'process raft request' (duration: 200.625341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:33.411953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.843035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T15:44:33.41213Z","caller":"traceutil/trace.go:171","msg":"trace[1822482751] linearizableReadLoop","detail":"{readStateIndex:28416; appliedIndex:28414; }","duration":"660.788928ms","start":"2026-10-27T15:44:32.751324Z","end":"2026-10-27T15:44:33.412113Z","steps":["trace[1822482751] 'read index received' (duration: 171.955837ms)","trace[1822482751] 'applied index is now lower than readState.Index' (duration: 488.832041ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:44:33.412402Z","caller":"traceutil/trace.go:171","msg":"trace[832158669] transaction","detail":"{read_only:false; response_revision:27499; number_of_response:1; }","duration":"688.627684ms","start":"2026-10-27T15:44:32.723762Z","end":"2026-10-27T15:44:33.41239Z","steps":["trace[832158669] 'process raft request' (duration: 399.246866ms)","trace[832158669] 'compare' (duration: 288.675622ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T15:44:33.412471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:32.723714Z","time spent":"688.722566ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:44:33.412581Z","caller":"traceutil/trace.go:171","msg":"trace[1074420937] transaction","detail":"{read_only:false; response_revision:27500; number_of_response:1; }","duration":"649.93738ms","start":"2026-10-27T15:44:32.762607Z","end":"2026-10-27T15:44:33.412544Z","steps":["trace[1074420937] 'process raft request' (duration: 649.451439ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:33.412607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.27651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T15:44:33.412688Z","caller":"traceutil/trace.go:171","msg":"trace[1117067644] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27500; }","duration":"661.408322ms","start":"2026-10-27T15:44:32.751262Z","end":"2026-10-27T15:44:33.41267Z","steps":["trace[1117067644] 'agreement among raft nodes before linearized reading' (duration: 661.267609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:33.412759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:32.751241Z","time spent":"661.507795ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-27T15:44:33.412805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:32.762583Z","time spent":"650.092124ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:44:33.682338Z","caller":"traceutil/trace.go:171","msg":"trace[934580694] transaction","detail":"{read_only:false; response_revision:27501; number_of_response:1; }","duration":"689.389871ms","start":"2026-10-27T15:44:32.992921Z","end":"2026-10-27T15:44:33.682311Z","steps":["trace[934580694] 'process raft request' (duration: 621.965242ms)","trace[934580694] 'compare' (duration: 67.243715ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T15:44:33.682499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:32.992898Z","time spent":"689.515774ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:44:33.682669Z","caller":"traceutil/trace.go:171","msg":"trace[968124529] linearizableReadLoop","detail":"{readStateIndex:28421; appliedIndex:28417; }","duration":"270.482776ms","start":"2026-10-27T15:44:33.412162Z","end":"2026-10-27T15:44:33.682645Z","steps":["trace[968124529] 'read index received' (duration: 202.682628ms)","trace[968124529] 'applied index is now lower than readState.Index' (duration: 67.798668ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T15:44:33.682689Z","caller":"traceutil/trace.go:171","msg":"trace[170529284] transaction","detail":"{read_only:false; response_revision:27502; number_of_response:1; }","duration":"680.829136ms","start":"2026-10-27T15:44:33.00181Z","end":"2026-10-27T15:44:33.682639Z","steps":["trace[170529284] 'process raft request' (duration: 680.459647ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:44:33.682817Z","caller":"traceutil/trace.go:171","msg":"trace[219651621] transaction","detail":"{read_only:false; response_revision:27504; number_of_response:1; }","duration":"342.92492ms","start":"2026-10-27T15:44:33.339862Z","end":"2026-10-27T15:44:33.682786Z","steps":["trace[219651621] 'process raft request' (duration: 342.724855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:33.682902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.001795Z","time spent":"680.98453ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T15:44:33.682905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.586365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-27T15:44:33.682975Z","caller":"traceutil/trace.go:171","msg":"trace[1813911504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27504; }","duration":"336.727049ms","start":"2026-10-27T15:44:33.346228Z","end":"2026-10-27T15:44:33.682955Z","steps":["trace[1813911504] 'agreement among raft nodes before linearized reading' (duration: 336.450382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:33.682907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.707639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-27T15:44:33.683034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.603545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.4\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-27T15:44:33.683041Z","caller":"traceutil/trace.go:171","msg":"trace[1132373096] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:27504; }","duration":"361.944014ms","start":"2026-10-27T15:44:33.321084Z","end":"2026-10-27T15:44:33.683028Z","steps":["trace[1132373096] 'agreement among raft nodes before linearized reading' (duration: 361.720979ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:44:33.683075Z","caller":"traceutil/trace.go:171","msg":"trace[30713482] range","detail":"{range_begin:/registry/masterleases/162.253.55.4; range_end:; response_count:1; response_revision:27504; }","duration":"491.677156ms","start":"2026-10-27T15:44:33.191387Z","end":"2026-10-27T15:44:33.683064Z","steps":["trace[30713482] 'agreement among raft nodes before linearized reading' (duration: 491.544333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:33.683084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.321062Z","time spent":"362.008246ms","remote":"127.0.0.1:47596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-10-27T15:44:33.683113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.191365Z","time spent":"491.740158ms","remote":"127.0.0.1:44426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.4\" "} {"level":"info","ts":"2026-10-27T15:44:33.683123Z","caller":"traceutil/trace.go:171","msg":"trace[1646649590] transaction","detail":"{read_only:false; response_revision:27503; number_of_response:1; }","duration":"351.069616ms","start":"2026-10-27T15:44:33.332037Z","end":"2026-10-27T15:44:33.683106Z","steps":["trace[1646649590] 'process raft request' (duration: 350.430042ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:33.683218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.332008Z","time spent":"351.165979ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T15:44:33.683253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.610726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-27T15:44:33.683297Z","caller":"traceutil/trace.go:171","msg":"trace[1426112489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27504; }","duration":"336.670447ms","start":"2026-10-27T15:44:33.346614Z","end":"2026-10-27T15:44:33.683285Z","steps":["trace[1426112489] 'agreement among raft nodes before linearized reading' (duration: 336.553185ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:33.683143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.434522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-27T15:44:33.683344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.346606Z","time spent":"336.727118ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-27T15:44:33.683352Z","caller":"traceutil/trace.go:171","msg":"trace[1538354652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27504; }","duration":"336.680137ms","start":"2026-10-27T15:44:33.346656Z","end":"2026-10-27T15:44:33.683336Z","steps":["trace[1538354652] 'agreement among raft nodes before linearized reading' (duration: 336.400641ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:33.683395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.346637Z","time spent":"336.74535ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-27T15:44:33.683425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.027028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-27T15:44:33.683416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.561502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-27T15:44:33.68339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.526344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1991"} {"level":"warn","ts":"2026-10-27T15:44:33.683508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.30085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T15:44:33.683521Z","caller":"traceutil/trace.go:171","msg":"trace[1359478987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27504; }","duration":"582.694515ms","start":"2026-10-27T15:44:33.10079Z","end":"2026-10-27T15:44:33.683485Z","steps":["trace[1359478987] 'agreement among raft nodes before linearized reading' (duration: 582.47452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:33.682978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.339843Z","time spent":"343.074974ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T15:44:33.683569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.100769Z","time spent":"582.790338ms","remote":"127.0.0.1:44706","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-27T15:44:33.683534Z","caller":"traceutil/trace.go:171","msg":"trace[1810564230] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27504; }","duration":"266.380843ms","start":"2026-10-27T15:44:33.417144Z","end":"2026-10-27T15:44:33.683525Z","steps":["trace[1810564230] 'agreement among raft nodes before linearized reading' (duration: 266.343572ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:44:33.683534Z","caller":"traceutil/trace.go:171","msg":"trace[1297635255] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:27504; }","duration":"682.748479ms","start":"2026-10-27T15:44:33.000763Z","end":"2026-10-27T15:44:33.683511Z","steps":["trace[1297635255] 'agreement among raft nodes before linearized reading' (duration: 682.221227ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:33.683641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.49116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T15:44:33.683698Z","caller":"traceutil/trace.go:171","msg":"trace[671816085] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:27504; }","duration":"589.650003ms","start":"2026-10-27T15:44:33.094033Z","end":"2026-10-27T15:44:33.683683Z","steps":["trace[671816085] 'agreement among raft nodes before linearized reading' (duration: 589.552771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:33.683764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.000743Z","time spent":"682.937104ms","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2014,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-10-27T15:44:33.683779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.094005Z","time spent":"589.757926ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true "} {"level":"info","ts":"2026-10-27T15:44:33.68346Z","caller":"traceutil/trace.go:171","msg":"trace[1429191901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27504; }","duration":"913.099889ms","start":"2026-10-27T15:44:32.770352Z","end":"2026-10-27T15:44:33.683452Z","steps":["trace[1429191901] 'agreement among raft nodes before linearized reading' (duration: 913.010257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:33.683932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:32.77033Z","time spent":"913.57037ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-27T15:44:33.683031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.346194Z","time spent":"336.824121ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-27T15:44:34.068816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.689471ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-10-27T15:44:34.068942Z","caller":"traceutil/trace.go:171","msg":"trace[636237135] linearizableReadLoop","detail":"{readStateIndex:28422; appliedIndex:28421; }","duration":"380.747383ms","start":"2026-10-27T15:44:33.688172Z","end":"2026-10-27T15:44:34.068919Z","steps":["trace[636237135] 'read index received' (duration: 172.960559ms)","trace[636237135] 'applied index is now lower than readState.Index' (duration: 207.784684ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T15:44:34.069021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.684832Z","time spent":"384.185201ms","remote":"127.0.0.1:44426","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-27T15:44:34.069406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.218723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12427"} {"level":"info","ts":"2026-10-27T15:44:34.069456Z","caller":"traceutil/trace.go:171","msg":"trace[343080219] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27504; }","duration":"381.303606ms","start":"2026-10-27T15:44:33.688139Z","end":"2026-10-27T15:44:34.069443Z","steps":["trace[343080219] 'agreement among raft nodes before linearized reading' (duration: 381.121452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:34.069492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.688123Z","time spent":"381.359577ms","remote":"127.0.0.1:44614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12450,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-27T15:44:34.073044Z","caller":"traceutil/trace.go:171","msg":"trace[186264822] transaction","detail":"{read_only:false; response_revision:27506; number_of_response:1; }","duration":"383.734832ms","start":"2026-10-27T15:44:33.68929Z","end":"2026-10-27T15:44:34.073025Z","steps":["trace[186264822] 'process raft request' (duration: 383.562538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:34.073178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.689278Z","time spent":"383.824863ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:44:34.073246Z","caller":"traceutil/trace.go:171","msg":"trace[1265470294] transaction","detail":"{read_only:false; response_revision:27508; number_of_response:1; }","duration":"383.220129ms","start":"2026-10-27T15:44:33.690017Z","end":"2026-10-27T15:44:34.073237Z","steps":["trace[1265470294] 'process raft request' (duration: 382.948503ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:44:34.073146Z","caller":"traceutil/trace.go:171","msg":"trace[518731266] transaction","detail":"{read_only:false; response_revision:27505; number_of_response:1; }","duration":"384.291764ms","start":"2026-10-27T15:44:33.688807Z","end":"2026-10-27T15:44:34.073099Z","steps":["trace[518731266] 'process raft request' (duration: 383.789883ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:44:34.073238Z","caller":"traceutil/trace.go:171","msg":"trace[306162921] transaction","detail":"{read_only:false; response_revision:27510; number_of_response:1; }","duration":"333.76878ms","start":"2026-10-27T15:44:33.739437Z","end":"2026-10-27T15:44:34.073206Z","steps":["trace[306162921] 'process raft request' (duration: 333.594606ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:44:34.073188Z","caller":"traceutil/trace.go:171","msg":"trace[900386754] transaction","detail":"{read_only:false; response_revision:27507; number_of_response:1; }","duration":"383.634179ms","start":"2026-10-27T15:44:33.689532Z","end":"2026-10-27T15:44:34.073166Z","steps":["trace[900386754] 'process raft request' (duration: 383.394713ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:34.073334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.552447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-27T15:44:34.073349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.690007Z","time spent":"383.27026ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:44:34.073399Z","caller":"traceutil/trace.go:171","msg":"trace[617163260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27510; }","duration":"270.71016ms","start":"2026-10-27T15:44:33.802674Z","end":"2026-10-27T15:44:34.073384Z","steps":["trace[617163260] 'agreement among raft nodes before linearized reading' (duration: 270.555316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:34.073395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.739413Z","time spent":"333.909103ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T15:44:34.073399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.688781Z","time spent":"384.522609ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T15:44:34.073181Z","caller":"traceutil/trace.go:171","msg":"trace[1585086257] transaction","detail":"{read_only:false; response_revision:27509; number_of_response:1; }","duration":"382.715398ms","start":"2026-10-27T15:44:33.690432Z","end":"2026-10-27T15:44:34.073147Z","steps":["trace[1585086257] 'process raft request' (duration: 382.566885ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:34.073438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.689519Z","time spent":"383.812023ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T15:44:34.073592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.690422Z","time spent":"383.077046ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T15:44:34.073627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.961709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T15:44:34.073763Z","caller":"traceutil/trace.go:171","msg":"trace[795530748] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:27510; }","duration":"339.110482ms","start":"2026-10-27T15:44:33.734603Z","end":"2026-10-27T15:44:34.073714Z","steps":["trace[795530748] 'agreement among raft nodes before linearized reading' (duration: 338.971979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T15:44:34.073827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T15:44:33.734579Z","time spent":"339.227295ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"info","ts":"2026-10-27T15:47:17.123113Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T15:47:17.131071Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-27T15:47:17.131273Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-27T15:48:17.634135Z","caller":"traceutil/trace.go:171","msg":"trace[452293861] transaction","detail":"{read_only:false; response_revision:29694; number_of_response:1; }","duration":"126.384536ms","start":"2026-10-27T15:48:17.507729Z","end":"2026-10-27T15:48:17.634114Z","steps":["trace[452293861] 'process raft request' (duration: 126.227653ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:48:17.635551Z","caller":"traceutil/trace.go:171","msg":"trace[474412776] transaction","detail":"{read_only:false; response_revision:29695; number_of_response:1; }","duration":"126.960389ms","start":"2026-10-27T15:48:17.508568Z","end":"2026-10-27T15:48:17.635528Z","steps":["trace[474412776] 'process raft request' (duration: 126.780995ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T15:48:30.748556Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26943} {"level":"info","ts":"2026-10-27T15:48:30.943127Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26943,"took":"181.643198ms","hash":2266022637,"current-db-size-bytes":56139776,"current-db-size":"56 MB","current-db-size-in-use-bytes":35672064,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-27T15:48:30.943206Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2266022637,"revision":26943,"compact-revision":23577} {"level":"info","ts":"2026-10-27T15:53:30.75661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29792} {"level":"info","ts":"2026-10-27T15:53:30.919572Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29792,"took":"157.607568ms","hash":1035425694,"current-db-size-bytes":56139776,"current-db-size":"56 MB","current-db-size-in-use-bytes":32395264,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T15:53:30.919666Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1035425694,"revision":29792,"compact-revision":26943} {"level":"info","ts":"2026-10-27T15:58:30.765375Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32181} {"level":"info","ts":"2026-10-27T15:58:30.945028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32181,"took":"175.012257ms","hash":2053792918,"current-db-size-bytes":57184256,"current-db-size":"57 MB","current-db-size-in-use-bytes":31154176,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-27T15:58:30.945085Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2053792918,"revision":32181,"compact-revision":29792} {"level":"info","ts":"2026-10-27T16:00:03.376875Z","caller":"traceutil/trace.go:171","msg":"trace[1341088268] transaction","detail":"{read_only:false; response_revision:35130; number_of_response:1; }","duration":"112.343645ms","start":"2026-10-27T16:00:03.264502Z","end":"2026-10-27T16:00:03.376846Z","steps":["trace[1341088268] 'process raft request' (duration: 87.514188ms)","trace[1341088268] 'compare' (duration: 24.698454ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T16:03:30.773482Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34409} {"level":"info","ts":"2026-10-27T16:03:30.938109Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34409,"took":"159.83405ms","hash":3381359789,"current-db-size-bytes":57184256,"current-db-size":"57 MB","current-db-size-in-use-bytes":31617024,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T16:03:30.938192Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3381359789,"revision":34409,"compact-revision":32181} {"level":"info","ts":"2026-10-27T16:07:49.24565Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T16:07:49.252409Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-27T16:07:49.252586Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-27T16:08:30.782209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36713} {"level":"info","ts":"2026-10-27T16:08:30.925881Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36713,"took":"139.487128ms","hash":3976340010,"current-db-size-bytes":57184256,"current-db-size":"57 MB","current-db-size-in-use-bytes":32399360,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T16:08:30.925971Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3976340010,"revision":36713,"compact-revision":34409} {"level":"info","ts":"2026-10-27T16:13:30.791221Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39081} {"level":"info","ts":"2026-10-27T16:13:30.938077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39081,"took":"142.168459ms","hash":1596756702,"current-db-size-bytes":58032128,"current-db-size":"58 MB","current-db-size-in-use-bytes":31268864,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-27T16:13:30.93817Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1596756702,"revision":39081,"compact-revision":36713}