{"level":"warn","ts":"2026-10-14T15:44:29.319599Z","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-14T15:44:29.319748Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.164: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.164:2380","--initial-cluster=instance=https://162.253.55.164:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.164:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.164: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-14T15:44:29.319842Z","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-14T15:44:29.31986Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.164:2380"]} {"level":"info","ts":"2026-10-14T15:44:29.319885Z","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-14T15:44:29.320726Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.164:2379"]} {"level":"info","ts":"2026-10-14T15:44:29.320841Z","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.164:2380"],"listen-peer-urls":["https://162.253.55.164:2380"],"advertise-client-urls":["https://162.253.55.164:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.164:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.164: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-14T15:44:29.327191Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.034006ms"} {"level":"info","ts":"2026-10-14T15:44:29.335414Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"777352cb93f4c05d","cluster-id":"ebb6470dab83b2e8"} {"level":"info","ts":"2026-10-14T15:44:29.335576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T15:44:29.335616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d became follower at term 0"} {"level":"info","ts":"2026-10-14T15:44:29.335632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 777352cb93f4c05d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T15:44:29.335648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d became follower at term 1"} {"level":"info","ts":"2026-10-14T15:44:29.335711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d switched to configuration voters=(8607314347149279325)"} {"level":"warn","ts":"2026-10-14T15:44:29.347924Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T15:44:29.350384Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T15:44:29.352109Z","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-14T15:44:29.356256Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"777352cb93f4c05d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T15:44:29.356603Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"777352cb93f4c05d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T15:44:29.356813Z","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-14T15:44:29.356949Z","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-14T15:44:29.356964Z","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-14T15:44:29.360166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d switched to configuration voters=(8607314347149279325)"} {"level":"info","ts":"2026-10-14T15:44:29.360804Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ebb6470dab83b2e8","local-member-id":"777352cb93f4c05d","added-peer-id":"777352cb93f4c05d","added-peer-peer-urls":["https://162.253.55.164:2380"]} {"level":"info","ts":"2026-10-14T15:44:29.362636Z","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-14T15:44:29.362747Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.164:2380"} {"level":"info","ts":"2026-10-14T15:44:29.36286Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.164:2380"} {"level":"info","ts":"2026-10-14T15:44:29.362956Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"777352cb93f4c05d","initial-advertise-peer-urls":["https://162.253.55.164:2380"],"listen-peer-urls":["https://162.253.55.164:2380"],"advertise-client-urls":["https://162.253.55.164:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.164:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T15:44:29.363028Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T15:44:30.236829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T15:44:30.23692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T15:44:30.23697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d received MsgPreVoteResp from 777352cb93f4c05d at term 1"} {"level":"info","ts":"2026-10-14T15:44:30.236997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d became candidate at term 2"} {"level":"info","ts":"2026-10-14T15:44:30.23702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d received MsgVoteResp from 777352cb93f4c05d at term 2"} {"level":"info","ts":"2026-10-14T15:44:30.237049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d became leader at term 2"} {"level":"info","ts":"2026-10-14T15:44:30.237074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 777352cb93f4c05d elected leader 777352cb93f4c05d at term 2"} {"level":"info","ts":"2026-10-14T15:44:30.238625Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"777352cb93f4c05d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.164:2379]}","request-path":"/0/members/777352cb93f4c05d/attributes","cluster-id":"ebb6470dab83b2e8","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T15:44:30.238685Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T15:44:30.238734Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:44:30.238816Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T15:44:30.239328Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T15:44:30.239411Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T15:44:30.241239Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ebb6470dab83b2e8","local-member-id":"777352cb93f4c05d","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:44:30.241359Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:44:30.241402Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:44:30.243709Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T15:44:30.243705Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.164:2379"} {"level":"info","ts":"2026-10-14T15:44:48.77138Z","caller":"traceutil/trace.go:171","msg":"trace[45028820] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"388.995502ms","start":"2026-10-14T15:44:48.38235Z","end":"2026-10-14T15:44:48.771346Z","steps":["trace[45028820] 'read index received' (duration: 388.673133ms)","trace[45028820] 'applied index is now lower than readState.Index' (duration: 321.369µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:44:48.771592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.207757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:44:48.771494Z","caller":"traceutil/trace.go:171","msg":"trace[315010032] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"396.657389ms","start":"2026-10-14T15:44:48.374787Z","end":"2026-10-14T15:44:48.771444Z","steps":["trace[315010032] 'process raft request' (duration: 396.390622ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:44:48.77174Z","caller":"traceutil/trace.go:171","msg":"trace[867159469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"389.406383ms","start":"2026-10-14T15:44:48.382287Z","end":"2026-10-14T15:44:48.771693Z","steps":["trace[867159469] 'agreement among raft nodes before linearized reading' (duration: 389.202507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:44:48.772187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:44:48.382261Z","time spent":"389.872634ms","remote":"127.0.0.1:51294","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-14T15:44:48.772639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:44:48.374754Z","time spent":"397.201613ms","remote":"127.0.0.1:51464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:44:54.184651Z","caller":"traceutil/trace.go:171","msg":"trace[1393104931] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:452; }","duration":"155.311975ms","start":"2026-10-14T15:44:54.029309Z","end":"2026-10-14T15:44:54.184621Z","steps":["trace[1393104931] 'read index received' (duration: 70.92992ms)","trace[1393104931] 'applied index is now lower than readState.Index' (duration: 84.380455ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:44:54.184816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.48558ms","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-14T15:44:54.184802Z","caller":"traceutil/trace.go:171","msg":"trace[1550992085] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"298.878467ms","start":"2026-10-14T15:44:53.885876Z","end":"2026-10-14T15:44:54.184754Z","steps":["trace[1550992085] 'process raft request' (duration: 214.259806ms)","trace[1550992085] 'compare' (duration: 84.322494ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:44:54.184862Z","caller":"traceutil/trace.go:171","msg":"trace[1281898981] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:436; }","duration":"155.615383ms","start":"2026-10-14T15:44:54.029236Z","end":"2026-10-14T15:44:54.184851Z","steps":["trace[1281898981] 'agreement among raft nodes before linearized reading' (duration: 155.49842ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:22.366971Z","caller":"traceutil/trace.go:171","msg":"trace[1523038861] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"278.439327ms","start":"2026-10-14T15:45:22.088495Z","end":"2026-10-14T15:45:22.366934Z","steps":["trace[1523038861] 'process raft request' (duration: 278.271413ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:22.875104Z","caller":"traceutil/trace.go:171","msg":"trace[919915375] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:681; }","duration":"549.71765ms","start":"2026-10-14T15:45:22.325357Z","end":"2026-10-14T15:45:22.875075Z","steps":["trace[919915375] 'read index received' (duration: 41.538621ms)","trace[919915375] 'applied index is now lower than readState.Index' (duration: 508.178129ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:45:22.875216Z","caller":"traceutil/trace.go:171","msg":"trace[312950816] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"550.58659ms","start":"2026-10-14T15:45:22.324557Z","end":"2026-10-14T15:45:22.875144Z","steps":["trace[312950816] 'process raft request' (duration: 550.005566ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:45:22.875406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.115799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-85f8q\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-14T15:45:22.87546Z","caller":"traceutil/trace.go:171","msg":"trace[183787812] range","detail":"{range_begin:/registry/pods/kube-system/cilium-85f8q; range_end:; response_count:1; response_revision:657; }","duration":"533.240223ms","start":"2026-10-14T15:45:22.342207Z","end":"2026-10-14T15:45:22.875447Z","steps":["trace[183787812] 'agreement among raft nodes before linearized reading' (duration: 533.063838ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:45:22.875489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:45:22.324528Z","time spent":"550.854987ms","remote":"127.0.0.1:51392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:45:22.875506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:45:22.342155Z","time spent":"533.341085ms","remote":"127.0.0.1:51464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-85f8q\" "} {"level":"warn","ts":"2026-10-14T15:45:22.875513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.204698ms","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-14T15:45:22.875583Z","caller":"traceutil/trace.go:171","msg":"trace[1200045236] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"451.318131ms","start":"2026-10-14T15:45:22.424243Z","end":"2026-10-14T15:45:22.875561Z","steps":["trace[1200045236] 'agreement among raft nodes before linearized reading' (duration: 451.073155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:45:22.875638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:45:22.424215Z","time spent":"451.411143ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-14T15:45:22.878894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.123397ms","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-14T15:45:22.879006Z","caller":"traceutil/trace.go:171","msg":"trace[1932125794] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"553.641154ms","start":"2026-10-14T15:45:22.325334Z","end":"2026-10-14T15:45:22.878975Z","steps":["trace[1932125794] 'agreement among raft nodes before linearized reading' (duration: 549.889283ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:45:22.879101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:45:22.325325Z","time spent":"553.739066ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-14T15:45:23.556217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.964866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:45:23.556539Z","caller":"traceutil/trace.go:171","msg":"trace[2115884278] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"671.457861ms","start":"2026-10-14T15:45:22.885023Z","end":"2026-10-14T15:45:23.556481Z","steps":["trace[2115884278] 'process raft request' (duration: 66.836ms)","trace[2115884278] 'compare' (duration: 603.847112ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:45:23.556654Z","caller":"traceutil/trace.go:171","msg":"trace[1697396999] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"655.056237ms","start":"2026-10-14T15:45:22.901577Z","end":"2026-10-14T15:45:23.556633Z","steps":["trace[1697396999] 'process raft request' (duration: 654.76804ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:23.556687Z","caller":"traceutil/trace.go:171","msg":"trace[1513672180] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:684; }","duration":"170.579058ms","start":"2026-10-14T15:45:23.386085Z","end":"2026-10-14T15:45:23.556664Z","steps":["trace[1513672180] 'read index received' (duration: 96.240227ms)","trace[1513672180] 'applied index is now lower than readState.Index' (duration: 74.337731ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:45:23.556904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.820544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:45:23.556721Z","caller":"traceutil/trace.go:171","msg":"trace[1045355844] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"596.959857ms","start":"2026-10-14T15:45:22.959732Z","end":"2026-10-14T15:45:23.556691Z","steps":["trace[1045355844] 'process raft request' (duration: 596.826484ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:23.556948Z","caller":"traceutil/trace.go:171","msg":"trace[465663196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:662; }","duration":"170.909046ms","start":"2026-10-14T15:45:23.386027Z","end":"2026-10-14T15:45:23.556936Z","steps":["trace[465663196] 'agreement among raft nodes before linearized reading' (duration: 170.836945ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:23.556991Z","caller":"traceutil/trace.go:171","msg":"trace[387982690] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"545.290274ms","start":"2026-10-14T15:45:23.011683Z","end":"2026-10-14T15:45:23.556973Z","steps":["trace[387982690] 'process raft request' (duration: 544.928655ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:45:23.557098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:45:23.011653Z","time spent":"545.391236ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:45:23.557113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:45:22.959706Z","time spent":"597.254635ms","remote":"127.0.0.1:51762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T15:45:23.556754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:45:22.901535Z","time spent":"655.17469ms","remote":"127.0.0.1:51464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:45:23.556742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:45:22.884995Z","time spent":"671.649246ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:45:40.656937Z","caller":"traceutil/trace.go:171","msg":"trace[622120167] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:860; }","duration":"183.272705ms","start":"2026-10-14T15:45:40.473634Z","end":"2026-10-14T15:45:40.656907Z","steps":["trace[622120167] 'read index received' (duration: 183.107611ms)","trace[622120167] 'applied index is now lower than readState.Index' (duration: 163.704µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:45:40.65719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.53246ms","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-14T15:45:40.657253Z","caller":"traceutil/trace.go:171","msg":"trace[1343375323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:829; }","duration":"183.636303ms","start":"2026-10-14T15:45:40.473599Z","end":"2026-10-14T15:45:40.657236Z","steps":["trace[1343375323] 'agreement among raft nodes before linearized reading' (duration: 183.431369ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:40.657256Z","caller":"traceutil/trace.go:171","msg":"trace[591757411] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"184.316799ms","start":"2026-10-14T15:45:40.472919Z","end":"2026-10-14T15:45:40.657235Z","steps":["trace[591757411] 'process raft request' (duration: 183.834358ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:14.048891Z","caller":"traceutil/trace.go:171","msg":"trace[970231433] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"142.476281ms","start":"2026-10-14T15:46:13.906382Z","end":"2026-10-14T15:46:14.048858Z","steps":["trace[970231433] 'process raft request' (duration: 142.277756ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:47:01.180477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.327648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-12a643c5-bd95-4124-b1d4-1d46fa585b02\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-14T15:47:01.180557Z","caller":"traceutil/trace.go:171","msg":"trace[1183900003] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-12a643c5-bd95-4124-b1d4-1d46fa585b02; range_end:; response_count:1; response_revision:1911; }","duration":"305.462522ms","start":"2026-10-14T15:47:00.875075Z","end":"2026-10-14T15:47:01.180538Z","steps":["trace[1183900003] 'range keys from in-memory index tree' (duration: 305.129463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:47:01.180592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:47:00.875052Z","time spent":"305.531173ms","remote":"127.0.0.1:51464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-12a643c5-bd95-4124-b1d4-1d46fa585b02\" "} {"level":"warn","ts":"2026-10-14T15:47:01.180683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.875209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-12a643c5-bd95-4124-b1d4-1d46fa585b02\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-14T15:47:01.180776Z","caller":"traceutil/trace.go:171","msg":"trace[839358289] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-12a643c5-bd95-4124-b1d4-1d46fa585b02; range_end:; response_count:1; response_revision:1911; }","duration":"153.012482ms","start":"2026-10-14T15:47:01.027744Z","end":"2026-10-14T15:47:01.180757Z","steps":["trace[839358289] 'range keys from in-memory index tree' (duration: 152.665344ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:01.690256Z","caller":"traceutil/trace.go:171","msg":"trace[1275990820] linearizableReadLoop","detail":"{readStateIndex:1990; appliedIndex:1989; }","duration":"124.820448ms","start":"2026-10-14T15:47:01.565416Z","end":"2026-10-14T15:47:01.690236Z","steps":["trace[1275990820] 'read index received' (duration: 124.743686ms)","trace[1275990820] 'applied index is now lower than readState.Index' (duration: 75.862µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:47:01.690366Z","caller":"traceutil/trace.go:171","msg":"trace[1466614017] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"186.847202ms","start":"2026-10-14T15:47:01.503487Z","end":"2026-10-14T15:47:01.690334Z","steps":["trace[1466614017] 'process raft request' (duration: 186.622187ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:47:01.690441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.014702ms","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-14T15:47:01.690473Z","caller":"traceutil/trace.go:171","msg":"trace[664253596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1914; }","duration":"125.093584ms","start":"2026-10-14T15:47:01.565371Z","end":"2026-10-14T15:47:01.690465Z","steps":["trace[664253596] 'agreement among raft nodes before linearized reading' (duration: 124.981021ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:47:02.001969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.443218ms","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-14T15:47:02.002117Z","caller":"traceutil/trace.go:171","msg":"trace[1292705354] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1914; }","duration":"256.603462ms","start":"2026-10-14T15:47:01.745494Z","end":"2026-10-14T15:47:02.002097Z","steps":["trace[1292705354] 'range keys from in-memory index tree' (duration: 256.427957ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:02.002095Z","caller":"traceutil/trace.go:171","msg":"trace[1832785905] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"306.67798ms","start":"2026-10-14T15:47:01.695382Z","end":"2026-10-14T15:47:02.00206Z","steps":["trace[1832785905] 'process raft request' (duration: 242.074723ms)","trace[1832785905] 'compare' (duration: 64.426732ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:47:02.002257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:47:01.695345Z","time spent":"306.820283ms","remote":"127.0.0.1:51536","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-14T15:47:02.010183Z","caller":"traceutil/trace.go:171","msg":"trace[1390146799] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"314.490637ms","start":"2026-10-14T15:47:01.695658Z","end":"2026-10-14T15:47:02.010149Z","steps":["trace[1390146799] 'process raft request' (duration: 314.274872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:47:02.010365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:47:01.695633Z","time spent":"314.63164ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:47:15.764607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.852541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-14T15:47:15.764747Z","caller":"traceutil/trace.go:171","msg":"trace[1555721822] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2039; }","duration":"146.036096ms","start":"2026-10-14T15:47:15.618668Z","end":"2026-10-14T15:47:15.764704Z","steps":["trace[1555721822] 'range keys from in-memory index tree' (duration: 145.592824ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:46.846877Z","caller":"traceutil/trace.go:171","msg":"trace[867129478] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"139.003088ms","start":"2026-10-14T15:47:46.707849Z","end":"2026-10-14T15:47:46.846852Z","steps":["trace[867129478] 'process raft request' (duration: 138.960737ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:46.847008Z","caller":"traceutil/trace.go:171","msg":"trace[1957267078] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"139.698664ms","start":"2026-10-14T15:47:46.707266Z","end":"2026-10-14T15:47:46.846964Z","steps":["trace[1957267078] 'process raft request' (duration: 117.904584ms)","trace[1957267078] 'compare' (duration: 21.523095ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:47:47.230955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.07646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-14T15:47:47.231082Z","caller":"traceutil/trace.go:171","msg":"trace[685096713] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2218; }","duration":"227.297765ms","start":"2026-10-14T15:47:47.003748Z","end":"2026-10-14T15:47:47.231045Z","steps":["trace[685096713] 'range keys from in-memory index tree' (duration: 226.864885ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:52.376259Z","caller":"traceutil/trace.go:171","msg":"trace[773254710] linearizableReadLoop","detail":"{readStateIndex:2331; appliedIndex:2330; }","duration":"106.153423ms","start":"2026-10-14T15:47:52.270074Z","end":"2026-10-14T15:47:52.376227Z","steps":["trace[773254710] 'read index received' (duration: 105.991329ms)","trace[773254710] 'applied index is now lower than readState.Index' (duration: 160.854µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:47:52.37637Z","caller":"traceutil/trace.go:171","msg":"trace[1801309393] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"124.702696ms","start":"2026-10-14T15:47:52.251614Z","end":"2026-10-14T15:47:52.376316Z","steps":["trace[1801309393] 'process raft request' (duration: 124.384399ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:47:52.376554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.43588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-14T15:47:52.376632Z","caller":"traceutil/trace.go:171","msg":"trace[871083241] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2244; }","duration":"106.576204ms","start":"2026-10-14T15:47:52.270037Z","end":"2026-10-14T15:47:52.376613Z","steps":["trace[871083241] 'agreement among raft nodes before linearized reading' (duration: 106.297127ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:52.543075Z","caller":"traceutil/trace.go:171","msg":"trace[999013640] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"156.522586ms","start":"2026-10-14T15:47:52.386515Z","end":"2026-10-14T15:47:52.543038Z","steps":["trace[999013640] 'process raft request' (duration: 156.447474ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:52.543111Z","caller":"traceutil/trace.go:171","msg":"trace[935112633] linearizableReadLoop","detail":"{readStateIndex:2332; appliedIndex:2331; }","duration":"159.450906ms","start":"2026-10-14T15:47:52.383628Z","end":"2026-10-14T15:47:52.543079Z","steps":["trace[935112633] 'read index received' (duration: 87.682022ms)","trace[935112633] 'applied index is now lower than readState.Index' (duration: 71.765824ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:47:52.543227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.578479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:47:52.543266Z","caller":"traceutil/trace.go:171","msg":"trace[1998305903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2246; }","duration":"159.658271ms","start":"2026-10-14T15:47:52.383598Z","end":"2026-10-14T15:47:52.543256Z","steps":["trace[1998305903] 'agreement among raft nodes before linearized reading' (duration: 159.567689ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:52.543124Z","caller":"traceutil/trace.go:171","msg":"trace[1293620531] transaction","detail":"{read_only:false; response_revision:2245; number_of_response:1; }","duration":"159.860616ms","start":"2026-10-14T15:47:52.383177Z","end":"2026-10-14T15:47:52.543037Z","steps":["trace[1293620531] 'process raft request' (duration: 87.963309ms)","trace[1293620531] 'compare' (duration: 71.62794ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:47:52.8197Z","caller":"traceutil/trace.go:171","msg":"trace[720693651] transaction","detail":"{read_only:false; response_revision:2247; number_of_response:1; }","duration":"206.652272ms","start":"2026-10-14T15:47:52.61302Z","end":"2026-10-14T15:47:52.819672Z","steps":["trace[720693651] 'process raft request' (duration: 142.550972ms)","trace[720693651] 'compare' (duration: 63.867294ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:48:42.082757Z","caller":"traceutil/trace.go:171","msg":"trace[1628350127] linearizableReadLoop","detail":"{readStateIndex:2791; appliedIndex:2790; }","duration":"125.693638ms","start":"2026-10-14T15:48:41.957044Z","end":"2026-10-14T15:48:42.082738Z","steps":["trace[1628350127] 'read index received' (duration: 125.497754ms)","trace[1628350127] 'applied index is now lower than readState.Index' (duration: 195.264µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:48:42.082953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.901673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-14T15:48:42.082978Z","caller":"traceutil/trace.go:171","msg":"trace[1579946280] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2686; }","duration":"125.978185ms","start":"2026-10-14T15:48:41.956993Z","end":"2026-10-14T15:48:42.082971Z","steps":["trace[1579946280] 'agreement among raft nodes before linearized reading' (duration: 125.845582ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:48:42.083265Z","caller":"traceutil/trace.go:171","msg":"trace[1645251046] transaction","detail":"{read_only:false; response_revision:2686; number_of_response:1; }","duration":"171.711648ms","start":"2026-10-14T15:48:41.911545Z","end":"2026-10-14T15:48:42.083256Z","steps":["trace[1645251046] 'process raft request' (duration: 171.069351ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:09.375258Z","caller":"traceutil/trace.go:171","msg":"trace[1441002771] transaction","detail":"{read_only:false; response_revision:2857; number_of_response:1; }","duration":"117.100803ms","start":"2026-10-14T15:49:09.258122Z","end":"2026-10-14T15:49:09.375222Z","steps":["trace[1441002771] 'process raft request' (duration: 116.95082ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:09.396636Z","caller":"traceutil/trace.go:171","msg":"trace[289806406] transaction","detail":"{read_only:false; response_revision:2858; number_of_response:1; }","duration":"138.418246ms","start":"2026-10-14T15:49:09.258191Z","end":"2026-10-14T15:49:09.396609Z","steps":["trace[289806406] 'process raft request' (duration: 138.140309ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:15.436086Z","caller":"traceutil/trace.go:171","msg":"trace[212936786] transaction","detail":"{read_only:false; response_revision:2887; number_of_response:1; }","duration":"284.953835ms","start":"2026-10-14T15:49:15.151091Z","end":"2026-10-14T15:49:15.436045Z","steps":["trace[212936786] 'process raft request' (duration: 259.230783ms)","trace[212936786] 'compare' (duration: 25.323803ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:49:15.436454Z","caller":"traceutil/trace.go:171","msg":"trace[1006759706] transaction","detail":"{read_only:false; response_revision:2888; number_of_response:1; }","duration":"220.207758ms","start":"2026-10-14T15:49:15.216163Z","end":"2026-10-14T15:49:15.436371Z","steps":["trace[1006759706] 'process raft request' (duration: 219.9088ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:21.597501Z","caller":"traceutil/trace.go:171","msg":"trace[172878464] linearizableReadLoop","detail":"{readStateIndex:3034; appliedIndex:3032; }","duration":"113.025253ms","start":"2026-10-14T15:49:21.484431Z","end":"2026-10-14T15:49:21.597456Z","steps":["trace[172878464] 'read index received' (duration: 52.07625ms)","trace[172878464] 'applied index is now lower than readState.Index' (duration: 60.947443ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:49:21.597648Z","caller":"traceutil/trace.go:171","msg":"trace[1607701806] transaction","detail":"{read_only:false; response_revision:2921; number_of_response:1; }","duration":"138.397016ms","start":"2026-10-14T15:49:21.459197Z","end":"2026-10-14T15:49:21.597594Z","steps":["trace[1607701806] 'process raft request' (duration: 132.734775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:21.597881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.418091ms","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-14T15:49:21.598133Z","caller":"traceutil/trace.go:171","msg":"trace[1983165840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2921; }","duration":"113.744539ms","start":"2026-10-14T15:49:21.484369Z","end":"2026-10-14T15:49:21.598113Z","steps":["trace[1983165840] 'agreement among raft nodes before linearized reading' (duration: 113.299898ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:53:56.379733Z","caller":"traceutil/trace.go:171","msg":"trace[1729100945] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"149.459649ms","start":"2026-10-14T15:53:56.23025Z","end":"2026-10-14T15:53:56.37971Z","steps":["trace[1729100945] 'process raft request' (duration: 149.316696ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:53:56.406336Z","caller":"traceutil/trace.go:171","msg":"trace[118337524] linearizableReadLoop","detail":"{readStateIndex:5396; appliedIndex:5394; }","duration":"153.52167ms","start":"2026-10-14T15:53:56.252795Z","end":"2026-10-14T15:53:56.406316Z","steps":["trace[118337524] 'read index received' (duration: 126.761275ms)","trace[118337524] 'applied index is now lower than readState.Index' (duration: 26.759805ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:53:56.40655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.245938ms","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-14T15:53:56.406576Z","caller":"traceutil/trace.go:171","msg":"trace[1355026913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5195; }","duration":"104.35573ms","start":"2026-10-14T15:53:56.302213Z","end":"2026-10-14T15:53:56.406569Z","steps":["trace[1355026913] 'agreement among raft nodes before linearized reading' (duration: 104.234647ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:53:56.406407Z","caller":"traceutil/trace.go:171","msg":"trace[269458970] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"172.299067ms","start":"2026-10-14T15:53:56.234072Z","end":"2026-10-14T15:53:56.406371Z","steps":["trace[269458970] 'process raft request' (duration: 172.125163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:53:56.407095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.265179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:531300"} {"level":"info","ts":"2026-10-14T15:53:56.407234Z","caller":"traceutil/trace.go:171","msg":"trace[631935823] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:5195; }","duration":"154.490874ms","start":"2026-10-14T15:53:56.25272Z","end":"2026-10-14T15:53:56.407211Z","steps":["trace[631935823] 'agreement among raft nodes before linearized reading' (duration: 153.732565ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:01.356952Z","caller":"traceutil/trace.go:171","msg":"trace[688732161] linearizableReadLoop","detail":"{readStateIndex:5533; appliedIndex:5532; }","duration":"177.686901ms","start":"2026-10-14T15:54:01.179239Z","end":"2026-10-14T15:54:01.356926Z","steps":["trace[688732161] 'read index received' (duration: 177.463775ms)","trace[688732161] 'applied index is now lower than readState.Index' (duration: 222.076µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:54:01.357043Z","caller":"traceutil/trace.go:171","msg":"trace[577651677] transaction","detail":"{read_only:false; response_revision:5329; number_of_response:1; }","duration":"207.644983ms","start":"2026-10-14T15:54:01.149373Z","end":"2026-10-14T15:54:01.357018Z","steps":["trace[577651677] 'process raft request' (duration: 207.383828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:01.357165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.044677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-14T15:54:01.357166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.925746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-14T15:54:01.357168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.090149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:54:01.357209Z","caller":"traceutil/trace.go:171","msg":"trace[346890053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5329; }","duration":"167.140559ms","start":"2026-10-14T15:54:01.190056Z","end":"2026-10-14T15:54:01.357197Z","steps":["trace[346890053] 'agreement among raft nodes before linearized reading' (duration: 167.013896ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:01.357244Z","caller":"traceutil/trace.go:171","msg":"trace[1919783980] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5329; }","duration":"124.205432ms","start":"2026-10-14T15:54:01.233016Z","end":"2026-10-14T15:54:01.357222Z","steps":["trace[1919783980] 'agreement among raft nodes before linearized reading' (duration: 124.085769ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:01.357215Z","caller":"traceutil/trace.go:171","msg":"trace[766849362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5329; }","duration":"178.056109ms","start":"2026-10-14T15:54:01.17915Z","end":"2026-10-14T15:54:01.357206Z","steps":["trace[766849362] 'agreement among raft nodes before linearized reading' (duration: 177.884035ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:01.357341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.564695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b215d114-c6db-4f58-81e8-1dc713b44925\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-10-14T15:54:01.357485Z","caller":"traceutil/trace.go:171","msg":"trace[580471357] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b215d114-c6db-4f58-81e8-1dc713b44925; range_end:; response_count:1; response_revision:5329; }","duration":"135.742519ms","start":"2026-10-14T15:54:01.221712Z","end":"2026-10-14T15:54:01.357454Z","steps":["trace[580471357] 'agreement among raft nodes before linearized reading' (duration: 135.476482ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:01.670864Z","caller":"traceutil/trace.go:171","msg":"trace[589944211] linearizableReadLoop","detail":"{readStateIndex:5535; appliedIndex:5534; }","duration":"250.832906ms","start":"2026-10-14T15:54:01.419999Z","end":"2026-10-14T15:54:01.670832Z","steps":["trace[589944211] 'read index received' (duration: 244.616512ms)","trace[589944211] 'applied index is now lower than readState.Index' (duration: 6.214613ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:54:01.670902Z","caller":"traceutil/trace.go:171","msg":"trace[1064931653] transaction","detail":"{read_only:false; response_revision:5331; number_of_response:1; }","duration":"307.701057ms","start":"2026-10-14T15:54:01.363167Z","end":"2026-10-14T15:54:01.670868Z","steps":["trace[1064931653] 'process raft request' (duration: 301.513315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:01.671018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.726626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:54:01.671079Z","caller":"traceutil/trace.go:171","msg":"trace[170513421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5331; }","duration":"288.805408ms","start":"2026-10-14T15:54:01.382259Z","end":"2026-10-14T15:54:01.671064Z","steps":["trace[170513421] 'agreement among raft nodes before linearized reading' (duration: 288.697966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:01.6711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.275962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:54:01.671186Z","caller":"traceutil/trace.go:171","msg":"trace[883235279] range","detail":"{range_begin:/registry/clusterrolebindings/vector; range_end:; response_count:0; response_revision:5331; }","duration":"248.412536ms","start":"2026-10-14T15:54:01.422753Z","end":"2026-10-14T15:54:01.671165Z","steps":["trace[883235279] 'agreement among raft nodes before linearized reading' (duration: 248.260562ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:01.671259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.970761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4dw9x\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-10-14T15:54:01.671324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.215658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:54:01.671355Z","caller":"traceutil/trace.go:171","msg":"trace[1882364658] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:5331; }","duration":"121.277799ms","start":"2026-10-14T15:54:01.55007Z","end":"2026-10-14T15:54:01.671348Z","steps":["trace[1882364658] 'agreement among raft nodes before linearized reading' (duration: 121.223848ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:01.67139Z","caller":"traceutil/trace.go:171","msg":"trace[59552807] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4dw9x; range_end:; response_count:1; response_revision:5331; }","duration":"102.144555ms","start":"2026-10-14T15:54:01.569194Z","end":"2026-10-14T15:54:01.671339Z","steps":["trace[59552807] 'agreement among raft nodes before linearized reading' (duration: 101.841938ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:01.671631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:54:01.363129Z","time spent":"307.833261ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:54:02.091322Z","caller":"traceutil/trace.go:171","msg":"trace[657273796] linearizableReadLoop","detail":"{readStateIndex:5542; appliedIndex:5541; }","duration":"222.77728ms","start":"2026-10-14T15:54:01.868528Z","end":"2026-10-14T15:54:02.091305Z","steps":["trace[657273796] 'read index received' (duration: 222.691278ms)","trace[657273796] 'applied index is now lower than readState.Index' (duration: 84.762µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:54:02.091452Z","caller":"traceutil/trace.go:171","msg":"trace[606559084] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"234.215753ms","start":"2026-10-14T15:54:01.85718Z","end":"2026-10-14T15:54:02.091395Z","steps":["trace[606559084] 'process raft request' (duration: 234.031308ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:02.091529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.997055ms","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-14T15:54:02.091558Z","caller":"traceutil/trace.go:171","msg":"trace[712464894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5337; }","duration":"223.074177ms","start":"2026-10-14T15:54:01.868474Z","end":"2026-10-14T15:54:02.091549Z","steps":["trace[712464894] 'agreement among raft nodes before linearized reading' (duration: 222.921553ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:16.389672Z","caller":"traceutil/trace.go:171","msg":"trace[1951664284] linearizableReadLoop","detail":"{readStateIndex:5798; appliedIndex:5797; }","duration":"160.933195ms","start":"2026-10-14T15:54:16.228703Z","end":"2026-10-14T15:54:16.389637Z","steps":["trace[1951664284] 'read index received' (duration: 160.71301ms)","trace[1951664284] 'applied index is now lower than readState.Index' (duration: 218.985µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:54:16.389791Z","caller":"traceutil/trace.go:171","msg":"trace[435640468] transaction","detail":"{read_only:false; response_revision:5585; number_of_response:1; }","duration":"175.628099ms","start":"2026-10-14T15:54:16.214152Z","end":"2026-10-14T15:54:16.389781Z","steps":["trace[435640468] 'process raft request' (duration: 175.337391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:16.390022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.305174ms","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-14T15:54:16.390058Z","caller":"traceutil/trace.go:171","msg":"trace[280796292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5585; }","duration":"161.379456ms","start":"2026-10-14T15:54:16.228669Z","end":"2026-10-14T15:54:16.390048Z","steps":["trace[280796292] 'agreement among raft nodes before linearized reading' (duration: 161.246933ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:16.390284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.225138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-14T15:54:16.390359Z","caller":"traceutil/trace.go:171","msg":"trace[1129730638] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5585; }","duration":"158.348001ms","start":"2026-10-14T15:54:16.231987Z","end":"2026-10-14T15:54:16.390335Z","steps":["trace[1129730638] 'agreement among raft nodes before linearized reading' (duration: 158.173016ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:21.382543Z","caller":"traceutil/trace.go:171","msg":"trace[1734246735] transaction","detail":"{read_only:false; response_revision:5650; number_of_response:1; }","duration":"114.795299ms","start":"2026-10-14T15:54:21.267715Z","end":"2026-10-14T15:54:21.38251Z","steps":["trace[1734246735] 'process raft request' (duration: 114.613815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:23.417944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.569595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd98d737-d1fc-403c-92f1-08475e76e765\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-14T15:54:23.418063Z","caller":"traceutil/trace.go:171","msg":"trace[523058570] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cd98d737-d1fc-403c-92f1-08475e76e765; range_end:; response_count:1; response_revision:5722; }","duration":"137.736409ms","start":"2026-10-14T15:54:23.280299Z","end":"2026-10-14T15:54:23.418036Z","steps":["trace[523058570] 'range keys from in-memory index tree' (duration: 137.284848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:23.418123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.03629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd98d737-d1fc-403c-92f1-08475e76e765\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-14T15:54:23.418215Z","caller":"traceutil/trace.go:171","msg":"trace[1648917510] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cd98d737-d1fc-403c-92f1-08475e76e765; range_end:; response_count:1; response_revision:5722; }","duration":"114.203545ms","start":"2026-10-14T15:54:23.303986Z","end":"2026-10-14T15:54:23.41819Z","steps":["trace[1648917510] 'range keys from in-memory index tree' (duration: 113.829325ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:23.676191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.501137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.164\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-14T15:54:23.676288Z","caller":"traceutil/trace.go:171","msg":"trace[1536909467] range","detail":"{range_begin:/registry/masterleases/162.253.55.164; range_end:; response_count:1; response_revision:5723; }","duration":"115.650961ms","start":"2026-10-14T15:54:23.560617Z","end":"2026-10-14T15:54:23.676268Z","steps":["trace[1536909467] 'range keys from in-memory index tree' (duration: 115.301932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:23.676418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.016123ms","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-14T15:54:23.676476Z","caller":"traceutil/trace.go:171","msg":"trace[1584624769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5723; }","duration":"150.118466ms","start":"2026-10-14T15:54:23.526344Z","end":"2026-10-14T15:54:23.676462Z","steps":["trace[1584624769] 'range keys from in-memory index tree' (duration: 149.843679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:23.96886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.687544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:54:23.969147Z","caller":"traceutil/trace.go:171","msg":"trace[1982159819] linearizableReadLoop","detail":"{readStateIndex:5940; appliedIndex:5938; }","duration":"248.278742ms","start":"2026-10-14T15:54:23.720851Z","end":"2026-10-14T15:54:23.969129Z","steps":["trace[1982159819] 'read index received' (duration: 57.20119ms)","trace[1982159819] 'applied index is now lower than readState.Index' (duration: 191.076622ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:54:23.969281Z","caller":"traceutil/trace.go:171","msg":"trace[1365159253] transaction","detail":"{read_only:false; response_revision:5725; number_of_response:1; }","duration":"249.604625ms","start":"2026-10-14T15:54:23.719653Z","end":"2026-10-14T15:54:23.969258Z","steps":["trace[1365159253] 'process raft request' (duration: 249.40078ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:23.969242Z","caller":"traceutil/trace.go:171","msg":"trace[912451810] transaction","detail":"{read_only:false; response_revision:5724; number_of_response:1; }","duration":"289.508626ms","start":"2026-10-14T15:54:23.679657Z","end":"2026-10-14T15:54:23.969165Z","steps":["trace[912451810] 'process raft request' (duration: 98.433254ms)","trace[912451810] 'compare' (duration: 190.56821ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:54:23.969417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.487187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T15:54:23.969511Z","caller":"traceutil/trace.go:171","msg":"trace[176081424] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:5725; }","duration":"248.661872ms","start":"2026-10-14T15:54:23.720827Z","end":"2026-10-14T15:54:23.969489Z","steps":["trace[176081424] 'agreement among raft nodes before linearized reading' (duration: 248.431636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:23.969514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.33496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-14T15:54:23.969606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.413953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4dw9x\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-14T15:54:23.969715Z","caller":"traceutil/trace.go:171","msg":"trace[1299647201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5725; }","duration":"187.552895ms","start":"2026-10-14T15:54:23.782121Z","end":"2026-10-14T15:54:23.969674Z","steps":["trace[1299647201] 'agreement among raft nodes before linearized reading' (duration: 187.153565ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:23.969783Z","caller":"traceutil/trace.go:171","msg":"trace[812681651] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4dw9x; range_end:; response_count:1; response_revision:5725; }","duration":"181.641819ms","start":"2026-10-14T15:54:23.788123Z","end":"2026-10-14T15:54:23.969765Z","steps":["trace[812681651] 'agreement among raft nodes before linearized reading' (duration: 181.31314ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:30.267613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2969} {"level":"info","ts":"2026-10-14T15:54:30.351892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2969,"took":"82.823316ms","hash":2396596244,"current-db-size-bytes":55971840,"current-db-size":"56 MB","current-db-size-in-use-bytes":32165888,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T15:54:30.351974Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2396596244,"revision":2969,"compact-revision":-1} {"level":"info","ts":"2026-10-14T15:54:38.210197Z","caller":"traceutil/trace.go:171","msg":"trace[366758257] transaction","detail":"{read_only:false; response_revision:5927; number_of_response:1; }","duration":"133.73247ms","start":"2026-10-14T15:54:38.076429Z","end":"2026-10-14T15:54:38.210161Z","steps":["trace[366758257] 'process raft request' (duration: 54.098473ms)","trace[366758257] 'compare' (duration: 79.495033ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:55:26.784562Z","caller":"traceutil/trace.go:171","msg":"trace[1252667216] transaction","detail":"{read_only:false; response_revision:6323; number_of_response:1; }","duration":"226.779608ms","start":"2026-10-14T15:55:26.557755Z","end":"2026-10-14T15:55:26.784535Z","steps":["trace[1252667216] 'process raft request' (duration: 226.631474ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:26.960583Z","caller":"traceutil/trace.go:171","msg":"trace[1585644302] transaction","detail":"{read_only:false; response_revision:6324; number_of_response:1; }","duration":"402.752802ms","start":"2026-10-14T15:55:26.557793Z","end":"2026-10-14T15:55:26.960546Z","steps":["trace[1585644302] 'process raft request' (duration: 392.148924ms)","trace[1585644302] 'compare' (duration: 10.383282ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:55:26.960611Z","caller":"traceutil/trace.go:171","msg":"trace[964686360] transaction","detail":"{read_only:false; response_revision:6325; number_of_response:1; }","duration":"402.483875ms","start":"2026-10-14T15:55:26.558094Z","end":"2026-10-14T15:55:26.960578Z","steps":["trace[964686360] 'process raft request' (duration: 402.386312ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:26.960747Z","caller":"traceutil/trace.go:171","msg":"trace[1287916978] transaction","detail":"{read_only:false; response_revision:6326; number_of_response:1; }","duration":"402.003323ms","start":"2026-10-14T15:55:26.558715Z","end":"2026-10-14T15:55:26.960719Z","steps":["trace[1287916978] 'process raft request' (duration: 401.813319ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:26.960767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:26.55777Z","time spent":"402.898316ms","remote":"127.0.0.1:51446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":966,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:55:26.960771Z","caller":"traceutil/trace.go:171","msg":"trace[2134967369] transaction","detail":"{read_only:false; response_revision:6327; number_of_response:1; }","duration":"401.824419ms","start":"2026-10-14T15:55:26.558921Z","end":"2026-10-14T15:55:26.960746Z","steps":["trace[2134967369] 'process raft request' (duration: 401.643164ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:26.960817Z","caller":"traceutil/trace.go:171","msg":"trace[1738414227] transaction","detail":"{read_only:false; response_revision:6328; number_of_response:1; }","duration":"401.567513ms","start":"2026-10-14T15:55:26.559219Z","end":"2026-10-14T15:55:26.960787Z","steps":["trace[1738414227] 'process raft request' (duration: 401.395989ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:26.960887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:26.558701Z","time spent":"402.086116ms","remote":"127.0.0.1:51446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1482,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:55:26.960931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:26.55921Z","time spent":"401.669426ms","remote":"127.0.0.1:51538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1617,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:55:26.960942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:26.55891Z","time spent":"401.954952ms","remote":"127.0.0.1:51538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:55:26.961277Z","caller":"traceutil/trace.go:171","msg":"trace[1277507748] transaction","detail":"{read_only:false; response_revision:6329; number_of_response:1; }","duration":"393.579471ms","start":"2026-10-14T15:55:26.567685Z","end":"2026-10-14T15:55:26.961265Z","steps":["trace[1277507748] 'process raft request' (duration: 393.011807ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:26.961303Z","caller":"traceutil/trace.go:171","msg":"trace[1846373470] linearizableReadLoop","detail":"{readStateIndex:6562; appliedIndex:6555; }","duration":"269.827126ms","start":"2026-10-14T15:55:26.691446Z","end":"2026-10-14T15:55:26.961273Z","steps":["trace[1846373470] 'read index received' (duration: 93.122773ms)","trace[1846373470] 'applied index is now lower than readState.Index' (duration: 176.703053ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:55:26.961381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:26.567659Z","time spent":"393.654783ms","remote":"127.0.0.1:51722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:55:26.961313Z","caller":"traceutil/trace.go:171","msg":"trace[263482065] transaction","detail":"{read_only:false; response_revision:6330; number_of_response:1; }","duration":"376.064358ms","start":"2026-10-14T15:55:26.585228Z","end":"2026-10-14T15:55:26.961292Z","steps":["trace[263482065] 'process raft request' (duration: 375.823102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:26.961566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:26.585204Z","time spent":"376.299865ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:55:26.962161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.719917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225408"} {"level":"info","ts":"2026-10-14T15:55:26.962214Z","caller":"traceutil/trace.go:171","msg":"trace[680373340] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:6330; }","duration":"270.814539ms","start":"2026-10-14T15:55:26.691385Z","end":"2026-10-14T15:55:26.962199Z","steps":["trace[680373340] 'agreement among raft nodes before linearized reading' (duration: 269.989869ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:26.964045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:26.558083Z","time spent":"402.590458ms","remote":"127.0.0.1:51446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":896,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:55:26.973716Z","caller":"traceutil/trace.go:171","msg":"trace[408012590] transaction","detail":"{read_only:false; response_revision:6331; number_of_response:1; }","duration":"183.623638ms","start":"2026-10-14T15:55:26.790061Z","end":"2026-10-14T15:55:26.973685Z","steps":["trace[408012590] 'process raft request' (duration: 183.353331ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:27.295819Z","caller":"traceutil/trace.go:171","msg":"trace[1342144843] linearizableReadLoop","detail":"{readStateIndex:6569; appliedIndex:6568; }","duration":"114.05403ms","start":"2026-10-14T15:55:27.181734Z","end":"2026-10-14T15:55:27.295788Z","steps":["trace[1342144843] 'read index received' (duration: 113.821844ms)","trace[1342144843] 'applied index is now lower than readState.Index' (duration: 231.096µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:55:27.295866Z","caller":"traceutil/trace.go:171","msg":"trace[298230631] transaction","detail":"{read_only:false; response_revision:6337; number_of_response:1; }","duration":"130.66639ms","start":"2026-10-14T15:55:27.165167Z","end":"2026-10-14T15:55:27.295833Z","steps":["trace[298230631] 'process raft request' (duration: 130.452015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:27.296152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.39396ms","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-14T15:55:27.296204Z","caller":"traceutil/trace.go:171","msg":"trace[1147426197] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6337; }","duration":"114.496872ms","start":"2026-10-14T15:55:27.181695Z","end":"2026-10-14T15:55:27.296192Z","steps":["trace[1147426197] 'agreement among raft nodes before linearized reading' (duration: 114.255055ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:59:30.275403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5840} {"level":"info","ts":"2026-10-14T15:59:30.37672Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5840,"took":"99.588534ms","hash":1845928743,"current-db-size-bytes":55971840,"current-db-size":"56 MB","current-db-size-in-use-bytes":26247168,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-14T15:59:30.376787Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1845928743,"revision":5840,"compact-revision":2969} {"level":"warn","ts":"2026-10-14T16:00:49.662532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:00:49.029998Z","time spent":"632.526663ms","remote":"127.0.0.1:55180","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-14T16:00:49.770143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.459173ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-14T16:00:49.770331Z","caller":"traceutil/trace.go:171","msg":"trace[2083110783] linearizableReadLoop","detail":"{readStateIndex:9010; appliedIndex:9009; }","duration":"606.814713ms","start":"2026-10-14T16:00:49.163496Z","end":"2026-10-14T16:00:49.770311Z","steps":["trace[2083110783] 'read index received' (duration: 304.160835ms)","trace[2083110783] 'applied index is now lower than readState.Index' (duration: 302.652358ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:00:49.770501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.085388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:00:49.77062Z","caller":"traceutil/trace.go:171","msg":"trace[796665933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8699; }","duration":"387.250362ms","start":"2026-10-14T16:00:49.383351Z","end":"2026-10-14T16:00:49.770602Z","steps":["trace[796665933] 'agreement among raft nodes before linearized reading' (duration: 387.095658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:00:49.770711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:00:49.383291Z","time spent":"387.403266ms","remote":"127.0.0.1:51294","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-14T16:00:49.770504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.999158ms","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-14T16:00:49.77084Z","caller":"traceutil/trace.go:171","msg":"trace[185130401] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8699; }","duration":"607.377947ms","start":"2026-10-14T16:00:49.163444Z","end":"2026-10-14T16:00:49.770822Z","steps":["trace[185130401] 'agreement among raft nodes before linearized reading' (duration: 606.932906ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:00:49.770894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:00:49.163424Z","time spent":"607.45596ms","remote":"127.0.0.1:51536","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-14T16:01:00.2106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.568305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:20199"} {"level":"info","ts":"2026-10-14T16:01:00.210687Z","caller":"traceutil/trace.go:171","msg":"trace[453237294] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8753; }","duration":"155.700789ms","start":"2026-10-14T16:01:00.054964Z","end":"2026-10-14T16:01:00.210665Z","steps":["trace[453237294] 'range keys from in-memory index tree' (duration: 155.409711ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:00.210683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.726875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T16:01:00.210715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.397074ms","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-14T16:01:00.210791Z","caller":"traceutil/trace.go:171","msg":"trace[1247083754] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:8753; }","duration":"158.893869ms","start":"2026-10-14T16:01:00.051867Z","end":"2026-10-14T16:01:00.210761Z","steps":["trace[1247083754] 'count revisions from in-memory index tree' (duration: 158.658704ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:00.210818Z","caller":"traceutil/trace.go:171","msg":"trace[875147744] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8753; }","duration":"190.548858ms","start":"2026-10-14T16:01:00.020242Z","end":"2026-10-14T16:01:00.210791Z","steps":["trace[875147744] 'range keys from in-memory index tree' (duration: 190.139258ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:00.315587Z","caller":"traceutil/trace.go:171","msg":"trace[934063964] transaction","detail":"{read_only:false; response_revision:8754; number_of_response:1; }","duration":"100.180609ms","start":"2026-10-14T16:01:00.215362Z","end":"2026-10-14T16:01:00.315543Z","steps":["trace[934063964] 'process raft request' (duration: 99.924653ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:00.353952Z","caller":"traceutil/trace.go:171","msg":"trace[2015480111] transaction","detail":"{read_only:false; response_revision:8755; number_of_response:1; }","duration":"111.619176ms","start":"2026-10-14T16:01:00.2423Z","end":"2026-10-14T16:01:00.353919Z","steps":["trace[2015480111] 'process raft request' (duration: 111.402821ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:00.507713Z","caller":"traceutil/trace.go:171","msg":"trace[598130367] linearizableReadLoop","detail":"{readStateIndex:9071; appliedIndex:9070; }","duration":"125.071086ms","start":"2026-10-14T16:01:00.382622Z","end":"2026-10-14T16:01:00.507693Z","steps":["trace[598130367] 'read index received' (duration: 78.028229ms)","trace[598130367] 'applied index is now lower than readState.Index' (duration: 47.041917ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:01:00.507843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.204799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:01:00.507868Z","caller":"traceutil/trace.go:171","msg":"trace[1281285518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8758; }","duration":"125.464726ms","start":"2026-10-14T16:01:00.382395Z","end":"2026-10-14T16:01:00.50786Z","steps":["trace[1281285518] 'agreement among raft nodes before linearized reading' (duration: 125.402895ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:00.507847Z","caller":"traceutil/trace.go:171","msg":"trace[665064715] transaction","detail":"{read_only:false; response_revision:8758; number_of_response:1; }","duration":"149.538905ms","start":"2026-10-14T16:01:00.358263Z","end":"2026-10-14T16:01:00.507802Z","steps":["trace[665064715] 'process raft request' (duration: 102.337604ms)","trace[665064715] 'compare' (duration: 46.990865ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:01:00.507922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.486114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:01:00.507993Z","caller":"traceutil/trace.go:171","msg":"trace[395047884] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:8758; }","duration":"113.599837ms","start":"2026-10-14T16:01:00.394375Z","end":"2026-10-14T16:01:00.507975Z","steps":["trace[395047884] 'agreement among raft nodes before linearized reading' (duration: 113.489484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:01.139838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.177591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T16:01:01.139998Z","caller":"traceutil/trace.go:171","msg":"trace[993075642] linearizableReadLoop","detail":"{readStateIndex:9073; appliedIndex:9072; }","duration":"152.390215ms","start":"2026-10-14T16:01:00.98759Z","end":"2026-10-14T16:01:01.13998Z","steps":["trace[993075642] 'read index received' (duration: 35.877455ms)","trace[993075642] 'applied index is now lower than readState.Index' (duration: 116.51152ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:01.140106Z","caller":"traceutil/trace.go:171","msg":"trace[592654245] transaction","detail":"{read_only:false; response_revision:8760; number_of_response:1; }","duration":"272.738183ms","start":"2026-10-14T16:01:00.867338Z","end":"2026-10-14T16:01:01.140076Z","steps":["trace[592654245] 'process raft request' (duration: 156.199002ms)","trace[592654245] 'compare' (duration: 116.015587ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:01:01.140142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.442577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T16:01:01.140237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.648201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-14T16:01:01.140284Z","caller":"traceutil/trace.go:171","msg":"trace[1471482790] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:8760; }","duration":"133.635393ms","start":"2026-10-14T16:01:01.006634Z","end":"2026-10-14T16:01:01.140269Z","steps":["trace[1471482790] 'agreement among raft nodes before linearized reading' (duration: 133.470088ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:01.140304Z","caller":"traceutil/trace.go:171","msg":"trace[713644976] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8760; }","duration":"152.714143ms","start":"2026-10-14T16:01:00.987574Z","end":"2026-10-14T16:01:01.140288Z","steps":["trace[713644976] 'agreement among raft nodes before linearized reading' (duration: 152.517138ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:27.006433Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002429.wal"} {"level":"info","ts":"2026-10-14T16:01:32.696769Z","caller":"traceutil/trace.go:171","msg":"trace[502580080] transaction","detail":"{read_only:false; response_revision:8981; number_of_response:1; }","duration":"132.68274ms","start":"2026-10-14T16:01:32.564064Z","end":"2026-10-14T16:01:32.696746Z","steps":["trace[502580080] 'process raft request' (duration: 117.809775ms)","trace[502580080] 'compare' (duration: 14.726911ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:38.978807Z","caller":"traceutil/trace.go:171","msg":"trace[814227093] transaction","detail":"{read_only:false; response_revision:9018; number_of_response:1; }","duration":"176.498911ms","start":"2026-10-14T16:01:38.80227Z","end":"2026-10-14T16:01:38.978769Z","steps":["trace[814227093] 'process raft request' (duration: 155.254377ms)","trace[814227093] 'compare' (duration: 20.883515ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:39.47745Z","caller":"traceutil/trace.go:171","msg":"trace[70196947] transaction","detail":"{read_only:false; response_revision:9020; number_of_response:1; }","duration":"127.643951ms","start":"2026-10-14T16:01:39.349773Z","end":"2026-10-14T16:01:39.477417Z","steps":["trace[70196947] 'process raft request' (duration: 127.436396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:39.82609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.952059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-14T16:01:39.826166Z","caller":"traceutil/trace.go:171","msg":"trace[330005803] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9021; }","duration":"223.074723ms","start":"2026-10-14T16:01:39.603073Z","end":"2026-10-14T16:01:39.826147Z","steps":["trace[330005803] 'range keys from in-memory index tree' (duration: 222.735594ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:46.289729Z","caller":"traceutil/trace.go:171","msg":"trace[2077589147] transaction","detail":"{read_only:false; response_revision:9098; number_of_response:1; }","duration":"108.243694ms","start":"2026-10-14T16:01:46.18146Z","end":"2026-10-14T16:01:46.289703Z","steps":["trace[2077589147] 'process raft request' (duration: 108.201843ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:46.289801Z","caller":"traceutil/trace.go:171","msg":"trace[1639963836] transaction","detail":"{read_only:false; response_revision:9096; number_of_response:1; }","duration":"167.317731ms","start":"2026-10-14T16:01:46.122443Z","end":"2026-10-14T16:01:46.28976Z","steps":["trace[1639963836] 'process raft request' (duration: 152.19778ms)","trace[1639963836] 'compare' (duration: 14.845644ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:46.290147Z","caller":"traceutil/trace.go:171","msg":"trace[82206952] transaction","detail":"{read_only:false; response_revision:9097; number_of_response:1; }","duration":"140.805391ms","start":"2026-10-14T16:01:46.14933Z","end":"2026-10-14T16:01:46.290136Z","steps":["trace[82206952] 'process raft request' (duration: 140.288559ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:44.677998Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"777352cb93f4c05d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T16:02:44.687586Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-14T16:02:44.68774Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-14T16:03:08.40786Z","caller":"traceutil/trace.go:171","msg":"trace[1059723820] transaction","detail":"{read_only:false; response_revision:9880; number_of_response:1; }","duration":"103.507495ms","start":"2026-10-14T16:03:08.304327Z","end":"2026-10-14T16:03:08.407834Z","steps":["trace[1059723820] 'process raft request' (duration: 103.358781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:03:08.898086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.040036ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-14T16:03:08.898234Z","caller":"traceutil/trace.go:171","msg":"trace[1248201633] linearizableReadLoop","detail":"{readStateIndex:10230; appliedIndex:10229; }","duration":"148.315622ms","start":"2026-10-14T16:03:08.749898Z","end":"2026-10-14T16:03:08.898214Z","steps":["trace[1248201633] 'read index received' (duration: 29.074931ms)","trace[1248201633] 'applied index is now lower than readState.Index' (duration: 119.238791ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:03:08.898477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.556978ms","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-14T16:03:08.898599Z","caller":"traceutil/trace.go:171","msg":"trace[1249422317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9880; }","duration":"148.726362ms","start":"2026-10-14T16:03:08.749843Z","end":"2026-10-14T16:03:08.89857Z","steps":["trace[1249422317] 'agreement among raft nodes before linearized reading' (duration: 148.432784ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:04:30.28315Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8047} {"level":"info","ts":"2026-10-14T16:04:30.357578Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8047,"took":"72.372489ms","hash":1205881965,"current-db-size-bytes":55971840,"current-db-size":"56 MB","current-db-size-in-use-bytes":28692480,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-14T16:04:30.357642Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1205881965,"revision":8047,"compact-revision":5840} {"level":"info","ts":"2026-10-14T16:06:54.058837Z","caller":"traceutil/trace.go:171","msg":"trace[435344042] linearizableReadLoop","detail":"{readStateIndex:12143; appliedIndex:12142; }","duration":"105.773297ms","start":"2026-10-14T16:06:53.95303Z","end":"2026-10-14T16:06:54.058803Z","steps":["trace[435344042] 'read index received' (duration: 38.205469ms)","trace[435344042] 'applied index is now lower than readState.Index' (duration: 67.566428ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:06:54.058918Z","caller":"traceutil/trace.go:171","msg":"trace[1063478603] transaction","detail":"{read_only:false; response_revision:11737; number_of_response:1; }","duration":"115.002906ms","start":"2026-10-14T16:06:53.943862Z","end":"2026-10-14T16:06:54.058865Z","steps":["trace[1063478603] 'process raft request' (duration: 47.48585ms)","trace[1063478603] 'compare' (duration: 67.308001ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:06:54.059059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.008802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:06:54.059148Z","caller":"traceutil/trace.go:171","msg":"trace[1345645886] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:11737; }","duration":"106.116725ms","start":"2026-10-14T16:06:53.953009Z","end":"2026-10-14T16:06:54.059126Z","steps":["trace[1345645886] 'agreement among raft nodes before linearized reading' (duration: 105.94313ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:06:54.352781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.315959ms","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-14T16:06:54.352913Z","caller":"traceutil/trace.go:171","msg":"trace[1908842995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11737; }","duration":"145.493982ms","start":"2026-10-14T16:06:54.207377Z","end":"2026-10-14T16:06:54.352871Z","steps":["trace[1908842995] 'range keys from in-memory index tree' (duration: 145.041962ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:06:54.515465Z","caller":"traceutil/trace.go:171","msg":"trace[1858789003] transaction","detail":"{read_only:false; response_revision:11739; number_of_response:1; }","duration":"123.996768ms","start":"2026-10-14T16:06:54.391429Z","end":"2026-10-14T16:06:54.515425Z","steps":["trace[1858789003] 'process raft request' (duration: 97.710116ms)","trace[1858789003] 'compare' (duration: 26.165509ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:09:30.29056Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10449} {"level":"info","ts":"2026-10-14T16:09:30.383195Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10449,"took":"90.408543ms","hash":2630823322,"current-db-size-bytes":55971840,"current-db-size":"56 MB","current-db-size-in-use-bytes":27901952,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T16:09:30.383259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2630823322,"revision":10449,"compact-revision":8047} {"level":"info","ts":"2026-10-14T16:12:44.506846Z","caller":"traceutil/trace.go:171","msg":"trace[1011403484] linearizableReadLoop","detail":"{readStateIndex:14619; appliedIndex:14618; }","duration":"123.04106ms","start":"2026-10-14T16:12:44.383776Z","end":"2026-10-14T16:12:44.506817Z","steps":["trace[1011403484] 'read index received' (duration: 122.277371ms)","trace[1011403484] 'applied index is now lower than readState.Index' (duration: 762.579µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:12:44.506898Z","caller":"traceutil/trace.go:171","msg":"trace[994130825] transaction","detail":"{read_only:false; response_revision:14130; number_of_response:1; }","duration":"126.205968ms","start":"2026-10-14T16:12:44.38066Z","end":"2026-10-14T16:12:44.506866Z","steps":["trace[994130825] 'process raft request' (duration: 125.444689ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:12:44.50704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.014469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:12:44.507079Z","caller":"traceutil/trace.go:171","msg":"trace[622779154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14130; }","duration":"123.09592ms","start":"2026-10-14T16:12:44.383973Z","end":"2026-10-14T16:12:44.507068Z","steps":["trace[622779154] 'agreement among raft nodes before linearized reading' (duration: 122.998088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:12:44.507693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.892641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:62 size:582636"} {"level":"info","ts":"2026-10-14T16:12:44.507805Z","caller":"traceutil/trace.go:171","msg":"trace[9413519] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:62; response_revision:14130; }","duration":"124.059854ms","start":"2026-10-14T16:12:44.383726Z","end":"2026-10-14T16:12:44.507785Z","steps":["trace[9413519] 'agreement among raft nodes before linearized reading' (duration: 123.208063ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:13:05.519769Z","caller":"traceutil/trace.go:171","msg":"trace[833916888] transaction","detail":"{read_only:false; response_revision:14246; number_of_response:1; }","duration":"142.182323ms","start":"2026-10-14T16:13:05.377536Z","end":"2026-10-14T16:13:05.519718Z","steps":["trace[833916888] 'process raft request' (duration: 142.013609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:13:05.520113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.662541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:13:05.520266Z","caller":"traceutil/trace.go:171","msg":"trace[1300737376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14246; }","duration":"137.865477ms","start":"2026-10-14T16:13:05.382302Z","end":"2026-10-14T16:13:05.520168Z","steps":["trace[1300737376] 'agreement among raft nodes before linearized reading' (duration: 137.582719ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:13:05.52027Z","caller":"traceutil/trace.go:171","msg":"trace[1619777464] linearizableReadLoop","detail":"{readStateIndex:14739; appliedIndex:14738; }","duration":"137.332593ms","start":"2026-10-14T16:13:05.382348Z","end":"2026-10-14T16:13:05.51968Z","steps":["trace[1619777464] 'read index received' (duration: 137.137478ms)","trace[1619777464] 'applied index is now lower than readState.Index' (duration: 194.165µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:13:05.529547Z","caller":"traceutil/trace.go:171","msg":"trace[1989920033] transaction","detail":"{read_only:false; response_revision:14247; number_of_response:1; }","duration":"107.631727ms","start":"2026-10-14T16:13:05.421883Z","end":"2026-10-14T16:13:05.529515Z","steps":["trace[1989920033] 'process raft request' (duration: 107.391471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:13:23.085594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.013412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T16:13:23.085776Z","caller":"traceutil/trace.go:171","msg":"trace[1643888356] linearizableReadLoop","detail":"{readStateIndex:14847; appliedIndex:14846; }","duration":"261.457629ms","start":"2026-10-14T16:13:22.824295Z","end":"2026-10-14T16:13:23.085753Z","steps":["trace[1643888356] 'read index received' (duration: 89.151419ms)","trace[1643888356] 'applied index is now lower than readState.Index' (duration: 172.30424ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:13:23.085996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.697574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4dw9x\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-14T16:13:23.086042Z","caller":"traceutil/trace.go:171","msg":"trace[428430052] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4dw9x; range_end:; response_count:1; response_revision:14349; }","duration":"261.792797ms","start":"2026-10-14T16:13:22.824234Z","end":"2026-10-14T16:13:23.086026Z","steps":["trace[428430052] 'agreement among raft nodes before linearized reading' (duration: 261.581021ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:13:23.086054Z","caller":"traceutil/trace.go:171","msg":"trace[1253593382] transaction","detail":"{read_only:false; response_revision:14349; number_of_response:1; }","duration":"298.645871ms","start":"2026-10-14T16:13:22.787381Z","end":"2026-10-14T16:13:23.086027Z","steps":["trace[1253593382] 'process raft request' (duration: 126.092175ms)","trace[1253593382] 'compare' (duration: 171.778516ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:14:30.301252Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12779} {"level":"info","ts":"2026-10-14T16:14:30.386317Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12779,"took":"82.250148ms","hash":2397364146,"current-db-size-bytes":55971840,"current-db-size":"56 MB","current-db-size-in-use-bytes":31031296,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T16:14:30.386459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2397364146,"revision":12779,"compact-revision":10449} {"level":"info","ts":"2026-10-14T16:15:18.191142Z","caller":"traceutil/trace.go:171","msg":"trace[1709801897] transaction","detail":"{read_only:false; response_revision:15504; number_of_response:1; }","duration":"159.212036ms","start":"2026-10-14T16:15:18.031903Z","end":"2026-10-14T16:15:18.191115Z","steps":["trace[1709801897] 'process raft request' (duration: 159.060453ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:15:18.239175Z","caller":"traceutil/trace.go:171","msg":"trace[1177485042] linearizableReadLoop","detail":"{readStateIndex:16040; appliedIndex:16038; }","duration":"193.210118ms","start":"2026-10-14T16:15:18.045946Z","end":"2026-10-14T16:15:18.239156Z","steps":["trace[1177485042] 'read index received' (duration: 145.072876ms)","trace[1177485042] 'applied index is now lower than readState.Index' (duration: 48.135802ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:15:18.23942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.458184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-14T16:15:18.239496Z","caller":"traceutil/trace.go:171","msg":"trace[1543337969] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15505; }","duration":"193.577897ms","start":"2026-10-14T16:15:18.045905Z","end":"2026-10-14T16:15:18.239483Z","steps":["trace[1543337969] 'agreement among raft nodes before linearized reading' (duration: 193.385562ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:15:18.239539Z","caller":"traceutil/trace.go:171","msg":"trace[1344724627] transaction","detail":"{read_only:false; response_revision:15505; number_of_response:1; }","duration":"207.457491ms","start":"2026-10-14T16:15:18.031987Z","end":"2026-10-14T16:15:18.239445Z","steps":["trace[1344724627] 'process raft request' (duration: 183.793855ms)","trace[1344724627] 'compare' (duration: 23.101362ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:19:30.308092Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15230} {"level":"info","ts":"2026-10-14T16:19:30.417633Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15230,"took":"106.411706ms","hash":3492370691,"current-db-size-bytes":55971840,"current-db-size":"56 MB","current-db-size-in-use-bytes":31842304,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T16:19:30.417775Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3492370691,"revision":15230,"compact-revision":12779} {"level":"info","ts":"2026-10-14T16:21:11.51743Z","caller":"traceutil/trace.go:171","msg":"trace[54186258] linearizableReadLoop","detail":"{readStateIndex:18742; appliedIndex:18741; }","duration":"266.197507ms","start":"2026-10-14T16:21:11.251175Z","end":"2026-10-14T16:21:11.517372Z","steps":["trace[54186258] 'read index received' (duration: 265.965973ms)","trace[54186258] 'applied index is now lower than readState.Index' (duration: 230.274µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:21:11.517588Z","caller":"traceutil/trace.go:171","msg":"trace[1400684711] transaction","detail":"{read_only:false; response_revision:18120; number_of_response:1; }","duration":"364.885821ms","start":"2026-10-14T16:21:11.152687Z","end":"2026-10-14T16:21:11.517573Z","steps":["trace[1400684711] 'process raft request' (duration: 364.509031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:11.517719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:11.152661Z","time spent":"364.961203ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:21:11.517782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.844288ms","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-14T16:21:11.517807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.028312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:21:11.517876Z","caller":"traceutil/trace.go:171","msg":"trace[1983873044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18120; }","duration":"237.990942ms","start":"2026-10-14T16:21:11.279862Z","end":"2026-10-14T16:21:11.517853Z","steps":["trace[1983873044] 'agreement among raft nodes before linearized reading' (duration: 237.757126ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:21:11.517879Z","caller":"traceutil/trace.go:171","msg":"trace[320958177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18120; }","duration":"135.152125ms","start":"2026-10-14T16:21:11.382709Z","end":"2026-10-14T16:21:11.517861Z","steps":["trace[320958177] 'agreement among raft nodes before linearized reading' (duration: 135.025732ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:11.51789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.709031ms","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-14T16:21:11.517966Z","caller":"traceutil/trace.go:171","msg":"trace[2050354030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18120; }","duration":"266.813324ms","start":"2026-10-14T16:21:11.251125Z","end":"2026-10-14T16:21:11.517938Z","steps":["trace[2050354030] 'agreement among raft nodes before linearized reading' (duration: 266.639529ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:11.517996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.36087ms","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-14T16:21:11.518036Z","caller":"traceutil/trace.go:171","msg":"trace[1875038950] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18120; }","duration":"224.435232ms","start":"2026-10-14T16:21:11.293589Z","end":"2026-10-14T16:21:11.518024Z","steps":["trace[1875038950] 'agreement among raft nodes before linearized reading' (duration: 224.308629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:12.088679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.259969ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:21:12.08888Z","caller":"traceutil/trace.go:171","msg":"trace[1922378328] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:18123; }","duration":"343.409383ms","start":"2026-10-14T16:21:11.745386Z","end":"2026-10-14T16:21:12.088795Z","steps":["trace[1922378328] 'range keys from in-memory index tree' (duration: 343.240579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:12.088997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.122279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:21:12.089125Z","caller":"traceutil/trace.go:171","msg":"trace[491378119] transaction","detail":"{read_only:false; response_revision:18124; number_of_response:1; }","duration":"478.784045ms","start":"2026-10-14T16:21:11.610311Z","end":"2026-10-14T16:21:12.089095Z","steps":["trace[491378119] 'process raft request' (duration: 364.553333ms)","trace[491378119] 'compare' (duration: 113.928504ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:21:12.08919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:11.610282Z","time spent":"478.878917ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:21:12.930394Z","caller":"traceutil/trace.go:171","msg":"trace[1601393191] linearizableReadLoop","detail":"{readStateIndex:18747; appliedIndex:18745; }","duration":"1.182415383s","start":"2026-10-14T16:21:11.747956Z","end":"2026-10-14T16:21:12.930371Z","steps":["trace[1601393191] 'read index received' (duration: 226.923405ms)","trace[1601393191] 'applied index is now lower than readState.Index' (duration: 955.491318ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:21:12.930445Z","caller":"traceutil/trace.go:171","msg":"trace[1833603774] transaction","detail":"{read_only:false; response_revision:18125; number_of_response:1; }","duration":"1.307718171s","start":"2026-10-14T16:21:11.622697Z","end":"2026-10-14T16:21:12.930415Z","steps":["trace[1833603774] 'process raft request' (duration: 1.307557617s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:12.930571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:11.62267Z","time spent":"1.307841284s","remote":"127.0.0.1:51536","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-14T16:21:12.930572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182609517s","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-14T16:21:12.930609Z","caller":"traceutil/trace.go:171","msg":"trace[1814913544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18125; }","duration":"1.182689619s","start":"2026-10-14T16:21:11.74791Z","end":"2026-10-14T16:21:12.930599Z","steps":["trace[1814913544] 'agreement among raft nodes before linearized reading' (duration: 1.182558286s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:12.930632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:11.747858Z","time spent":"1.182768081s","remote":"127.0.0.1:51536","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-14T16:21:12.945472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.983085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:21:12.945573Z","caller":"traceutil/trace.go:171","msg":"trace[1272574764] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:0; response_revision:18126; }","duration":"882.129319ms","start":"2026-10-14T16:21:12.063428Z","end":"2026-10-14T16:21:12.945557Z","steps":["trace[1272574764] 'agreement among raft nodes before linearized reading' (duration: 881.989726ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:12.945613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.533473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fv7lz\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-10-14T16:21:12.945667Z","caller":"traceutil/trace.go:171","msg":"trace[797624021] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fv7lz; range_end:; response_count:1; response_revision:18126; }","duration":"960.643335ms","start":"2026-10-14T16:21:11.985006Z","end":"2026-10-14T16:21:12.945649Z","steps":["trace[797624021] 'agreement among raft nodes before linearized reading' (duration: 960.542653ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:12.945707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:11.984985Z","time spent":"960.715368ms","remote":"127.0.0.1:51464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22156,"request content":"key:\"/registry/pods/openstack/nova-compute-default-fv7lz\" "} {"level":"warn","ts":"2026-10-14T16:21:12.945717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.772448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T16:21:12.945748Z","caller":"traceutil/trace.go:171","msg":"trace[1034162752] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:18126; }","duration":"459.83569ms","start":"2026-10-14T16:21:12.485901Z","end":"2026-10-14T16:21:12.945736Z","steps":["trace[1034162752] 'agreement among raft nodes before linearized reading' (duration: 459.784288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:12.945775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:12.485877Z","time spent":"459.892342ms","remote":"127.0.0.1:39646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-10-14T16:21:12.945854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.460036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-lk9xd\" ","response":"range_response_count:1 size:9797"} {"level":"info","ts":"2026-10-14T16:21:12.945897Z","caller":"traceutil/trace.go:171","msg":"trace[2127434053] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-lk9xd; range_end:; response_count:1; response_revision:18126; }","duration":"874.521698ms","start":"2026-10-14T16:21:12.071368Z","end":"2026-10-14T16:21:12.94589Z","steps":["trace[2127434053] 'agreement among raft nodes before linearized reading' (duration: 874.273261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:12.945936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:12.071358Z","time spent":"874.569449ms","remote":"127.0.0.1:51464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9820,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-lk9xd\" "} {"level":"warn","ts":"2026-10-14T16:21:12.945622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:12.063384Z","time spent":"882.229882ms","remote":"127.0.0.1:57830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" "} {"level":"warn","ts":"2026-10-14T16:21:12.945998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091583486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-14T16:21:12.946036Z","caller":"traceutil/trace.go:171","msg":"trace[1859439891] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18126; }","duration":"1.091661928s","start":"2026-10-14T16:21:11.854363Z","end":"2026-10-14T16:21:12.946025Z","steps":["trace[1859439891] 'agreement among raft nodes before linearized reading' (duration: 1.091539405s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:12.945506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165932668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-14T16:21:12.946068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:11.854334Z","time spent":"1.09172763s","remote":"127.0.0.1:51464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-14T16:21:12.946077Z","caller":"traceutil/trace.go:171","msg":"trace[2022640427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18126; }","duration":"1.166620405s","start":"2026-10-14T16:21:11.779448Z","end":"2026-10-14T16:21:12.946069Z","steps":["trace[2022640427] 'agreement among raft nodes before linearized reading' (duration: 1.165884426s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:12.946103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:11.779399Z","time spent":"1.166694828s","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-14T16:21:12.94552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.493448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-14T16:21:12.946157Z","caller":"traceutil/trace.go:171","msg":"trace[658962519] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18126; }","duration":"701.177765ms","start":"2026-10-14T16:21:12.244968Z","end":"2026-10-14T16:21:12.946146Z","steps":["trace[658962519] 'agreement among raft nodes before linearized reading' (duration: 700.425956ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:12.946186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:12.244921Z","time spent":"701.258227ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-14T16:21:12.9455Z","caller":"traceutil/trace.go:171","msg":"trace[194377914] transaction","detail":"{read_only:false; response_revision:18126; number_of_response:1; }","duration":"582.056322ms","start":"2026-10-14T16:21:12.363398Z","end":"2026-10-14T16:21:12.945455Z","steps":["trace[194377914] 'process raft request' (duration: 581.799535ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:12.946284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:12.363372Z","time spent":"582.879933ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:23:27.427412Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"777352cb93f4c05d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T16:23:27.438126Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-14T16:23:27.438276Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-14T16:24:30.314739Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17424} {"level":"info","ts":"2026-10-14T16:24:30.426255Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17424,"took":"108.322325ms","hash":1475380825,"current-db-size-bytes":55971840,"current-db-size":"56 MB","current-db-size-in-use-bytes":34676736,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-14T16:24:30.426314Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1475380825,"revision":17424,"compact-revision":15230} {"level":"info","ts":"2026-10-14T16:26:47.655388Z","caller":"traceutil/trace.go:171","msg":"trace[1680174243] linearizableReadLoop","detail":"{readStateIndex:21451; appliedIndex:21450; }","duration":"144.467917ms","start":"2026-10-14T16:26:47.510893Z","end":"2026-10-14T16:26:47.655361Z","steps":["trace[1680174243] 'read index received' (duration: 144.262742ms)","trace[1680174243] 'applied index is now lower than readState.Index' (duration: 204.095µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:26:47.655599Z","caller":"traceutil/trace.go:171","msg":"trace[2017107935] transaction","detail":"{read_only:false; response_revision:20743; number_of_response:1; }","duration":"197.632905ms","start":"2026-10-14T16:26:47.457796Z","end":"2026-10-14T16:26:47.655429Z","steps":["trace[2017107935] 'process raft request' (duration: 197.41804ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:47.65582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.909789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-14T16:26:47.65591Z","caller":"traceutil/trace.go:171","msg":"trace[952711237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20743; }","duration":"145.012672ms","start":"2026-10-14T16:26:47.510874Z","end":"2026-10-14T16:26:47.655887Z","steps":["trace[952711237] 'agreement among raft nodes before linearized reading' (duration: 144.592671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:47.655871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.685739ms","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-14T16:26:47.656061Z","caller":"traceutil/trace.go:171","msg":"trace[954602498] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20743; }","duration":"129.961826ms","start":"2026-10-14T16:26:47.526013Z","end":"2026-10-14T16:26:47.655975Z","steps":["trace[954602498] 'agreement among raft nodes before linearized reading' (duration: 129.496134ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:47.947478Z","caller":"traceutil/trace.go:171","msg":"trace[245539581] linearizableReadLoop","detail":"{readStateIndex:21453; appliedIndex:21451; }","duration":"265.05362ms","start":"2026-10-14T16:26:47.682395Z","end":"2026-10-14T16:26:47.947449Z","steps":["trace[245539581] 'read index received' (duration: 172.901269ms)","trace[245539581] 'applied index is now lower than readState.Index' (duration: 92.151071ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:26:47.947514Z","caller":"traceutil/trace.go:171","msg":"trace[1851475422] transaction","detail":"{read_only:false; response_revision:20744; number_of_response:1; }","duration":"286.532626ms","start":"2026-10-14T16:26:47.660943Z","end":"2026-10-14T16:26:47.947476Z","steps":["trace[1851475422] 'process raft request' (duration: 194.458926ms)","trace[1851475422] 'compare' (duration: 91.640139ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:26:47.947562Z","caller":"traceutil/trace.go:171","msg":"trace[1316821216] transaction","detail":"{read_only:false; response_revision:20745; number_of_response:1; }","duration":"286.000853ms","start":"2026-10-14T16:26:47.66152Z","end":"2026-10-14T16:26:47.94752Z","steps":["trace[1316821216] 'process raft request' (duration: 285.87407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:47.947751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.802467ms","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:6"} {"level":"warn","ts":"2026-10-14T16:26:47.947769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.337477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T16:26:47.947807Z","caller":"traceutil/trace.go:171","msg":"trace[319220884] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:20745; }","duration":"104.936491ms","start":"2026-10-14T16:26:47.842853Z","end":"2026-10-14T16:26:47.94779Z","steps":["trace[319220884] 'agreement among raft nodes before linearized reading' (duration: 104.778307ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:47.94785Z","caller":"traceutil/trace.go:171","msg":"trace[292152192] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:20745; }","duration":"265.461541ms","start":"2026-10-14T16:26:47.682363Z","end":"2026-10-14T16:26:47.947825Z","steps":["trace[292152192] 'agreement among raft nodes before linearized reading' (duration: 265.227125ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:48.311381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.002222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T16:26:48.311473Z","caller":"traceutil/trace.go:171","msg":"trace[2090103632] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:20745; }","duration":"135.153356ms","start":"2026-10-14T16:26:48.176296Z","end":"2026-10-14T16:26:48.31145Z","steps":["trace[2090103632] 'count revisions from in-memory index tree' (duration: 134.905849ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:48.535157Z","caller":"traceutil/trace.go:171","msg":"trace[1100638779] transaction","detail":"{read_only:false; response_revision:20746; number_of_response:1; }","duration":"272.366972ms","start":"2026-10-14T16:26:48.262763Z","end":"2026-10-14T16:26:48.53513Z","steps":["trace[1100638779] 'process raft request' (duration: 272.180958ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:48.535141Z","caller":"traceutil/trace.go:171","msg":"trace[383968150] linearizableReadLoop","detail":"{readStateIndex:21454; appliedIndex:21453; }","duration":"258.858105ms","start":"2026-10-14T16:26:48.276247Z","end":"2026-10-14T16:26:48.535105Z","steps":["trace[383968150] 'read index received' (duration: 258.719451ms)","trace[383968150] 'applied index is now lower than readState.Index' (duration: 137.214µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:26:48.535283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.013449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:26:48.53535Z","caller":"traceutil/trace.go:171","msg":"trace[1751842168] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:20746; }","duration":"259.152592ms","start":"2026-10-14T16:26:48.276178Z","end":"2026-10-14T16:26:48.53533Z","steps":["trace[1751842168] 'agreement among raft nodes before linearized reading' (duration: 259.026859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:48.535405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.409997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:26:48.535455Z","caller":"traceutil/trace.go:171","msg":"trace[24228790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20746; }","duration":"152.493749ms","start":"2026-10-14T16:26:48.382948Z","end":"2026-10-14T16:26:48.535442Z","steps":["trace[24228790] 'agreement among raft nodes before linearized reading' (duration: 152.412657ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:48.535649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.476481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d82a99d0-0070-48df-8ffc-8ad1ed58f2a6\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-10-14T16:26:48.535765Z","caller":"traceutil/trace.go:171","msg":"trace[1256268288] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d82a99d0-0070-48df-8ffc-8ad1ed58f2a6; range_end:; response_count:1; response_revision:20746; }","duration":"221.669916ms","start":"2026-10-14T16:26:48.314061Z","end":"2026-10-14T16:26:48.535731Z","steps":["trace[1256268288] 'agreement among raft nodes before linearized reading' (duration: 221.279716ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:48.535781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.274413ms","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:8"} {"level":"info","ts":"2026-10-14T16:26:48.535894Z","caller":"traceutil/trace.go:171","msg":"trace[1864397559] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20746; }","duration":"170.460357ms","start":"2026-10-14T16:26:48.365405Z","end":"2026-10-14T16:26:48.535865Z","steps":["trace[1864397559] 'agreement among raft nodes before linearized reading' (duration: 170.232582ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:48.935255Z","caller":"traceutil/trace.go:171","msg":"trace[1399940605] transaction","detail":"{read_only:false; response_revision:20747; number_of_response:1; }","duration":"142.044137ms","start":"2026-10-14T16:26:48.793184Z","end":"2026-10-14T16:26:48.935228Z","steps":["trace[1399940605] 'process raft request' (duration: 141.888353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:49.284001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.242099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:26:49.284409Z","caller":"traceutil/trace.go:171","msg":"trace[1856876538] transaction","detail":"{read_only:false; response_revision:20748; number_of_response:1; }","duration":"486.402277ms","start":"2026-10-14T16:26:48.797969Z","end":"2026-10-14T16:26:49.284372Z","steps":["trace[1856876538] 'process raft request' (duration: 225.697996ms)","trace[1856876538] 'compare' (duration: 259.87676ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:26:49.284487Z","caller":"traceutil/trace.go:171","msg":"trace[1412936193] transaction","detail":"{read_only:false; response_revision:20749; number_of_response:1; }","duration":"416.158893ms","start":"2026-10-14T16:26:48.868288Z","end":"2026-10-14T16:26:49.284447Z","steps":["trace[1412936193] 'process raft request' (duration: 415.858005ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:49.284537Z","caller":"traceutil/trace.go:171","msg":"trace[1934393919] linearizableReadLoop","detail":"{readStateIndex:21456; appliedIndex:21454; }","duration":"424.005509ms","start":"2026-10-14T16:26:48.860508Z","end":"2026-10-14T16:26:49.284514Z","steps":["trace[1934393919] 'read index received' (duration: 74.635334ms)","trace[1934393919] 'applied index is now lower than readState.Index' (duration: 349.368014ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:26:49.284596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:26:48.79795Z","time spent":"486.54362ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:26:49.284704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:26:48.868258Z","time spent":"416.309286ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:26:49.487722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.389539ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-14T16:26:49.487835Z","caller":"traceutil/trace.go:171","msg":"trace[1851863571] linearizableReadLoop","detail":"{readStateIndex:21458; appliedIndex:21457; }","duration":"203.174524ms","start":"2026-10-14T16:26:49.284642Z","end":"2026-10-14T16:26:49.487816Z","steps":["trace[1851863571] 'read index received' (duration: 45.601µs)","trace[1851863571] 'applied index is now lower than readState.Index' (duration: 203.127613ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:26:49.48808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.761186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T16:26:49.488121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.597814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19016"} {"level":"info","ts":"2026-10-14T16:26:49.48817Z","caller":"traceutil/trace.go:171","msg":"trace[1582666356] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:20749; }","duration":"233.704176ms","start":"2026-10-14T16:26:49.254449Z","end":"2026-10-14T16:26:49.488153Z","steps":["trace[1582666356] 'agreement among raft nodes before linearized reading' (duration: 233.47527ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:49.488167Z","caller":"traceutil/trace.go:171","msg":"trace[1364469427] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:20749; }","duration":"490.912559ms","start":"2026-10-14T16:26:48.99723Z","end":"2026-10-14T16:26:49.488143Z","steps":["trace[1364469427] 'agreement among raft nodes before linearized reading' (duration: 490.745465ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:49.488197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.887573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-14T16:26:49.488223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:26:48.997198Z","time spent":"491.009222ms","remote":"127.0.0.1:48574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"warn","ts":"2026-10-14T16:26:49.488223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.543645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-14T16:26:49.488256Z","caller":"traceutil/trace.go:171","msg":"trace[1381826032] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:20749; }","duration":"315.002546ms","start":"2026-10-14T16:26:49.173236Z","end":"2026-10-14T16:26:49.488239Z","steps":["trace[1381826032] 'agreement among raft nodes before linearized reading' (duration: 314.906124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:49.488219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.78435ms","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:8"} {"level":"info","ts":"2026-10-14T16:26:49.488276Z","caller":"traceutil/trace.go:171","msg":"trace[1966898543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20749; }","duration":"115.662598ms","start":"2026-10-14T16:26:49.372598Z","end":"2026-10-14T16:26:49.488261Z","steps":["trace[1966898543] 'agreement among raft nodes before linearized reading' (duration: 115.511644ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:49.488073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.49421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-10-14T16:26:49.488308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:26:49.173205Z","time spent":"315.088739ms","remote":"127.0.0.1:51520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":7,"response size":31,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-10-14T16:26:49.488397Z","caller":"traceutil/trace.go:171","msg":"trace[1828058648] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20749; }","duration":"627.88443ms","start":"2026-10-14T16:26:48.860462Z","end":"2026-10-14T16:26:49.488347Z","steps":["trace[1828058648] 'agreement among raft nodes before linearized reading' (duration: 424.199483ms)","trace[1828058648] 'range keys from in-memory index tree' (duration: 203.180294ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:26:49.488491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:26:48.860443Z","time spent":"628.018093ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-14T16:26:49.488536Z","caller":"traceutil/trace.go:171","msg":"trace[1123047662] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20749; }","duration":"340.942895ms","start":"2026-10-14T16:26:49.147354Z","end":"2026-10-14T16:26:49.488297Z","steps":["trace[1123047662] 'agreement among raft nodes before linearized reading' (duration: 340.791701ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:49.488708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:26:49.147317Z","time spent":"341.373045ms","remote":"127.0.0.1:57862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-10-14T16:26:49.631297Z","caller":"traceutil/trace.go:171","msg":"trace[761475764] linearizableReadLoop","detail":"{readStateIndex:21460; appliedIndex:21458; }","duration":"136.517379ms","start":"2026-10-14T16:26:49.494746Z","end":"2026-10-14T16:26:49.631263Z","steps":["trace[761475764] 'read index received' (duration: 20.433971ms)","trace[761475764] 'applied index is now lower than readState.Index' (duration: 116.082008ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:26:49.6314Z","caller":"traceutil/trace.go:171","msg":"trace[1220468593] transaction","detail":"{read_only:false; response_revision:20751; number_of_response:1; }","duration":"138.009066ms","start":"2026-10-14T16:26:49.493355Z","end":"2026-10-14T16:26:49.631364Z","steps":["trace[1220468593] 'process raft request' (duration: 129.299029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:49.631537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.770085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:1 size:1137"} {"level":"info","ts":"2026-10-14T16:26:49.631595Z","caller":"traceutil/trace.go:171","msg":"trace[2072256067] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:1; response_revision:20751; }","duration":"136.875558ms","start":"2026-10-14T16:26:49.494703Z","end":"2026-10-14T16:26:49.631578Z","steps":["trace[2072256067] 'agreement among raft nodes before linearized reading' (duration: 136.672443ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:29:30.334245Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19781} {"level":"info","ts":"2026-10-14T16:29:30.454236Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19781,"took":"116.369866ms","hash":1458877515,"current-db-size-bytes":55971840,"current-db-size":"56 MB","current-db-size-in-use-bytes":30109696,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T16:29:30.45431Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1458877515,"revision":19781,"compact-revision":17424} {"level":"info","ts":"2026-10-14T16:30:43.474686Z","caller":"traceutil/trace.go:171","msg":"trace[179538874] linearizableReadLoop","detail":"{readStateIndex:23991; appliedIndex:23990; }","duration":"216.180288ms","start":"2026-10-14T16:30:43.25848Z","end":"2026-10-14T16:30:43.47466Z","steps":["trace[179538874] 'read index received' (duration: 215.938102ms)","trace[179538874] 'applied index is now lower than readState.Index' (duration: 241.156µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:30:43.47473Z","caller":"traceutil/trace.go:171","msg":"trace[151582447] transaction","detail":"{read_only:false; response_revision:23210; number_of_response:1; }","duration":"222.100085ms","start":"2026-10-14T16:30:43.252601Z","end":"2026-10-14T16:30:43.474701Z","steps":["trace[151582447] 'process raft request' (duration: 221.89025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:30:43.474925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.433075ms","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-14T16:30:43.474965Z","caller":"traceutil/trace.go:171","msg":"trace[1708388603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23210; }","duration":"216.527787ms","start":"2026-10-14T16:30:43.258426Z","end":"2026-10-14T16:30:43.474953Z","steps":["trace[1708388603] 'agreement among raft nodes before linearized reading' (duration: 216.371503ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:00.535729Z","caller":"traceutil/trace.go:171","msg":"trace[1257880045] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"185.757063ms","start":"2026-10-14T16:34:00.349947Z","end":"2026-10-14T16:34:00.535704Z","steps":["trace[1257880045] 'process raft request' (duration: 185.327512ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:06.114925Z","caller":"traceutil/trace.go:171","msg":"trace[1555636964] linearizableReadLoop","detail":"{readStateIndex:26023; appliedIndex:26022; }","duration":"134.278302ms","start":"2026-10-14T16:34:05.980589Z","end":"2026-10-14T16:34:06.114868Z","steps":["trace[1555636964] 'read index received' (duration: 97.099839ms)","trace[1555636964] 'applied index is now lower than readState.Index' (duration: 37.176263ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:34:06.11499Z","caller":"traceutil/trace.go:171","msg":"trace[1361047632] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"137.81555ms","start":"2026-10-14T16:34:05.977133Z","end":"2026-10-14T16:34:06.114948Z","steps":["trace[1361047632] 'process raft request' (duration: 100.625027ms)","trace[1361047632] 'compare' (duration: 36.810683ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:34:06.115209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.60563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-14T16:34:06.115295Z","caller":"traceutil/trace.go:171","msg":"trace[508450589] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25193; }","duration":"134.739343ms","start":"2026-10-14T16:34:05.980535Z","end":"2026-10-14T16:34:06.115274Z","steps":["trace[508450589] 'agreement among raft nodes before linearized reading' (duration: 134.486577ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:06.291522Z","caller":"traceutil/trace.go:171","msg":"trace[16638865] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"171.107126ms","start":"2026-10-14T16:34:06.120361Z","end":"2026-10-14T16:34:06.291468Z","steps":["trace[16638865] 'process raft request' (duration: 159.360551ms)","trace[16638865] 'compare' (duration: 11.393286ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:34:06.293152Z","caller":"traceutil/trace.go:171","msg":"trace[1952912233] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"116.742222ms","start":"2026-10-14T16:34:06.176385Z","end":"2026-10-14T16:34:06.293127Z","steps":["trace[1952912233] 'process raft request' (duration: 116.464735ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:06.527865Z","caller":"traceutil/trace.go:171","msg":"trace[1492111652] linearizableReadLoop","detail":"{readStateIndex:26026; appliedIndex:26025; }","duration":"167.6936ms","start":"2026-10-14T16:34:06.360138Z","end":"2026-10-14T16:34:06.527832Z","steps":["trace[1492111652] 'read index received' (duration: 131.118872ms)","trace[1492111652] 'applied index is now lower than readState.Index' (duration: 36.573338ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:34:06.528209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.06212ms","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-14T16:34:06.528246Z","caller":"traceutil/trace.go:171","msg":"trace[395572733] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25196; }","duration":"168.147761ms","start":"2026-10-14T16:34:06.360089Z","end":"2026-10-14T16:34:06.528236Z","steps":["trace[395572733] 'agreement among raft nodes before linearized reading' (duration: 168.055549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:06.528257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.008822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:34:06.528304Z","caller":"traceutil/trace.go:171","msg":"trace[2068806585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25196; }","duration":"145.068583ms","start":"2026-10-14T16:34:06.383219Z","end":"2026-10-14T16:34:06.528288Z","steps":["trace[2068806585] 'agreement among raft nodes before linearized reading' (duration: 144.986021ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:06.528295Z","caller":"traceutil/trace.go:171","msg":"trace[1708450382] transaction","detail":"{read_only:false; response_revision:25196; number_of_response:1; }","duration":"224.847455ms","start":"2026-10-14T16:34:06.30339Z","end":"2026-10-14T16:34:06.528237Z","steps":["trace[1708450382] 'process raft request' (duration: 187.931398ms)","trace[1708450382] 'compare' (duration: 35.886221ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:34:06.714725Z","caller":"traceutil/trace.go:171","msg":"trace[836282362] transaction","detail":"{read_only:false; response_revision:25197; number_of_response:1; }","duration":"156.275994ms","start":"2026-10-14T16:34:06.558401Z","end":"2026-10-14T16:34:06.714677Z","steps":["trace[836282362] 'process raft request' (duration: 117.314626ms)","trace[836282362] 'compare' (duration: 38.782764ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:34:06.859733Z","caller":"traceutil/trace.go:171","msg":"trace[2114336808] transaction","detail":"{read_only:false; response_revision:25199; number_of_response:1; }","duration":"138.703772ms","start":"2026-10-14T16:34:06.721002Z","end":"2026-10-14T16:34:06.859706Z","steps":["trace[2114336808] 'process raft request' (duration: 138.6253ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:06.859765Z","caller":"traceutil/trace.go:171","msg":"trace[1354289767] transaction","detail":"{read_only:false; response_revision:25198; number_of_response:1; }","duration":"139.968425ms","start":"2026-10-14T16:34:06.719743Z","end":"2026-10-14T16:34:06.859711Z","steps":["trace[1354289767] 'process raft request' (duration: 122.961338ms)","trace[1354289767] 'compare' (duration: 16.7528ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:34:25.404865Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006679.wal"} {"level":"info","ts":"2026-10-14T16:34:30.341382Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21990} {"level":"info","ts":"2026-10-14T16:34:30.463534Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21990,"took":"116.889995ms","hash":3277240313,"current-db-size-bytes":56815616,"current-db-size":"57 MB","current-db-size-in-use-bytes":45268992,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-14T16:34:30.463603Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3277240313,"revision":21990,"compact-revision":19781} {"level":"info","ts":"2026-10-14T16:35:01.706803Z","caller":"traceutil/trace.go:171","msg":"trace[1746975164] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"114.658258ms","start":"2026-10-14T16:35:01.592121Z","end":"2026-10-14T16:35:01.706779Z","steps":["trace[1746975164] 'process raft request' (duration: 114.532795ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:35:03.510468Z","caller":"traceutil/trace.go:171","msg":"trace[791622460] transaction","detail":"{read_only:false; response_revision:25731; number_of_response:1; }","duration":"273.510649ms","start":"2026-10-14T16:35:03.236931Z","end":"2026-10-14T16:35:03.510442Z","steps":["trace[791622460] 'process raft request' (duration: 273.376645ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:35:03.53326Z","caller":"traceutil/trace.go:171","msg":"trace[83151436] linearizableReadLoop","detail":"{readStateIndex:26577; appliedIndex:26574; }","duration":"149.839352ms","start":"2026-10-14T16:35:03.383402Z","end":"2026-10-14T16:35:03.533241Z","steps":["trace[83151436] 'read index received' (duration: 127.636905ms)","trace[83151436] 'applied index is now lower than readState.Index' (duration: 22.199877ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:35:03.533374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.964725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:35:03.533402Z","caller":"traceutil/trace.go:171","msg":"trace[534930155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25734; }","duration":"150.045037ms","start":"2026-10-14T16:35:03.38335Z","end":"2026-10-14T16:35:03.533395Z","steps":["trace[534930155] 'agreement among raft nodes before linearized reading' (duration: 149.961865ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:35:03.533704Z","caller":"traceutil/trace.go:171","msg":"trace[403497083] transaction","detail":"{read_only:false; response_revision:25732; number_of_response:1; }","duration":"280.633846ms","start":"2026-10-14T16:35:03.253058Z","end":"2026-10-14T16:35:03.533692Z","steps":["trace[403497083] 'process raft request' (duration: 280.015411ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:35:03.533849Z","caller":"traceutil/trace.go:171","msg":"trace[1410681617] transaction","detail":"{read_only:false; response_revision:25733; number_of_response:1; }","duration":"205.414607ms","start":"2026-10-14T16:35:03.328422Z","end":"2026-10-14T16:35:03.533836Z","steps":["trace[1410681617] 'process raft request' (duration: 204.756111ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:35:03.533991Z","caller":"traceutil/trace.go:171","msg":"trace[1530086281] transaction","detail":"{read_only:false; response_revision:25734; number_of_response:1; }","duration":"191.99595ms","start":"2026-10-14T16:35:03.341985Z","end":"2026-10-14T16:35:03.533981Z","steps":["trace[1530086281] 'process raft request' (duration: 191.220791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:03.907053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.669475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.164\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-14T16:35:03.90714Z","caller":"traceutil/trace.go:171","msg":"trace[1316459687] range","detail":"{range_begin:/registry/masterleases/162.253.55.164; range_end:; response_count:1; response_revision:25734; }","duration":"213.794628ms","start":"2026-10-14T16:35:03.693322Z","end":"2026-10-14T16:35:03.907117Z","steps":["trace[1316459687] 'range keys from in-memory index tree' (duration: 213.47827ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:03.907243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.171861ms","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-14T16:35:03.907281Z","caller":"traceutil/trace.go:171","msg":"trace[222026072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25734; }","duration":"197.248023ms","start":"2026-10-14T16:35:03.710025Z","end":"2026-10-14T16:35:03.907273Z","steps":["trace[222026072] 'range keys from in-memory index tree' (duration: 197.053268ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:35:03.937358Z","caller":"traceutil/trace.go:171","msg":"trace[1969145571] transaction","detail":"{read_only:false; response_revision:25735; number_of_response:1; }","duration":"172.843659ms","start":"2026-10-14T16:35:03.764482Z","end":"2026-10-14T16:35:03.937326Z","steps":["trace[1969145571] 'process raft request' (duration: 172.686286ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:35:04.145175Z","caller":"traceutil/trace.go:171","msg":"trace[247055774] linearizableReadLoop","detail":"{readStateIndex:26579; appliedIndex:26577; }","duration":"235.153214ms","start":"2026-10-14T16:35:03.909999Z","end":"2026-10-14T16:35:04.145152Z","steps":["trace[247055774] 'read index received' (duration: 27.151761ms)","trace[247055774] 'applied index is now lower than readState.Index' (duration: 208.000103ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:35:04.145338Z","caller":"traceutil/trace.go:171","msg":"trace[673079978] transaction","detail":"{read_only:false; response_revision:25736; number_of_response:1; }","duration":"233.37907ms","start":"2026-10-14T16:35:03.911927Z","end":"2026-10-14T16:35:04.145306Z","steps":["trace[673079978] 'process raft request' (duration: 233.084242ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:04.145399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.38001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-14T16:35:04.145436Z","caller":"traceutil/trace.go:171","msg":"trace[1075668784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25736; }","duration":"235.453382ms","start":"2026-10-14T16:35:03.90997Z","end":"2026-10-14T16:35:04.145424Z","steps":["trace[1075668784] 'agreement among raft nodes before linearized reading' (duration: 235.268527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:04.410734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.117215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:35:04.410851Z","caller":"traceutil/trace.go:171","msg":"trace[1495492853] transaction","detail":"{read_only:false; response_revision:25737; number_of_response:1; }","duration":"264.293676ms","start":"2026-10-14T16:35:04.146537Z","end":"2026-10-14T16:35:04.410831Z","steps":["trace[1495492853] 'process raft request' (duration: 147.980886ms)","trace[1495492853] 'compare' (duration: 116.015283ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:35:04.485571Z","caller":"traceutil/trace.go:171","msg":"trace[650877968] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"336.12629ms","start":"2026-10-14T16:35:04.149413Z","end":"2026-10-14T16:35:04.48554Z","steps":["trace[650877968] 'process raft request' (duration: 335.941615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:04.485767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:35:04.149393Z","time spent":"336.297274ms","remote":"127.0.0.1:51536","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-14T16:35:04.485953Z","caller":"traceutil/trace.go:171","msg":"trace[1018572866] linearizableReadLoop","detail":"{readStateIndex:26583; appliedIndex:26581; }","duration":"181.256341ms","start":"2026-10-14T16:35:04.304668Z","end":"2026-10-14T16:35:04.485924Z","steps":["trace[1018572866] 'read index received' (duration: 180.656645ms)","trace[1018572866] 'applied index is now lower than readState.Index' (duration: 596.716µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:35:04.48599Z","caller":"traceutil/trace.go:171","msg":"trace[1176687830] transaction","detail":"{read_only:false; response_revision:25739; number_of_response:1; }","duration":"328.385556ms","start":"2026-10-14T16:35:04.157577Z","end":"2026-10-14T16:35:04.485962Z","steps":["trace[1176687830] 'process raft request' (duration: 327.916204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:04.48612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:35:04.157564Z","time spent":"328.485798ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:35:04.486309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.62148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-10-14T16:35:04.486354Z","caller":"traceutil/trace.go:171","msg":"trace[1182107787] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25739; }","duration":"181.728242ms","start":"2026-10-14T16:35:04.304613Z","end":"2026-10-14T16:35:04.486342Z","steps":["trace[1182107787] 'agreement among raft nodes before linearized reading' (duration: 181.473076ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:04.728894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.415549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:35:04.729057Z","caller":"traceutil/trace.go:171","msg":"trace[80278514] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"228.584209ms","start":"2026-10-14T16:35:04.500454Z","end":"2026-10-14T16:35:04.729038Z","steps":["trace[80278514] 'process raft request' (duration: 228.529387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:04.72946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.590369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:35:04.729307Z","caller":"traceutil/trace.go:171","msg":"trace[117401187] linearizableReadLoop","detail":"{readStateIndex:26584; appliedIndex:26583; }","duration":"236.396885ms","start":"2026-10-14T16:35:04.492837Z","end":"2026-10-14T16:35:04.729233Z","steps":["trace[117401187] 'read index received' (duration: 20.577536ms)","trace[117401187] 'applied index is now lower than readState.Index' (duration: 215.815988ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:35:04.729559Z","caller":"traceutil/trace.go:171","msg":"trace[2084747749] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25741; }","duration":"236.725413ms","start":"2026-10-14T16:35:04.492812Z","end":"2026-10-14T16:35:04.729538Z","steps":["trace[2084747749] 'agreement among raft nodes before linearized reading' (duration: 236.541259ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:35:04.729473Z","caller":"traceutil/trace.go:171","msg":"trace[723159074] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"238.035437ms","start":"2026-10-14T16:35:04.491404Z","end":"2026-10-14T16:35:04.729439Z","steps":["trace[723159074] 'process raft request' (duration: 21.995552ms)","trace[723159074] 'compare' (duration: 215.168804ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:35:04.729943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.635806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"warn","ts":"2026-10-14T16:35:04.729957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.942833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-14T16:35:04.730005Z","caller":"traceutil/trace.go:171","msg":"trace[1983492772] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25741; }","duration":"235.723669ms","start":"2026-10-14T16:35:04.494267Z","end":"2026-10-14T16:35:04.72999Z","steps":["trace[1983492772] 'agreement among raft nodes before linearized reading' (duration: 235.483383ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:35:04.730024Z","caller":"traceutil/trace.go:171","msg":"trace[321905436] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25741; }","duration":"134.060175ms","start":"2026-10-14T16:35:04.595944Z","end":"2026-10-14T16:35:04.730004Z","steps":["trace[321905436] 'agreement among raft nodes before linearized reading' (duration: 133.861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:38:33.953316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.917298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:38:33.953567Z","caller":"traceutil/trace.go:171","msg":"trace[693485774] transaction","detail":"{read_only:false; response_revision:27796; number_of_response:1; }","duration":"121.685925ms","start":"2026-10-14T16:38:33.831863Z","end":"2026-10-14T16:38:33.953549Z","steps":["trace[693485774] 'process raft request' (duration: 121.572602ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:38:33.953833Z","caller":"traceutil/trace.go:171","msg":"trace[1878787553] transaction","detail":"{read_only:false; response_revision:27795; number_of_response:1; }","duration":"198.317759ms","start":"2026-10-14T16:38:33.755503Z","end":"2026-10-14T16:38:33.95382Z","steps":["trace[1878787553] 'process raft request' (duration: 64.798247ms)","trace[1878787553] 'compare' (duration: 132.640921ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:38:33.95421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.824731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-14T16:38:33.954288Z","caller":"traceutil/trace.go:171","msg":"trace[1178431973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27796; }","duration":"139.956734ms","start":"2026-10-14T16:38:33.814315Z","end":"2026-10-14T16:38:33.954272Z","steps":["trace[1178431973] 'agreement among raft nodes before linearized reading' (duration: 139.692507ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:38:33.953972Z","caller":"traceutil/trace.go:171","msg":"trace[1879680774] linearizableReadLoop","detail":"{readStateIndex:28688; appliedIndex:28687; }","duration":"139.506633ms","start":"2026-10-14T16:38:33.814361Z","end":"2026-10-14T16:38:33.953868Z","steps":["trace[1879680774] 'read index received' (duration: 5.892998ms)","trace[1879680774] 'applied index is now lower than readState.Index' (duration: 133.610515ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:38:34.099435Z","caller":"traceutil/trace.go:171","msg":"trace[346513811] transaction","detail":"{read_only:false; response_revision:27798; number_of_response:1; }","duration":"141.303628ms","start":"2026-10-14T16:38:33.958104Z","end":"2026-10-14T16:38:34.099408Z","steps":["trace[346513811] 'process raft request' (duration: 141.254627ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:38:34.099607Z","caller":"traceutil/trace.go:171","msg":"trace[1996406714] linearizableReadLoop","detail":"{readStateIndex:28690; appliedIndex:28689; }","duration":"144.014235ms","start":"2026-10-14T16:38:33.955558Z","end":"2026-10-14T16:38:34.099573Z","steps":["trace[1996406714] 'read index received' (duration: 118.662208ms)","trace[1996406714] 'applied index is now lower than readState.Index' (duration: 25.347787ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:38:34.099584Z","caller":"traceutil/trace.go:171","msg":"trace[400805203] transaction","detail":"{read_only:false; response_revision:27797; number_of_response:1; }","duration":"248.287714ms","start":"2026-10-14T16:38:33.851246Z","end":"2026-10-14T16:38:34.099534Z","steps":["trace[400805203] 'process raft request' (duration: 222.960528ms)","trace[400805203] 'compare' (duration: 25.014098ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:38:34.099869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.282572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-10-14T16:38:34.099963Z","caller":"traceutil/trace.go:171","msg":"trace[1234221602] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:27798; }","duration":"144.406196ms","start":"2026-10-14T16:38:33.955535Z","end":"2026-10-14T16:38:34.099941Z","steps":["trace[1234221602] 'agreement among raft nodes before linearized reading' (duration: 144.221471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:38:34.100052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.922986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-14T16:38:34.100124Z","caller":"traceutil/trace.go:171","msg":"trace[147995260] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27798; }","duration":"117.07168ms","start":"2026-10-14T16:38:33.983033Z","end":"2026-10-14T16:38:34.100104Z","steps":["trace[147995260] 'agreement among raft nodes before linearized reading' (duration: 116.754242ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:39:30.348277Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25458} {"level":"info","ts":"2026-10-14T16:39:30.531617Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25458,"took":"174.121032ms","hash":2082954298,"current-db-size-bytes":58925056,"current-db-size":"59 MB","current-db-size-in-use-bytes":36061184,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-14T16:39:30.531697Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2082954298,"revision":25458,"compact-revision":21990} {"level":"info","ts":"2026-10-14T16:41:05.031116Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"777352cb93f4c05d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T16:41:05.038245Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-14T16:41:05.038367Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-14T16:44:30.355661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28329} {"level":"info","ts":"2026-10-14T16:44:30.519337Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28329,"took":"157.48853ms","hash":1381928160,"current-db-size-bytes":58925056,"current-db-size":"59 MB","current-db-size-in-use-bytes":31395840,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T16:44:30.519424Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1381928160,"revision":28329,"compact-revision":25458} {"level":"info","ts":"2026-10-14T16:49:30.363361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30568} {"level":"info","ts":"2026-10-14T16:49:30.50308Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30568,"took":"135.626106ms","hash":1990837963,"current-db-size-bytes":58925056,"current-db-size":"59 MB","current-db-size-in-use-bytes":31252480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T16:49:30.503182Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1990837963,"revision":30568,"compact-revision":28329} {"level":"info","ts":"2026-10-14T16:54:30.370464Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32820} {"level":"info","ts":"2026-10-14T16:54:30.528006Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32820,"took":"153.33097ms","hash":24375031,"current-db-size-bytes":58925056,"current-db-size":"59 MB","current-db-size-in-use-bytes":30531584,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T16:54:30.528166Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":24375031,"revision":32820,"compact-revision":30568} {"level":"info","ts":"2026-10-14T16:58:54.478799Z","caller":"traceutil/trace.go:171","msg":"trace[705606014] linearizableReadLoop","detail":"{readStateIndex:38239; appliedIndex:38238; }","duration":"147.909304ms","start":"2026-10-14T16:58:54.330838Z","end":"2026-10-14T16:58:54.478747Z","steps":["trace[705606014] 'read index received' (duration: 141.011701ms)","trace[705606014] 'applied index is now lower than readState.Index' (duration: 6.896233ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:58:54.479179Z","caller":"traceutil/trace.go:171","msg":"trace[1237069176] transaction","detail":"{read_only:false; response_revision:37077; number_of_response:1; }","duration":"162.660344ms","start":"2026-10-14T16:58:54.316499Z","end":"2026-10-14T16:58:54.479159Z","steps":["trace[1237069176] 'process raft request' (duration: 155.440972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:58:54.479294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.426207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-14T16:58:54.479359Z","caller":"traceutil/trace.go:171","msg":"trace[1393964373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:37077; }","duration":"148.53243ms","start":"2026-10-14T16:58:54.330808Z","end":"2026-10-14T16:58:54.479341Z","steps":["trace[1393964373] 'agreement among raft nodes before linearized reading' (duration: 148.304914ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:59:30.377844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35102} {"level":"info","ts":"2026-10-14T16:59:30.51846Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35102,"took":"136.415936ms","hash":124762031,"current-db-size-bytes":60194816,"current-db-size":"60 MB","current-db-size-in-use-bytes":32763904,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T16:59:30.518522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":124762031,"revision":35102,"compact-revision":32820} {"level":"info","ts":"2026-10-14T17:02:29.161025Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"777352cb93f4c05d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T17:02:29.167466Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-14T17:02:29.167593Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-14T17:04:30.388314Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37340} {"level":"info","ts":"2026-10-14T17:04:30.52033Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37340,"took":"127.86769ms","hash":1387189788,"current-db-size-bytes":62324736,"current-db-size":"62 MB","current-db-size-in-use-bytes":32735232,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T17:04:30.520392Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1387189788,"revision":37340,"compact-revision":35102}