{"level":"warn","ts":"2026-09-07T10:39:56.060058Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T10:39:56.060237Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.176: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.176:2380","--initial-cluster=instance=https://162.253.55.176:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.176:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.176: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-09-07T10:39:56.060337Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T10:39:56.060346Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.176:2380"]} {"level":"info","ts":"2026-09-07T10:39:56.060377Z","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-09-07T10:39:56.06119Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.176:2379"]} {"level":"info","ts":"2026-09-07T10:39:56.061303Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.176:2380"],"listen-peer-urls":["https://162.253.55.176:2380"],"advertise-client-urls":["https://162.253.55.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.176: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-09-07T10:39:56.067178Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.577744ms"} {"level":"info","ts":"2026-09-07T10:39:56.076795Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"590b810b00f00655","cluster-id":"dcc6923d0deac545"} {"level":"info","ts":"2026-09-07T10:39:56.076955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T10:39:56.077012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became follower at term 0"} {"level":"info","ts":"2026-09-07T10:39:56.077037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 590b810b00f00655 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T10:39:56.077052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became follower at term 1"} {"level":"info","ts":"2026-09-07T10:39:56.077117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 switched to configuration voters=(6416363978379757141)"} {"level":"warn","ts":"2026-09-07T10:39:56.085146Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T10:39:56.086838Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T10:39:56.088255Z","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-09-07T10:39:56.093913Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"590b810b00f00655","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T10:39:56.100637Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"590b810b00f00655","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T10:39:56.100877Z","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-09-07T10:39:56.101117Z","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-09-07T10:39:56.101139Z","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-09-07T10:39:56.101775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 switched to configuration voters=(6416363978379757141)"} {"level":"info","ts":"2026-09-07T10:39:56.103502Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dcc6923d0deac545","local-member-id":"590b810b00f00655","added-peer-id":"590b810b00f00655","added-peer-peer-urls":["https://162.253.55.176:2380"]} {"level":"info","ts":"2026-09-07T10:39:56.104189Z","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-09-07T10:39:56.104491Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"590b810b00f00655","initial-advertise-peer-urls":["https://162.253.55.176:2380"],"listen-peer-urls":["https://162.253.55.176:2380"],"advertise-client-urls":["https://162.253.55.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T10:39:56.104538Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T10:39:56.104667Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.176:2380"} {"level":"info","ts":"2026-09-07T10:39:56.104688Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.176:2380"} {"level":"info","ts":"2026-09-07T10:39:56.479006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T10:39:56.479068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T10:39:56.479113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 received MsgPreVoteResp from 590b810b00f00655 at term 1"} {"level":"info","ts":"2026-09-07T10:39:56.479268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became candidate at term 2"} {"level":"info","ts":"2026-09-07T10:39:56.47928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 received MsgVoteResp from 590b810b00f00655 at term 2"} {"level":"info","ts":"2026-09-07T10:39:56.479373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became leader at term 2"} {"level":"info","ts":"2026-09-07T10:39:56.479387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 590b810b00f00655 elected leader 590b810b00f00655 at term 2"} {"level":"info","ts":"2026-09-07T10:39:56.490482Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:39:56.490955Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T10:39:56.491368Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T10:39:56.490944Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"590b810b00f00655","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.176:2379]}","request-path":"/0/members/590b810b00f00655/attributes","cluster-id":"dcc6923d0deac545","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T10:39:56.492034Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T10:39:56.492076Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T10:39:56.495076Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dcc6923d0deac545","local-member-id":"590b810b00f00655","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:39:56.495174Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:39:56.495211Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:39:56.496238Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.176:2379"} {"level":"info","ts":"2026-09-07T10:39:56.497157Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-07T10:40:01.179699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.509848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:40:01.179818Z","caller":"traceutil/trace.go:171","msg":"trace[553499967] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"512.366459ms","start":"2026-09-07T10:40:00.667436Z","end":"2026-09-07T10:40:01.179802Z","steps":["trace[553499967] 'process raft request' (duration: 159.323177ms)","trace[553499967] 'compare' (duration: 352.393147ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:40:01.179897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:00.667416Z","time spent":"512.441421ms","remote":"127.0.0.1:55156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T10:40:01.180973Z","caller":"traceutil/trace.go:171","msg":"trace[388601915] linearizableReadLoop","detail":"{readStateIndex:245; appliedIndex:243; }","duration":"369.429318ms","start":"2026-09-07T10:40:00.811524Z","end":"2026-09-07T10:40:01.180953Z","steps":["trace[388601915] 'read index received' (duration: 15.241647ms)","trace[388601915] 'applied index is now lower than readState.Index' (duration: 354.186531ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:40:01.181136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.591909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T10:40:01.181152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.57719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:40:01.181207Z","caller":"traceutil/trace.go:171","msg":"trace[1359037412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:239; }","duration":"286.729371ms","start":"2026-09-07T10:40:00.894463Z","end":"2026-09-07T10:40:01.181192Z","steps":["trace[1359037412] 'agreement among raft nodes before linearized reading' (duration: 286.63933ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:01.18117Z","caller":"traceutil/trace.go:171","msg":"trace[674555411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:239; }","duration":"369.66764ms","start":"2026-09-07T10:40:00.811493Z","end":"2026-09-07T10:40:01.181161Z","steps":["trace[674555411] 'agreement among raft nodes before linearized reading' (duration: 369.560879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:01.181306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:00.811476Z","time spent":"369.820272ms","remote":"127.0.0.1:55358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-07T10:40:01.181053Z","caller":"traceutil/trace.go:171","msg":"trace[436795973] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"513.479049ms","start":"2026-09-07T10:40:00.667552Z","end":"2026-09-07T10:40:01.181031Z","steps":["trace[436795973] 'process raft request' (duration: 513.271937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:01.181984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:00.667527Z","time spent":"514.429876ms","remote":"127.0.0.1:55178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T10:40:01.421781Z","caller":"traceutil/trace.go:171","msg":"trace[899335716] linearizableReadLoop","detail":"{readStateIndex:247; appliedIndex:245; }","duration":"235.692464ms","start":"2026-09-07T10:40:01.186054Z","end":"2026-09-07T10:40:01.421747Z","steps":["trace[899335716] 'read index received' (duration: 176.338581ms)","trace[899335716] 'applied index is now lower than readState.Index' (duration: 59.352713ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:40:01.421854Z","caller":"traceutil/trace.go:171","msg":"trace[1012526473] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"234.780027ms","start":"2026-09-07T10:40:01.187055Z","end":"2026-09-07T10:40:01.421835Z","steps":["trace[1012526473] 'process raft request' (duration: 234.657786ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:01.421912Z","caller":"traceutil/trace.go:171","msg":"trace[1912044424] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"236.275119ms","start":"2026-09-07T10:40:01.185542Z","end":"2026-09-07T10:40:01.421817Z","steps":["trace[1912044424] 'process raft request' (duration: 236.109577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:01.422018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.921836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-tsyfb1\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-09-07T10:40:01.42208Z","caller":"traceutil/trace.go:171","msg":"trace[1369672634] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-tsyfb1; range_end:; response_count:1; response_revision:242; }","duration":"236.043846ms","start":"2026-09-07T10:40:01.186019Z","end":"2026-09-07T10:40:01.422063Z","steps":["trace[1369672634] 'agreement among raft nodes before linearized reading' (duration: 235.841435ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:01.422133Z","caller":"traceutil/trace.go:171","msg":"trace[1029434947] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"237.942771ms","start":"2026-09-07T10:40:01.183795Z","end":"2026-09-07T10:40:01.421738Z","steps":["trace[1029434947] 'process raft request' (duration: 178.556777ms)","trace[1029434947] 'compare' (duration: 59.103562ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:40:32.568557Z","caller":"traceutil/trace.go:171","msg":"trace[1332909012] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"150.290327ms","start":"2026-09-07T10:40:32.418235Z","end":"2026-09-07T10:40:32.568525Z","steps":["trace[1332909012] 'process raft request' (duration: 150.112206ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:35.133009Z","caller":"traceutil/trace.go:171","msg":"trace[839950319] transaction","detail":"{read_only:false; number_of_response:1; response_revision:543; }","duration":"123.761613ms","start":"2026-09-07T10:40:35.009224Z","end":"2026-09-07T10:40:35.132986Z","steps":["trace[839950319] 'process raft request' (duration: 123.658377ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:35.140681Z","caller":"traceutil/trace.go:171","msg":"trace[1987218862] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"130.668207ms","start":"2026-09-07T10:40:35.009988Z","end":"2026-09-07T10:40:35.140656Z","steps":["trace[1987218862] 'process raft request' (duration: 130.489697ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:35.140754Z","caller":"traceutil/trace.go:171","msg":"trace[1998817627] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"130.682378ms","start":"2026-09-07T10:40:35.010035Z","end":"2026-09-07T10:40:35.140718Z","steps":["trace[1998817627] 'process raft request' (duration: 130.558441ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:35.141102Z","caller":"traceutil/trace.go:171","msg":"trace[98972143] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"125.582382ms","start":"2026-09-07T10:40:35.015253Z","end":"2026-09-07T10:40:35.140835Z","steps":["trace[98972143] 'process raft request' (duration: 125.391431ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:35.141111Z","caller":"traceutil/trace.go:171","msg":"trace[2092219395] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"125.777864ms","start":"2026-09-07T10:40:35.015316Z","end":"2026-09-07T10:40:35.141094Z","steps":["trace[2092219395] 'process raft request' (duration: 125.410252ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:35.143673Z","caller":"traceutil/trace.go:171","msg":"trace[524488269] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"124.356819ms","start":"2026-09-07T10:40:35.019273Z","end":"2026-09-07T10:40:35.143629Z","steps":["trace[524488269] 'process raft request' (duration: 123.064652ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:35.144151Z","caller":"traceutil/trace.go:171","msg":"trace[1649160435] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"128.126275ms","start":"2026-09-07T10:40:35.015888Z","end":"2026-09-07T10:40:35.144014Z","steps":["trace[1649160435] 'process raft request' (duration: 124.987357ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:35.144451Z","caller":"traceutil/trace.go:171","msg":"trace[632422845] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"120.613685ms","start":"2026-09-07T10:40:35.023655Z","end":"2026-09-07T10:40:35.144269Z","steps":["trace[632422845] 'process raft request' (duration: 118.762994ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:35.144738Z","caller":"traceutil/trace.go:171","msg":"trace[1524049871] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"115.055652ms","start":"2026-09-07T10:40:35.029589Z","end":"2026-09-07T10:40:35.144645Z","steps":["trace[1524049871] 'process raft request' (duration: 112.871591ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:41.853121Z","caller":"traceutil/trace.go:171","msg":"trace[1366170140] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"274.218093ms","start":"2026-09-07T10:40:41.578878Z","end":"2026-09-07T10:40:41.853096Z","steps":["trace[1366170140] 'process raft request' (duration: 274.057634ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:42.654382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.58974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:40:42.654499Z","caller":"traceutil/trace.go:171","msg":"trace[1968408625] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:648; }","duration":"951.789994ms","start":"2026-09-07T10:40:41.702688Z","end":"2026-09-07T10:40:42.654478Z","steps":["trace[1968408625] 'read index received' (duration: 150.343982ms)","trace[1968408625] 'applied index is now lower than readState.Index' (duration: 801.444382ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:40:42.654559Z","caller":"traceutil/trace.go:171","msg":"trace[430336956] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.055203327s","start":"2026-09-07T10:40:41.599318Z","end":"2026-09-07T10:40:42.654521Z","steps":["trace[430336956] 'process raft request' (duration: 390.149391ms)","trace[430336956] 'compare' (duration: 664.076002ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:40:42.654707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.018087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-07T10:40:42.654711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.609784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:40:42.654749Z","caller":"traceutil/trace.go:171","msg":"trace[1335709631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"952.091091ms","start":"2026-09-07T10:40:41.702645Z","end":"2026-09-07T10:40:42.654736Z","steps":["trace[1335709631] 'agreement among raft nodes before linearized reading' (duration: 951.929873ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:42.654762Z","caller":"traceutil/trace.go:171","msg":"trace[2096703100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"184.686978ms","start":"2026-09-07T10:40:42.470061Z","end":"2026-09-07T10:40:42.654748Z","steps":["trace[2096703100] 'agreement among raft nodes before linearized reading' (duration: 184.609464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:42.654787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:41.702628Z","time spent":"952.149015ms","remote":"127.0.0.1:55358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-07T10:40:42.654852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.051151ms","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-09-07T10:40:42.655026Z","caller":"traceutil/trace.go:171","msg":"trace[2137260791] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"803.315425ms","start":"2026-09-07T10:40:41.851691Z","end":"2026-09-07T10:40:42.655007Z","steps":["trace[2137260791] 'agreement among raft nodes before linearized reading' (duration: 803.013959ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:42.655246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:41.851671Z","time spent":"803.563479ms","remote":"127.0.0.1:55358","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-09-07T10:40:42.655329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.658811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-07T10:40:42.655388Z","caller":"traceutil/trace.go:171","msg":"trace[1339057194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"950.734665ms","start":"2026-09-07T10:40:41.704638Z","end":"2026-09-07T10:40:42.655372Z","steps":["trace[1339057194] 'agreement among raft nodes before linearized reading' (duration: 950.052998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:42.655423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:41.70463Z","time spent":"950.785728ms","remote":"127.0.0.1:55358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-07T10:40:42.655536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:41.599293Z","time spent":"1.055357825s","remote":"127.0.0.1:55178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:40:43.918801Z","caller":"traceutil/trace.go:171","msg":"trace[642138564] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"151.333235ms","start":"2026-09-07T10:40:43.767428Z","end":"2026-09-07T10:40:43.918761Z","steps":["trace[642138564] 'process raft request' (duration: 132.910655ms)","trace[642138564] 'compare' (duration: 18.195307ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:42:08.902444Z","caller":"traceutil/trace.go:171","msg":"trace[346791563] transaction","detail":"{read_only:false; response_revision:1766; number_of_response:1; }","duration":"103.084824ms","start":"2026-09-07T10:42:08.799308Z","end":"2026-09-07T10:42:08.902392Z","steps":["trace[346791563] 'process raft request' (duration: 102.945671ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:09.602806Z","caller":"traceutil/trace.go:171","msg":"trace[531403968] transaction","detail":"{read_only:false; response_revision:1772; number_of_response:1; }","duration":"305.245697ms","start":"2026-09-07T10:42:09.297499Z","end":"2026-09-07T10:42:09.602745Z","steps":["trace[531403968] 'process raft request' (duration: 284.376952ms)","trace[531403968] 'compare' (duration: 20.080057ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:42:09.602881Z","caller":"traceutil/trace.go:171","msg":"trace[1695035023] transaction","detail":"{read_only:false; response_revision:1773; number_of_response:1; }","duration":"304.227113ms","start":"2026-09-07T10:42:09.298642Z","end":"2026-09-07T10:42:09.602869Z","steps":["trace[1695035023] 'process raft request' (duration: 304.137771ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:09.603005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:42:09.298631Z","time spent":"304.281225ms","remote":"127.0.0.1:55358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:42:09.603027Z","caller":"traceutil/trace.go:171","msg":"trace[16903551] linearizableReadLoop","detail":"{readStateIndex:1838; appliedIndex:1836; }","duration":"136.128255ms","start":"2026-09-07T10:42:09.466863Z","end":"2026-09-07T10:42:09.602991Z","steps":["trace[16903551] 'read index received' (duration: 115.025394ms)","trace[16903551] 'applied index is now lower than readState.Index' (duration: 21.100801ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:42:09.60317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.316059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T10:42:09.603117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:42:09.297484Z","time spent":"305.429311ms","remote":"127.0.0.1:55358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:42:09.603204Z","caller":"traceutil/trace.go:171","msg":"trace[1881480730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1774; }","duration":"136.344719ms","start":"2026-09-07T10:42:09.466847Z","end":"2026-09-07T10:42:09.603192Z","steps":["trace[1881480730] 'agreement among raft nodes before linearized reading' (duration: 136.297908ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:17.437148Z","caller":"traceutil/trace.go:171","msg":"trace[1463685425] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1945; }","duration":"290.221836ms","start":"2026-09-07T10:42:17.1469Z","end":"2026-09-07T10:42:17.437122Z","steps":["trace[1463685425] 'read index received' (duration: 289.994571ms)","trace[1463685425] 'applied index is now lower than readState.Index' (duration: 226.445µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:42:17.437445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.416191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-09-07T10:42:17.43746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.547693ms","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-09-07T10:42:17.437504Z","caller":"traceutil/trace.go:171","msg":"trace[1936081316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1879; }","duration":"199.552503ms","start":"2026-09-07T10:42:17.237934Z","end":"2026-09-07T10:42:17.437487Z","steps":["trace[1936081316] 'agreement among raft nodes before linearized reading' (duration: 199.322618ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:17.437541Z","caller":"traceutil/trace.go:171","msg":"trace[489112430] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1879; }","duration":"290.674166ms","start":"2026-09-07T10:42:17.14685Z","end":"2026-09-07T10:42:17.437524Z","steps":["trace[489112430] 'agreement among raft nodes before linearized reading' (duration: 290.39351ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:17.437537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.831305ms","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-09-07T10:42:17.437614Z","caller":"traceutil/trace.go:171","msg":"trace[1474063035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1879; }","duration":"146.971768ms","start":"2026-09-07T10:42:17.290625Z","end":"2026-09-07T10:42:17.437597Z","steps":["trace[1474063035] 'agreement among raft nodes before linearized reading' (duration: 146.709752ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:17.437865Z","caller":"traceutil/trace.go:171","msg":"trace[1505503268] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"348.617268ms","start":"2026-09-07T10:42:17.088566Z","end":"2026-09-07T10:42:17.437184Z","steps":["trace[1505503268] 'process raft request' (duration: 348.421364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:17.438001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:42:17.088541Z","time spent":"349.392406ms","remote":"127.0.0.1:55274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:42:17.613768Z","caller":"traceutil/trace.go:171","msg":"trace[280371512] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1947; }","duration":"144.874303ms","start":"2026-09-07T10:42:17.468847Z","end":"2026-09-07T10:42:17.613721Z","steps":["trace[280371512] 'read index received' (duration: 144.305481ms)","trace[280371512] 'applied index is now lower than readState.Index' (duration: 567.802µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:42:17.613913Z","caller":"traceutil/trace.go:171","msg":"trace[700435936] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"169.536125ms","start":"2026-09-07T10:42:17.44436Z","end":"2026-09-07T10:42:17.613896Z","steps":["trace[700435936] 'process raft request' (duration: 168.812219ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:17.613938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.068727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:42:17.613983Z","caller":"traceutil/trace.go:171","msg":"trace[1792527759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1882; }","duration":"145.16907ms","start":"2026-09-07T10:42:17.4688Z","end":"2026-09-07T10:42:17.613969Z","steps":["trace[1792527759] 'agreement among raft nodes before linearized reading' (duration: 145.075028ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:17.614217Z","caller":"traceutil/trace.go:171","msg":"trace[77020318] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"159.842002ms","start":"2026-09-07T10:42:17.454359Z","end":"2026-09-07T10:42:17.614201Z","steps":["trace[77020318] 'process raft request' (duration: 159.28897ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:17.850579Z","caller":"traceutil/trace.go:171","msg":"trace[316742340] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"128.941413ms","start":"2026-09-07T10:42:17.72159Z","end":"2026-09-07T10:42:17.850532Z","steps":["trace[316742340] 'process raft request' (duration: 86.629763ms)","trace[316742340] 'compare' (duration: 42.175367ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:42:30.124673Z","caller":"traceutil/trace.go:171","msg":"trace[1336044671] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"177.218279ms","start":"2026-09-07T10:42:29.947419Z","end":"2026-09-07T10:42:30.124637Z","steps":["trace[1336044671] 'process raft request' (duration: 176.994725ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:56.914751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.915209ms","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-09-07T10:42:56.91486Z","caller":"traceutil/trace.go:171","msg":"trace[1130584197] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2150; }","duration":"208.079932ms","start":"2026-09-07T10:42:56.706752Z","end":"2026-09-07T10:42:56.914832Z","steps":["trace[1130584197] 'range keys from in-memory index tree' (duration: 207.682395ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:56.915557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.958992ms","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-09-07T10:42:56.915595Z","caller":"traceutil/trace.go:171","msg":"trace[319741858] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2150; }","duration":"174.000563ms","start":"2026-09-07T10:42:56.741584Z","end":"2026-09-07T10:42:56.915585Z","steps":["trace[319741858] 'range keys from in-memory index tree' (duration: 173.141097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:56.915702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.117158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:42:56.915784Z","caller":"traceutil/trace.go:171","msg":"trace[882815272] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2150; }","duration":"218.258941ms","start":"2026-09-07T10:42:56.697509Z","end":"2026-09-07T10:42:56.915767Z","steps":["trace[882815272] 'count revisions from in-memory index tree' (duration: 217.414476ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:57.178699Z","caller":"traceutil/trace.go:171","msg":"trace[1676689351] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"259.995073ms","start":"2026-09-07T10:42:56.918674Z","end":"2026-09-07T10:42:57.178669Z","steps":["trace[1676689351] 'process raft request' (duration: 259.82024ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:57.489143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.893229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-07T10:42:57.489155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.350684ms","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-09-07T10:42:57.489236Z","caller":"traceutil/trace.go:171","msg":"trace[1155185660] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2151; }","duration":"258.060772ms","start":"2026-09-07T10:42:57.231152Z","end":"2026-09-07T10:42:57.489213Z","steps":["trace[1155185660] 'count revisions from in-memory index tree' (duration: 257.783317ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:57.489252Z","caller":"traceutil/trace.go:171","msg":"trace[1255453743] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2151; }","duration":"122.503207ms","start":"2026-09-07T10:42:57.366724Z","end":"2026-09-07T10:42:57.489228Z","steps":["trace[1255453743] 'range keys from in-memory index tree' (duration: 122.037939ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:57.489298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.297033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-09-07T10:42:57.489435Z","caller":"traceutil/trace.go:171","msg":"trace[1829232360] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2151; }","duration":"231.504667ms","start":"2026-09-07T10:42:57.257887Z","end":"2026-09-07T10:42:57.489392Z","steps":["trace[1829232360] 'range keys from in-memory index tree' (duration: 231.079199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:57.892555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.162846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T10:42:57.892663Z","caller":"traceutil/trace.go:171","msg":"trace[1966017881] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2153; }","duration":"269.3391ms","start":"2026-09-07T10:42:57.6233Z","end":"2026-09-07T10:42:57.892639Z","steps":["trace[1966017881] 'count revisions from in-memory index tree' (duration: 269.030405ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:57.893137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.27872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-07T10:42:57.893292Z","caller":"traceutil/trace.go:171","msg":"trace[835733591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2153; }","duration":"204.542254ms","start":"2026-09-07T10:42:57.688715Z","end":"2026-09-07T10:42:57.893257Z","steps":["trace[835733591] 'range keys from in-memory index tree' (duration: 203.617638ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:44:20.606742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.816446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-09-07T10:44:20.606832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.785321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T10:44:20.606895Z","caller":"traceutil/trace.go:171","msg":"trace[1752290084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2705; }","duration":"188.03461ms","start":"2026-09-07T10:44:20.418827Z","end":"2026-09-07T10:44:20.606862Z","steps":["trace[1752290084] 'range keys from in-memory index tree' (duration: 187.485401ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:44:20.60701Z","caller":"traceutil/trace.go:171","msg":"trace[1539945336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2705; }","duration":"150.049894ms","start":"2026-09-07T10:44:20.456925Z","end":"2026-09-07T10:44:20.606975Z","steps":["trace[1539945336] 'range keys from in-memory index tree' (duration: 149.514227ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:44:20.607594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.693597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:44:20.607661Z","caller":"traceutil/trace.go:171","msg":"trace[1931002144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2705; }","duration":"139.62385ms","start":"2026-09-07T10:44:20.468021Z","end":"2026-09-07T10:44:20.607645Z","steps":["trace[1931002144] 'range keys from in-memory index tree' (duration: 138.587316ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:48:24.920021Z","caller":"traceutil/trace.go:171","msg":"trace[1911814737] transaction","detail":"{read_only:false; response_revision:4129; number_of_response:1; }","duration":"129.919367ms","start":"2026-09-07T10:48:24.790083Z","end":"2026-09-07T10:48:24.920002Z","steps":["trace[1911814737] 'process raft request' (duration: 67.730714ms)","trace[1911814737] 'compare' (duration: 62.02435ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:48:24.928728Z","caller":"traceutil/trace.go:171","msg":"trace[1334167517] transaction","detail":"{read_only:false; response_revision:4130; number_of_response:1; }","duration":"134.318203ms","start":"2026-09-07T10:48:24.794381Z","end":"2026-09-07T10:48:24.928699Z","steps":["trace[1334167517] 'process raft request' (duration: 133.953165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:16.423192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.707032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-09-07T10:49:16.423222Z","caller":"traceutil/trace.go:171","msg":"trace[755415015] linearizableReadLoop","detail":"{readStateIndex:5027; appliedIndex:5026; }","duration":"103.597721ms","start":"2026-09-07T10:49:16.319595Z","end":"2026-09-07T10:49:16.423193Z","steps":["trace[755415015] 'read index received' (duration: 66.892161ms)","trace[755415015] 'applied index is now lower than readState.Index' (duration: 36.70466ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:16.423253Z","caller":"traceutil/trace.go:171","msg":"trace[1518642704] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4848; }","duration":"127.828434ms","start":"2026-09-07T10:49:16.295416Z","end":"2026-09-07T10:49:16.423244Z","steps":["trace[1518642704] 'range keys from in-memory index tree' (duration: 127.555099ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:16.423253Z","caller":"traceutil/trace.go:171","msg":"trace[1674044368] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"112.439798ms","start":"2026-09-07T10:49:16.310791Z","end":"2026-09-07T10:49:16.423231Z","steps":["trace[1674044368] 'process raft request' (duration: 75.745758ms)","trace[1674044368] 'compare' (duration: 36.379394ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:49:16.423377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.777606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T10:49:16.42341Z","caller":"traceutil/trace.go:171","msg":"trace[1939158516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4849; }","duration":"103.844207ms","start":"2026-09-07T10:49:16.319556Z","end":"2026-09-07T10:49:16.4234Z","steps":["trace[1939158516] 'agreement among raft nodes before linearized reading' (duration: 103.733854ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:18.48563Z","caller":"traceutil/trace.go:171","msg":"trace[616148648] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"132.475499ms","start":"2026-09-07T10:49:18.35314Z","end":"2026-09-07T10:49:18.485616Z","steps":["trace[616148648] 'process raft request' (duration: 106.393811ms)","trace[616148648] 'compare' (duration: 25.872744ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:49:18.485815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.937279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1223"} {"level":"info","ts":"2026-09-07T10:49:18.485872Z","caller":"traceutil/trace.go:171","msg":"trace[1890180035] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4878; }","duration":"128.030531ms","start":"2026-09-07T10:49:18.357828Z","end":"2026-09-07T10:49:18.485859Z","steps":["trace[1890180035] 'agreement among raft nodes before linearized reading' (duration: 127.858378ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:18.485574Z","caller":"traceutil/trace.go:171","msg":"trace[236656537] linearizableReadLoop","detail":"{readStateIndex:5056; appliedIndex:5055; }","duration":"127.688854ms","start":"2026-09-07T10:49:18.357861Z","end":"2026-09-07T10:49:18.48555Z","steps":["trace[236656537] 'read index received' (duration: 101.685778ms)","trace[236656537] 'applied index is now lower than readState.Index' (duration: 26.002096ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:20.818913Z","caller":"traceutil/trace.go:171","msg":"trace[338547660] linearizableReadLoop","detail":"{readStateIndex:5136; appliedIndex:5135; }","duration":"209.623189ms","start":"2026-09-07T10:49:20.609264Z","end":"2026-09-07T10:49:20.818888Z","steps":["trace[338547660] 'read index received' (duration: 209.332943ms)","trace[338547660] 'applied index is now lower than readState.Index' (duration: 288.896µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:49:20.819055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.780992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-07T10:49:20.819081Z","caller":"traceutil/trace.go:171","msg":"trace[441923473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4953; }","duration":"209.859884ms","start":"2026-09-07T10:49:20.609216Z","end":"2026-09-07T10:49:20.819076Z","steps":["trace[441923473] 'agreement among raft nodes before linearized reading' (duration: 209.729981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:20.819168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.480702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-09-07T10:49:20.819208Z","caller":"traceutil/trace.go:171","msg":"trace[903143734] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4953; }","duration":"130.582994ms","start":"2026-09-07T10:49:20.688613Z","end":"2026-09-07T10:49:20.819196Z","steps":["trace[903143734] 'agreement among raft nodes before linearized reading' (duration: 130.471312ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:20.818914Z","caller":"traceutil/trace.go:171","msg":"trace[137624364] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"223.86764ms","start":"2026-09-07T10:49:20.59503Z","end":"2026-09-07T10:49:20.818898Z","steps":["trace[137624364] 'process raft request' (duration: 223.670126ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:21.042282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.191743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:49:21.042487Z","caller":"traceutil/trace.go:171","msg":"trace[1439300801] linearizableReadLoop","detail":"{readStateIndex:5138; appliedIndex:5136; }","duration":"100.15944ms","start":"2026-09-07T10:49:20.942306Z","end":"2026-09-07T10:49:21.042465Z","steps":["trace[1439300801] 'read index received' (duration: 14.554088ms)","trace[1439300801] 'applied index is now lower than readState.Index' (duration: 85.604282ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:21.042571Z","caller":"traceutil/trace.go:171","msg":"trace[160392003] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"218.163366ms","start":"2026-09-07T10:49:20.824383Z","end":"2026-09-07T10:49:21.042547Z","steps":["trace[160392003] 'process raft request' (duration: 218.004983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:21.042713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.417036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-fgrxk\" ","response":"range_response_count:1 size:5141"} {"level":"info","ts":"2026-09-07T10:49:21.042767Z","caller":"traceutil/trace.go:171","msg":"trace[1436815314] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-fgrxk; range_end:; response_count:1; response_revision:4955; }","duration":"100.474247ms","start":"2026-09-07T10:49:20.942277Z","end":"2026-09-07T10:49:21.042751Z","steps":["trace[1436815314] 'agreement among raft nodes before linearized reading' (duration: 100.257803ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:21.042493Z","caller":"traceutil/trace.go:171","msg":"trace[145861151] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"221.618064ms","start":"2026-09-07T10:49:20.820851Z","end":"2026-09-07T10:49:21.042469Z","steps":["trace[145861151] 'process raft request' (duration: 85.121325ms)","trace[145861151] 'compare' (duration: 136.067841ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:21.730127Z","caller":"traceutil/trace.go:171","msg":"trace[335564551] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"161.821878ms","start":"2026-09-07T10:49:21.568264Z","end":"2026-09-07T10:49:21.730086Z","steps":["trace[335564551] 'process raft request' (duration: 143.804812ms)","trace[335564551] 'compare' (duration: 17.252561ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:49:27.596042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.850456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:49:27.596151Z","caller":"traceutil/trace.go:171","msg":"trace[1252927935] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"277.579251ms","start":"2026-09-07T10:49:27.318553Z","end":"2026-09-07T10:49:27.596132Z","steps":["trace[1252927935] 'process raft request' (duration: 150.570622ms)","trace[1252927935] 'compare' (duration: 126.633932ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:27.741785Z","caller":"traceutil/trace.go:171","msg":"trace[1110764553] linearizableReadLoop","detail":"{readStateIndex:5202; appliedIndex:5200; }","duration":"400.723084ms","start":"2026-09-07T10:49:27.341038Z","end":"2026-09-07T10:49:27.741761Z","steps":["trace[1110764553] 'read index received' (duration: 128.172552ms)","trace[1110764553] 'applied index is now lower than readState.Index' (duration: 272.549412ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:27.741929Z","caller":"traceutil/trace.go:171","msg":"trace[1771523277] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"418.428212ms","start":"2026-09-07T10:49:27.323443Z","end":"2026-09-07T10:49:27.741871Z","steps":["trace[1771523277] 'process raft request' (duration: 414.877362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:27.742127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:49:27.323431Z","time spent":"418.587945ms","remote":"127.0.0.1:55274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T10:49:27.742338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.281745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-07T10:49:27.742432Z","caller":"traceutil/trace.go:171","msg":"trace[94383847] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5017; }","duration":"401.373557ms","start":"2026-09-07T10:49:27.341033Z","end":"2026-09-07T10:49:27.742407Z","steps":["trace[94383847] 'agreement among raft nodes before linearized reading' (duration: 401.084662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:27.742567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:49:27.341019Z","time spent":"401.52623ms","remote":"127.0.0.1:55244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-09-07T10:49:27.742592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.504559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:49:27.742743Z","caller":"traceutil/trace.go:171","msg":"trace[1483553375] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:5017; }","duration":"127.695513ms","start":"2026-09-07T10:49:27.615027Z","end":"2026-09-07T10:49:27.742723Z","steps":["trace[1483553375] 'agreement among raft nodes before linearized reading' (duration: 127.50146ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:27.7428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.445119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T10:49:27.742841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.002134ms","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-09-07T10:49:27.742913Z","caller":"traceutil/trace.go:171","msg":"trace[89048471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5017; }","duration":"275.608392ms","start":"2026-09-07T10:49:27.467277Z","end":"2026-09-07T10:49:27.742885Z","steps":["trace[89048471] 'agreement among raft nodes before linearized reading' (duration: 275.424458ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:27.743Z","caller":"traceutil/trace.go:171","msg":"trace[125695728] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5017; }","duration":"339.197988ms","start":"2026-09-07T10:49:27.403719Z","end":"2026-09-07T10:49:27.742917Z","steps":["trace[125695728] 'agreement among raft nodes before linearized reading' (duration: 338.77342ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:27.743123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:49:27.403696Z","time spent":"339.402453ms","remote":"127.0.0.1:55358","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":"warn","ts":"2026-09-07T10:49:28.100388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.357227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:49:28.100648Z","caller":"traceutil/trace.go:171","msg":"trace[1803614874] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"208.703708ms","start":"2026-09-07T10:49:27.891877Z","end":"2026-09-07T10:49:28.100581Z","steps":["trace[1803614874] 'process raft request' (duration: 89.048035ms)","trace[1803614874] 'compare' (duration: 119.172623ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:28.100867Z","caller":"traceutil/trace.go:171","msg":"trace[913320326] linearizableReadLoop","detail":"{readStateIndex:5215; appliedIndex:5214; }","duration":"204.884683ms","start":"2026-09-07T10:49:27.895962Z","end":"2026-09-07T10:49:28.100846Z","steps":["trace[913320326] 'read index received' (duration: 84.996266ms)","trace[913320326] 'applied index is now lower than readState.Index' (duration: 119.886587ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:49:28.101123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.139738ms","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-09-07T10:49:28.101182Z","caller":"traceutil/trace.go:171","msg":"trace[2114967055] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5031; }","duration":"205.25718ms","start":"2026-09-07T10:49:27.89591Z","end":"2026-09-07T10:49:28.101167Z","steps":["trace[2114967055] 'agreement among raft nodes before linearized reading' (duration: 205.062766ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:28.101256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.136352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-09-07T10:49:28.101327Z","caller":"traceutil/trace.go:171","msg":"trace[1833990197] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5031; }","duration":"197.238173ms","start":"2026-09-07T10:49:27.904073Z","end":"2026-09-07T10:49:28.101311Z","steps":["trace[1833990197] 'agreement among raft nodes before linearized reading' (duration: 197.005229ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:31.614221Z","caller":"traceutil/trace.go:171","msg":"trace[1485857415] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"217.268456ms","start":"2026-09-07T10:49:31.396928Z","end":"2026-09-07T10:49:31.614197Z","steps":["trace[1485857415] 'process raft request' (duration: 169.083777ms)","trace[1485857415] 'compare' (duration: 47.956624ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:31.6193Z","caller":"traceutil/trace.go:171","msg":"trace[27816036] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"212.806949ms","start":"2026-09-07T10:49:31.406462Z","end":"2026-09-07T10:49:31.619269Z","steps":["trace[27816036] 'process raft request' (duration: 212.634086ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:41.989422Z","caller":"traceutil/trace.go:171","msg":"trace[586559519] linearizableReadLoop","detail":"{readStateIndex:5449; appliedIndex:5448; }","duration":"142.001253ms","start":"2026-09-07T10:49:41.847398Z","end":"2026-09-07T10:49:41.9894Z","steps":["trace[586559519] 'read index received' (duration: 141.86403ms)","trace[586559519] 'applied index is now lower than readState.Index' (duration: 136.223µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:41.989552Z","caller":"traceutil/trace.go:171","msg":"trace[1030718660] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"143.748247ms","start":"2026-09-07T10:49:41.845754Z","end":"2026-09-07T10:49:41.989502Z","steps":["trace[1030718660] 'process raft request' (duration: 143.508442ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:41.989778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.35831ms","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-09-07T10:49:41.989822Z","caller":"traceutil/trace.go:171","msg":"trace[1181377762] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5257; }","duration":"142.444492ms","start":"2026-09-07T10:49:41.847366Z","end":"2026-09-07T10:49:41.989811Z","steps":["trace[1181377762] 'agreement among raft nodes before linearized reading' (duration: 142.121366ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:42.228625Z","caller":"traceutil/trace.go:171","msg":"trace[862857208] linearizableReadLoop","detail":"{readStateIndex:5450; appliedIndex:5449; }","duration":"236.747992ms","start":"2026-09-07T10:49:41.991848Z","end":"2026-09-07T10:49:42.228596Z","steps":["trace[862857208] 'read index received' (duration: 179.841398ms)","trace[862857208] 'applied index is now lower than readState.Index' (duration: 56.903824ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:42.22865Z","caller":"traceutil/trace.go:171","msg":"trace[1552438042] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"358.843912ms","start":"2026-09-07T10:49:41.869784Z","end":"2026-09-07T10:49:42.228628Z","steps":["trace[1552438042] 'process raft request' (duration: 301.851566ms)","trace[1552438042] 'compare' (duration: 56.294802ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:49:42.230186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:49:41.869749Z","time spent":"360.33635ms","remote":"127.0.0.1:55358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T10:49:42.228869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.007497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:11126"} {"level":"info","ts":"2026-09-07T10:49:42.230553Z","caller":"traceutil/trace.go:171","msg":"trace[497392060] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5259; }","duration":"238.654269ms","start":"2026-09-07T10:49:41.991817Z","end":"2026-09-07T10:49:42.230472Z","steps":["trace[497392060] 'agreement among raft nodes before linearized reading' (duration: 236.930966ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:42.64063Z","caller":"traceutil/trace.go:171","msg":"trace[43492944] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"125.410571ms","start":"2026-09-07T10:49:42.515199Z","end":"2026-09-07T10:49:42.640609Z","steps":["trace[43492944] 'process raft request' (duration: 125.267338ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:42.660802Z","caller":"traceutil/trace.go:171","msg":"trace[2146828262] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"143.276534ms","start":"2026-09-07T10:49:42.517498Z","end":"2026-09-07T10:49:42.660774Z","steps":["trace[2146828262] 'process raft request' (duration: 143.07204ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:42.660836Z","caller":"traceutil/trace.go:171","msg":"trace[1154603114] linearizableReadLoop","detail":"{readStateIndex:5460; appliedIndex:5455; }","duration":"108.431954ms","start":"2026-09-07T10:49:42.552383Z","end":"2026-09-07T10:49:42.660815Z","steps":["trace[1154603114] 'read index received' (duration: 88.201355ms)","trace[1154603114] 'applied index is now lower than readState.Index' (duration: 20.229789ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:42.661138Z","caller":"traceutil/trace.go:171","msg":"trace[1469983222] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"143.335485ms","start":"2026-09-07T10:49:42.517783Z","end":"2026-09-07T10:49:42.661118Z","steps":["trace[1469983222] 'process raft request' (duration: 142.896417ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:42.661178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.775042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-07T10:49:42.661217Z","caller":"traceutil/trace.go:171","msg":"trace[2094685826] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5268; }","duration":"108.857213ms","start":"2026-09-07T10:49:42.552346Z","end":"2026-09-07T10:49:42.661203Z","steps":["trace[2094685826] 'agreement among raft nodes before linearized reading' (duration: 108.542377ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:42.661256Z","caller":"traceutil/trace.go:171","msg":"trace[567724535] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"143.281314ms","start":"2026-09-07T10:49:42.517949Z","end":"2026-09-07T10:49:42.66123Z","steps":["trace[567724535] 'process raft request' (duration: 142.828105ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:42.661731Z","caller":"traceutil/trace.go:171","msg":"trace[563852705] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"143.874235ms","start":"2026-09-07T10:49:42.517835Z","end":"2026-09-07T10:49:42.661709Z","steps":["trace[563852705] 'process raft request' (duration: 142.890556ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:56.539149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2896} {"level":"info","ts":"2026-09-07T10:49:56.625542Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2896,"took":"85.434366ms","hash":2844440991,"current-db-size-bytes":52883456,"current-db-size":"53 MB","current-db-size-in-use-bytes":28119040,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T10:49:56.625613Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2844440991,"revision":2896,"compact-revision":-1} {"level":"info","ts":"2026-09-07T10:50:31.964969Z","caller":"traceutil/trace.go:171","msg":"trace[1931973382] transaction","detail":"{read_only:false; response_revision:5685; number_of_response:1; }","duration":"153.405262ms","start":"2026-09-07T10:50:31.811502Z","end":"2026-09-07T10:50:31.964907Z","steps":["trace[1931973382] 'process raft request' (duration: 153.201498ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:53:39.443603Z","caller":"traceutil/trace.go:171","msg":"trace[1345143809] transaction","detail":"{read_only:false; response_revision:6997; number_of_response:1; }","duration":"152.396799ms","start":"2026-09-07T10:53:39.291182Z","end":"2026-09-07T10:53:39.443579Z","steps":["trace[1345143809] 'process raft request' (duration: 152.228803ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:54:56.547477Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5376} {"level":"info","ts":"2026-09-07T10:54:56.624843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5376,"took":"76.307833ms","hash":493890058,"current-db-size-bytes":52883456,"current-db-size":"53 MB","current-db-size-in-use-bytes":18771968,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-07T10:54:56.624893Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":493890058,"revision":5376,"compact-revision":2896}