{"level":"warn","ts":"2026-09-29T15:34:25.078225Z","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-29T15:34:25.078336Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.189: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.189:2380","--initial-cluster=instance=https://162.253.55.189:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.189:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.189: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-29T15:34:25.078404Z","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-29T15:34:25.078427Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.189:2380"]} {"level":"info","ts":"2026-09-29T15:34:25.078445Z","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-29T15:34:25.079064Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.189:2379"]} {"level":"info","ts":"2026-09-29T15:34:25.079138Z","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.189:2380"],"listen-peer-urls":["https://162.253.55.189:2380"],"advertise-client-urls":["https://162.253.55.189:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.189:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.189: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-29T15:34:25.082804Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.460602ms"} {"level":"info","ts":"2026-09-29T15:34:25.090216Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f14c7d724df9ef94","cluster-id":"f7bde5cbb4d65447"} {"level":"info","ts":"2026-09-29T15:34:25.090275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 switched to configuration voters=()"} {"level":"info","ts":"2026-09-29T15:34:25.090293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became follower at term 0"} {"level":"info","ts":"2026-09-29T15:34:25.090301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f14c7d724df9ef94 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-29T15:34:25.090309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became follower at term 1"} {"level":"info","ts":"2026-09-29T15:34:25.090337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 switched to configuration voters=(17387410191258611604)"} {"level":"warn","ts":"2026-09-29T15:34:25.0958Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-29T15:34:25.096979Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-29T15:34:25.09813Z","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-29T15:34:25.099754Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f14c7d724df9ef94","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-29T15:34:25.10159Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f14c7d724df9ef94","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-29T15:34:25.099973Z","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-29T15:34:25.10184Z","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-29T15:34:25.101865Z","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-29T15:34:25.103251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 switched to configuration voters=(17387410191258611604)"} {"level":"info","ts":"2026-09-29T15:34:25.103402Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f7bde5cbb4d65447","local-member-id":"f14c7d724df9ef94","added-peer-id":"f14c7d724df9ef94","added-peer-peer-urls":["https://162.253.55.189:2380"]} {"level":"info","ts":"2026-09-29T15:34:25.104819Z","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-29T15:34:25.105377Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.189:2380"} {"level":"info","ts":"2026-09-29T15:34:25.105433Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.189:2380"} {"level":"info","ts":"2026-09-29T15:34:25.105571Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f14c7d724df9ef94","initial-advertise-peer-urls":["https://162.253.55.189:2380"],"listen-peer-urls":["https://162.253.55.189:2380"],"advertise-client-urls":["https://162.253.55.189:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.189:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-29T15:34:25.105595Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-29T15:34:25.89056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 is starting a new election at term 1"} {"level":"info","ts":"2026-09-29T15:34:25.890647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-29T15:34:25.890679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 received MsgPreVoteResp from f14c7d724df9ef94 at term 1"} {"level":"info","ts":"2026-09-29T15:34:25.890702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became candidate at term 2"} {"level":"info","ts":"2026-09-29T15:34:25.890713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 received MsgVoteResp from f14c7d724df9ef94 at term 2"} {"level":"info","ts":"2026-09-29T15:34:25.890727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became leader at term 2"} {"level":"info","ts":"2026-09-29T15:34:25.890739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f14c7d724df9ef94 elected leader f14c7d724df9ef94 at term 2"} {"level":"info","ts":"2026-09-29T15:34:25.891769Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T15:34:25.892716Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f14c7d724df9ef94","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.189:2379]}","request-path":"/0/members/f14c7d724df9ef94/attributes","cluster-id":"f7bde5cbb4d65447","publish-timeout":"7s"} {"level":"info","ts":"2026-09-29T15:34:25.892734Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-29T15:34:25.892763Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-29T15:34:25.893622Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f7bde5cbb4d65447","local-member-id":"f14c7d724df9ef94","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T15:34:25.893585Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-29T15:34:25.89374Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-29T15:34:25.893798Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T15:34:25.893853Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T15:34:25.89472Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.189:2379"} {"level":"info","ts":"2026-09-29T15:34:25.896233Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-29T15:34:29.751747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.957796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-29T15:34:29.751858Z","caller":"traceutil/trace.go:171","msg":"trace[384189000] linearizableReadLoop","detail":"{readStateIndex:187; appliedIndex:186; }","duration":"203.410057ms","start":"2026-09-29T15:34:29.548431Z","end":"2026-09-29T15:34:29.751841Z","steps":["trace[384189000] 'read index received' (duration: 21.832784ms)","trace[384189000] 'applied index is now lower than readState.Index' (duration: 181.576092ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:34:29.751935Z","caller":"traceutil/trace.go:171","msg":"trace[520042634] transaction","detail":"{read_only:false; response_revision:183; number_of_response:1; }","duration":"213.086695ms","start":"2026-09-29T15:34:29.538839Z","end":"2026-09-29T15:34:29.751926Z","steps":["trace[520042634] 'process raft request' (duration: 31.471661ms)","trace[520042634] 'compare' (duration: 180.801482ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:34:29.752051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.590231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T15:34:29.752172Z","caller":"traceutil/trace.go:171","msg":"trace[714605311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:183; }","duration":"203.741056ms","start":"2026-09-29T15:34:29.548407Z","end":"2026-09-29T15:34:29.752148Z","steps":["trace[714605311] 'agreement among raft nodes before linearized reading' (duration: 203.52781ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:34:30.151878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.936354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-29T15:34:30.152451Z","caller":"traceutil/trace.go:171","msg":"trace[852861835] linearizableReadLoop","detail":"{readStateIndex:188; appliedIndex:187; }","duration":"400.528094ms","start":"2026-09-29T15:34:29.751895Z","end":"2026-09-29T15:34:30.152423Z","steps":["trace[852861835] 'read index received' (duration: 119.910319ms)","trace[852861835] 'applied index is now lower than readState.Index' (duration: 280.421089ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:34:30.152546Z","caller":"traceutil/trace.go:171","msg":"trace[170862436] transaction","detail":"{read_only:false; response_revision:184; number_of_response:1; }","duration":"521.910678ms","start":"2026-09-29T15:34:29.630333Z","end":"2026-09-29T15:34:30.152244Z","steps":["trace[170862436] 'process raft request' (duration: 241.53712ms)","trace[170862436] 'compare' (duration: 279.788809ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:34:30.152791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:34:29.630319Z","time spent":"522.349062ms","remote":"127.0.0.1:48308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-29T15:34:30.155404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.541846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/system:controller:bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T15:34:30.155482Z","caller":"traceutil/trace.go:171","msg":"trace[682403129] range","detail":"{range_begin:/registry/roles/kube-public/system:controller:bootstrap-signer; range_end:; response_count:0; response_revision:184; }","duration":"401.640309ms","start":"2026-09-29T15:34:29.753799Z","end":"2026-09-29T15:34:30.15544Z","steps":["trace[682403129] 'agreement among raft nodes before linearized reading' (duration: 399.498701ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:34:30.155521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:34:29.753792Z","time spent":"401.719422ms","remote":"127.0.0.1:48472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/roles/kube-public/system:controller:bootstrap-signer\" "} {"level":"warn","ts":"2026-09-29T15:34:30.155589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.575925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T15:34:30.155687Z","caller":"traceutil/trace.go:171","msg":"trace[974429558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:184; }","duration":"402.681769ms","start":"2026-09-29T15:34:29.752966Z","end":"2026-09-29T15:34:30.155647Z","steps":["trace[974429558] 'agreement among raft nodes before linearized reading' (duration: 399.846769ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:34:30.155744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:34:29.752953Z","time spent":"402.778801ms","remote":"127.0.0.1:48116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-29T15:34:30.157944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.313777ms","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-29T15:34:30.158009Z","caller":"traceutil/trace.go:171","msg":"trace[949893532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:184; }","duration":"452.39951ms","start":"2026-09-29T15:34:29.705592Z","end":"2026-09-29T15:34:30.157992Z","steps":["trace[949893532] 'agreement among raft nodes before linearized reading' (duration: 446.738889ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:34:30.158045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:34:29.705582Z","time spent":"452.454581ms","remote":"127.0.0.1:48388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-29T15:34:30.360487Z","caller":"traceutil/trace.go:171","msg":"trace[988523941] transaction","detail":"{read_only:false; response_revision:186; number_of_response:1; }","duration":"197.291882ms","start":"2026-09-29T15:34:30.163169Z","end":"2026-09-29T15:34:30.360461Z","steps":["trace[988523941] 'process raft request' (duration: 197.24551ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:34:30.360517Z","caller":"traceutil/trace.go:171","msg":"trace[1351753036] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"198.082767ms","start":"2026-09-29T15:34:30.162406Z","end":"2026-09-29T15:34:30.360489Z","steps":["trace[1351753036] 'process raft request' (duration: 178.886436ms)","trace[1351753036] 'compare' (duration: 18.964573ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:35:07.690137Z","caller":"traceutil/trace.go:171","msg":"trace[980120235] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"125.602427ms","start":"2026-09-29T15:35:07.564509Z","end":"2026-09-29T15:35:07.690111Z","steps":["trace[980120235] 'process raft request' (duration: 125.427411ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:35:14.293861Z","caller":"traceutil/trace.go:171","msg":"trace[1518543029] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"108.329555ms","start":"2026-09-29T15:35:14.185506Z","end":"2026-09-29T15:35:14.293836Z","steps":["trace[1518543029] 'process raft request' (duration: 107.81825ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:35:14.555603Z","caller":"traceutil/trace.go:171","msg":"trace[837063216] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"177.906674ms","start":"2026-09-29T15:35:14.377664Z","end":"2026-09-29T15:35:14.555571Z","steps":["trace[837063216] 'process raft request' (duration: 177.836232ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:35:14.555621Z","caller":"traceutil/trace.go:171","msg":"trace[438018679] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"217.017267ms","start":"2026-09-29T15:35:14.33855Z","end":"2026-09-29T15:35:14.555568Z","steps":["trace[438018679] 'process raft request' (duration: 131.589929ms)","trace[438018679] 'compare' (duration: 85.230303ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:35:15.203808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.825879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T15:35:15.204012Z","caller":"traceutil/trace.go:171","msg":"trace[881010889] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"556.100489ms","start":"2026-09-29T15:35:14.647854Z","end":"2026-09-29T15:35:15.203955Z","steps":["trace[881010889] 'process raft request' (duration: 344.911949ms)","trace[881010889] 'compare' (duration: 210.678214ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:35:15.20426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:35:14.647836Z","time spent":"556.294084ms","remote":"127.0.0.1:48388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T15:35:15.204405Z","caller":"traceutil/trace.go:171","msg":"trace[2967270] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"254.575078ms","start":"2026-09-29T15:35:14.949807Z","end":"2026-09-29T15:35:15.204382Z","steps":["trace[2967270] 'read index received' (duration: 42.978516ms)","trace[2967270] 'applied index is now lower than readState.Index' (duration: 211.594222ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:35:15.205578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.870097ms","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-09-29T15:35:15.205689Z","caller":"traceutil/trace.go:171","msg":"trace[1588829551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"255.883627ms","start":"2026-09-29T15:35:14.949767Z","end":"2026-09-29T15:35:15.205651Z","steps":["trace[1588829551] 'agreement among raft nodes before linearized reading' (duration: 254.754794ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:35:15.212087Z","caller":"traceutil/trace.go:171","msg":"trace[28989026] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"232.90973ms","start":"2026-09-29T15:35:14.979145Z","end":"2026-09-29T15:35:15.212055Z","steps":["trace[28989026] 'process raft request' (duration: 225.43424ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:35:15.212125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.346743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T15:35:15.212226Z","caller":"traceutil/trace.go:171","msg":"trace[939830804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"122.511538ms","start":"2026-09-29T15:35:15.089692Z","end":"2026-09-29T15:35:15.212204Z","steps":["trace[939830804] 'agreement among raft nodes before linearized reading' (duration: 122.323542ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:35:32.769194Z","caller":"traceutil/trace.go:171","msg":"trace[1299869397] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:794; }","duration":"131.50373ms","start":"2026-09-29T15:35:32.637669Z","end":"2026-09-29T15:35:32.769173Z","steps":["trace[1299869397] 'read index received' (duration: 131.314405ms)","trace[1299869397] 'applied index is now lower than readState.Index' (duration: 188.395µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:35:32.769291Z","caller":"traceutil/trace.go:171","msg":"trace[2135324849] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"178.389345ms","start":"2026-09-29T15:35:32.590848Z","end":"2026-09-29T15:35:32.769238Z","steps":["trace[2135324849] 'process raft request' (duration: 178.18864ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:35:32.769436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.760447ms","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-29T15:35:32.769478Z","caller":"traceutil/trace.go:171","msg":"trace[1120999921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:766; }","duration":"131.84737ms","start":"2026-09-29T15:35:32.637619Z","end":"2026-09-29T15:35:32.769467Z","steps":["trace[1120999921] 'agreement among raft nodes before linearized reading' (duration: 131.707566ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:35:50.372672Z","caller":"traceutil/trace.go:171","msg":"trace[784139554] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"111.276717ms","start":"2026-09-29T15:35:50.261373Z","end":"2026-09-29T15:35:50.372649Z","steps":["trace[784139554] 'process raft request' (duration: 111.153374ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:36:04.360853Z","caller":"traceutil/trace.go:171","msg":"trace[1693711414] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1218; }","duration":"269.616472ms","start":"2026-09-29T15:36:04.091175Z","end":"2026-09-29T15:36:04.360791Z","steps":["trace[1693711414] 'read index received' (duration: 260.139285ms)","trace[1693711414] 'applied index is now lower than readState.Index' (duration: 9.474707ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:36:04.360958Z","caller":"traceutil/trace.go:171","msg":"trace[1803774381] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"320.026764ms","start":"2026-09-29T15:36:04.040875Z","end":"2026-09-29T15:36:04.360902Z","steps":["trace[1803774381] 'process raft request' (duration: 310.496596ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:36:04.361144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.90668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T15:36:04.36119Z","caller":"traceutil/trace.go:171","msg":"trace[102506150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1182; }","duration":"270.077405ms","start":"2026-09-29T15:36:04.091099Z","end":"2026-09-29T15:36:04.361176Z","steps":["trace[102506150] 'agreement among raft nodes before linearized reading' (duration: 269.887119ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:36:04.361297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:36:04.040841Z","time spent":"320.24555ms","remote":"127.0.0.1:48388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T15:36:04.371218Z","caller":"traceutil/trace.go:171","msg":"trace[1707230378] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"181.118597ms","start":"2026-09-29T15:36:04.190069Z","end":"2026-09-29T15:36:04.371188Z","steps":["trace[1707230378] 'process raft request' (duration: 180.605002ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:36:05.156101Z","caller":"traceutil/trace.go:171","msg":"trace[1254420212] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"100.300456ms","start":"2026-09-29T15:36:05.055762Z","end":"2026-09-29T15:36:05.156062Z","steps":["trace[1254420212] 'process raft request' (duration: 100.08412ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:36:05.163968Z","caller":"traceutil/trace.go:171","msg":"trace[2096485509] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"105.937575ms","start":"2026-09-29T15:36:05.057996Z","end":"2026-09-29T15:36:05.163933Z","steps":["trace[2096485509] 'process raft request' (duration: 105.73261ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:36:48.034164Z","caller":"traceutil/trace.go:171","msg":"trace[448927018] transaction","detail":"{read_only:false; response_revision:1763; number_of_response:1; }","duration":"170.128298ms","start":"2026-09-29T15:36:47.864008Z","end":"2026-09-29T15:36:48.034137Z","steps":["trace[448927018] 'process raft request' (duration: 169.963193ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:36:48.089356Z","caller":"traceutil/trace.go:171","msg":"trace[2090829402] transaction","detail":"{read_only:false; response_revision:1764; number_of_response:1; }","duration":"225.031397ms","start":"2026-09-29T15:36:47.8643Z","end":"2026-09-29T15:36:48.089331Z","steps":["trace[2090829402] 'process raft request' (duration: 220.489611ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:36:57.920699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.008121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T15:36:57.920814Z","caller":"traceutil/trace.go:171","msg":"trace[977333384] linearizableReadLoop","detail":"{readStateIndex:1960; appliedIndex:1959; }","duration":"223.111236ms","start":"2026-09-29T15:36:57.697678Z","end":"2026-09-29T15:36:57.920789Z","steps":["trace[977333384] 'read index received' (duration: 51.521µs)","trace[977333384] 'applied index is now lower than readState.Index' (duration: 223.058015ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:36:57.920944Z","caller":"traceutil/trace.go:171","msg":"trace[1552410055] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"384.665563ms","start":"2026-09-29T15:36:57.536265Z","end":"2026-09-29T15:36:57.920931Z","steps":["trace[1552410055] 'process raft request' (duration: 94.322562ms)","trace[1552410055] 'compare' (duration: 289.866548ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:36:57.921044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:36:57.536245Z","time spent":"384.754914ms","remote":"127.0.0.1:48388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T15:36:57.921426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.957983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-29T15:36:57.921531Z","caller":"traceutil/trace.go:171","msg":"trace[365529683] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1885; }","duration":"189.115697ms","start":"2026-09-29T15:36:57.732389Z","end":"2026-09-29T15:36:57.921505Z","steps":["trace[365529683] 'agreement among raft nodes before linearized reading' (duration: 188.84696ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:36:57.921984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.296028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-29T15:36:57.922131Z","caller":"traceutil/trace.go:171","msg":"trace[185307178] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1885; }","duration":"224.424043ms","start":"2026-09-29T15:36:57.697624Z","end":"2026-09-29T15:36:57.922048Z","steps":["trace[185307178] 'agreement among raft nodes before linearized reading' (duration: 223.816775ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:36:58.330742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.376463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-29T15:36:58.330836Z","caller":"traceutil/trace.go:171","msg":"trace[1896228814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1886; }","duration":"251.520617ms","start":"2026-09-29T15:36:58.079292Z","end":"2026-09-29T15:36:58.330812Z","steps":["trace[1896228814] 'range keys from in-memory index tree' (duration: 251.169637ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:36:58.330948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.025493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa05507e-877d-44b2-ad81-d78186ddae38\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-29T15:36:58.331014Z","caller":"traceutil/trace.go:171","msg":"trace[1993679158] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fa05507e-877d-44b2-ad81-d78186ddae38; range_end:; response_count:1; response_revision:1886; }","duration":"226.126286ms","start":"2026-09-29T15:36:58.104864Z","end":"2026-09-29T15:36:58.33099Z","steps":["trace[1993679158] 'range keys from in-memory index tree' (duration: 225.803448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:36:58.330735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.894066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T15:36:58.331152Z","caller":"traceutil/trace.go:171","msg":"trace[1861868477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1886; }","duration":"240.331929ms","start":"2026-09-29T15:36:58.090786Z","end":"2026-09-29T15:36:58.331117Z","steps":["trace[1861868477] 'range keys from in-memory index tree' (duration: 239.783643ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:36:58.358239Z","caller":"traceutil/trace.go:171","msg":"trace[2079986710] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1961; }","duration":"223.614068ms","start":"2026-09-29T15:36:58.134581Z","end":"2026-09-29T15:36:58.358195Z","steps":["trace[2079986710] 'read index received' (duration: 223.427103ms)","trace[2079986710] 'applied index is now lower than readState.Index' (duration: 185.315µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:36:58.358271Z","caller":"traceutil/trace.go:171","msg":"trace[42442000] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"228.947315ms","start":"2026-09-29T15:36:58.129305Z","end":"2026-09-29T15:36:58.358252Z","steps":["trace[42442000] 'process raft request' (duration: 228.688148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:36:58.358536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.852165ms","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-09-29T15:36:58.358613Z","caller":"traceutil/trace.go:171","msg":"trace[1308869327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1887; }","duration":"224.026299ms","start":"2026-09-29T15:36:58.134555Z","end":"2026-09-29T15:36:58.358582Z","steps":["trace[1308869327] 'agreement among raft nodes before linearized reading' (duration: 223.773182ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:36:58.370073Z","caller":"traceutil/trace.go:171","msg":"trace[1877055972] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"170.127361ms","start":"2026-09-29T15:36:58.199923Z","end":"2026-09-29T15:36:58.37005Z","steps":["trace[1877055972] 'process raft request' (duration: 169.938356ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:36:58.370375Z","caller":"traceutil/trace.go:171","msg":"trace[1812838756] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"144.389383ms","start":"2026-09-29T15:36:58.225968Z","end":"2026-09-29T15:36:58.370357Z","steps":["trace[1812838756] 'process raft request' (duration: 144.038692ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:37:11.123654Z","caller":"traceutil/trace.go:171","msg":"trace[1486773870] linearizableReadLoop","detail":"{readStateIndex:2083; appliedIndex:2082; }","duration":"114.307933ms","start":"2026-09-29T15:37:11.009322Z","end":"2026-09-29T15:37:11.12363Z","steps":["trace[1486773870] 'read index received' (duration: 91.033343ms)","trace[1486773870] 'applied index is now lower than readState.Index' (duration: 23.27372ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:37:11.123728Z","caller":"traceutil/trace.go:171","msg":"trace[133739769] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"182.527239ms","start":"2026-09-29T15:37:10.941173Z","end":"2026-09-29T15:37:11.1237Z","steps":["trace[133739769] 'process raft request' (duration: 159.265739ms)","trace[133739769] 'compare' (duration: 22.993562ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:37:11.123872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.54682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-29T15:37:11.123909Z","caller":"traceutil/trace.go:171","msg":"trace[740635024] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2004; }","duration":"114.645662ms","start":"2026-09-29T15:37:11.009251Z","end":"2026-09-29T15:37:11.123896Z","steps":["trace[740635024] 'agreement among raft nodes before linearized reading' (duration: 114.498178ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:37:26.950605Z","caller":"traceutil/trace.go:171","msg":"trace[710727069] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"102.536562ms","start":"2026-09-29T15:37:26.848045Z","end":"2026-09-29T15:37:26.950581Z","steps":["trace[710727069] 'process raft request' (duration: 102.219644ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:37:32.643796Z","caller":"traceutil/trace.go:171","msg":"trace[1585466090] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"132.631403ms","start":"2026-09-29T15:37:32.511133Z","end":"2026-09-29T15:37:32.643765Z","steps":["trace[1585466090] 'process raft request' (duration: 82.991423ms)","trace[1585466090] 'compare' (duration: 49.443915ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:37:33.178314Z","caller":"traceutil/trace.go:171","msg":"trace[1008202592] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"119.783301ms","start":"2026-09-29T15:37:33.058502Z","end":"2026-09-29T15:37:33.178286Z","steps":["trace[1008202592] 'process raft request' (duration: 113.367724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:37:33.477433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.243543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-29T15:37:33.477527Z","caller":"traceutil/trace.go:171","msg":"trace[5180015] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2131; }","duration":"193.393857ms","start":"2026-09-29T15:37:33.28411Z","end":"2026-09-29T15:37:33.477503Z","steps":["trace[5180015] 'range keys from in-memory index tree' (duration: 192.919104ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:37:33.819954Z","caller":"traceutil/trace.go:171","msg":"trace[1465215148] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"107.777703ms","start":"2026-09-29T15:37:33.712152Z","end":"2026-09-29T15:37:33.81993Z","steps":["trace[1465215148] 'process raft request' (duration: 107.623469ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:37:34.236692Z","caller":"traceutil/trace.go:171","msg":"trace[1526740069] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"100.088241ms","start":"2026-09-29T15:37:34.136582Z","end":"2026-09-29T15:37:34.236671Z","steps":["trace[1526740069] 'process raft request' (duration: 99.961008ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:39:14.428006Z","caller":"traceutil/trace.go:171","msg":"trace[1986981731] transaction","detail":"{read_only:false; response_revision:2766; number_of_response:1; }","duration":"141.343081ms","start":"2026-09-29T15:39:14.286645Z","end":"2026-09-29T15:39:14.427988Z","steps":["trace[1986981731] 'process raft request' (duration: 141.250247ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:39:47.225923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.966364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T15:39:47.226513Z","caller":"traceutil/trace.go:171","msg":"trace[200165669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2946; }","duration":"136.576727ms","start":"2026-09-29T15:39:47.089895Z","end":"2026-09-29T15:39:47.226472Z","steps":["trace[200165669] 'range keys from in-memory index tree' (duration: 135.894201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:39:47.225989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.559759ms","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-09-29T15:39:47.226925Z","caller":"traceutil/trace.go:171","msg":"trace[724971953] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2946; }","duration":"111.509994ms","start":"2026-09-29T15:39:47.115371Z","end":"2026-09-29T15:39:47.226881Z","steps":["trace[724971953] 'range keys from in-memory index tree' (duration: 110.542349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:39:47.436285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.249025ms","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-29T15:39:47.436389Z","caller":"traceutil/trace.go:171","msg":"trace[1395478255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2947; }","duration":"103.40167ms","start":"2026-09-29T15:39:47.332968Z","end":"2026-09-29T15:39:47.43637Z","steps":["trace[1395478255] 'range keys from in-memory index tree' (duration: 103.076658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:39:47.436401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.126492ms","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-29T15:39:47.436462Z","caller":"traceutil/trace.go:171","msg":"trace[379404670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2947; }","duration":"105.260527ms","start":"2026-09-29T15:39:47.331183Z","end":"2026-09-29T15:39:47.436443Z","steps":["trace[379404670] 'range keys from in-memory index tree' (duration: 104.983527ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:39:47.614322Z","caller":"traceutil/trace.go:171","msg":"trace[2089454617] transaction","detail":"{read_only:false; response_revision:2948; number_of_response:1; }","duration":"216.857566ms","start":"2026-09-29T15:39:47.397436Z","end":"2026-09-29T15:39:47.614293Z","steps":["trace[2089454617] 'process raft request' (duration: 216.585787ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:39:47.647236Z","caller":"traceutil/trace.go:171","msg":"trace[222968821] transaction","detail":"{read_only:false; response_revision:2949; number_of_response:1; }","duration":"208.062815ms","start":"2026-09-29T15:39:47.439144Z","end":"2026-09-29T15:39:47.647207Z","steps":["trace[222968821] 'process raft request' (duration: 203.23572ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:39:47.647323Z","caller":"traceutil/trace.go:171","msg":"trace[798871584] transaction","detail":"{read_only:false; response_revision:2950; number_of_response:1; }","duration":"206.463157ms","start":"2026-09-29T15:39:47.440834Z","end":"2026-09-29T15:39:47.647298Z","steps":["trace[798871584] 'process raft request' (duration: 206.273151ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:39:47.647412Z","caller":"traceutil/trace.go:171","msg":"trace[333742996] transaction","detail":"{read_only:false; response_revision:2951; number_of_response:1; }","duration":"206.28354ms","start":"2026-09-29T15:39:47.441091Z","end":"2026-09-29T15:39:47.647374Z","steps":["trace[333742996] 'process raft request' (duration: 206.082633ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:43:06.840097Z","caller":"traceutil/trace.go:171","msg":"trace[1390608568] transaction","detail":"{read_only:false; response_revision:4084; number_of_response:1; }","duration":"104.087022ms","start":"2026-09-29T15:43:06.735984Z","end":"2026-09-29T15:43:06.840071Z","steps":["trace[1390608568] 'process raft request' (duration: 103.935237ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:44:14.131584Z","caller":"traceutil/trace.go:171","msg":"trace[682560937] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"149.998022ms","start":"2026-09-29T15:44:13.98156Z","end":"2026-09-29T15:44:14.131558Z","steps":["trace[682560937] 'process raft request' (duration: 149.816237ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:44:14.133836Z","caller":"traceutil/trace.go:171","msg":"trace[1651751610] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"151.843258ms","start":"2026-09-29T15:44:13.981946Z","end":"2026-09-29T15:44:14.13379Z","steps":["trace[1651751610] 'process raft request' (duration: 151.5859ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:44:14.134112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.3295ms","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-29T15:44:14.134425Z","caller":"traceutil/trace.go:171","msg":"trace[1070054734] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5034; }","duration":"101.711942ms","start":"2026-09-29T15:44:14.032698Z","end":"2026-09-29T15:44:14.13441Z","steps":["trace[1070054734] 'agreement among raft nodes before linearized reading' (duration: 101.197087ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:44:14.135157Z","caller":"traceutil/trace.go:171","msg":"trace[361357735] linearizableReadLoop","detail":"{readStateIndex:5229; appliedIndex:5227; }","duration":"100.9583ms","start":"2026-09-29T15:44:14.03274Z","end":"2026-09-29T15:44:14.133698Z","steps":["trace[361357735] 'read index received' (duration: 98.60784ms)","trace[361357735] 'applied index is now lower than readState.Index' (duration: 2.34937ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:44:19.327037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.078926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T15:44:19.327589Z","caller":"traceutil/trace.go:171","msg":"trace[1377608995] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5125; }","duration":"110.670654ms","start":"2026-09-29T15:44:19.216893Z","end":"2026-09-29T15:44:19.327564Z","steps":["trace[1377608995] 'count revisions from in-memory index tree' (duration: 110.000503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:44:19.327158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.378268ms","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-09-29T15:44:19.328008Z","caller":"traceutil/trace.go:171","msg":"trace[895175501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5125; }","duration":"128.257103ms","start":"2026-09-29T15:44:19.199736Z","end":"2026-09-29T15:44:19.327993Z","steps":["trace[895175501] 'range keys from in-memory index tree' (duration: 127.218032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:44:19.327882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.021942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5719c714-a910-41ac-9630-b57b73ff80b6\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-29T15:44:19.328107Z","caller":"traceutil/trace.go:171","msg":"trace[710276876] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5719c714-a910-41ac-9630-b57b73ff80b6; range_end:; response_count:1; response_revision:5125; }","duration":"184.27753ms","start":"2026-09-29T15:44:19.143804Z","end":"2026-09-29T15:44:19.328081Z","steps":["trace[710276876] 'range keys from in-memory index tree' (duration: 183.154236ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:44:19.383907Z","caller":"traceutil/trace.go:171","msg":"trace[1227303327] transaction","detail":"{read_only:false; response_revision:5126; number_of_response:1; }","duration":"127.334417ms","start":"2026-09-29T15:44:19.256533Z","end":"2026-09-29T15:44:19.383867Z","steps":["trace[1227303327] 'process raft request' (duration: 127.176763ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:44:19.829982Z","caller":"traceutil/trace.go:171","msg":"trace[1241049659] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"201.775718ms","start":"2026-09-29T15:44:19.628182Z","end":"2026-09-29T15:44:19.829958Z","steps":["trace[1241049659] 'process raft request' (duration: 201.643554ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:44:25.92618Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2835} {"level":"info","ts":"2026-09-29T15:44:26.007276Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2835,"took":"79.513518ms","hash":1596644014,"current-db-size-bytes":51720192,"current-db-size":"52 MB","current-db-size-in-use-bytes":27119616,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-29T15:44:26.007334Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1596644014,"revision":2835,"compact-revision":-1} {"level":"warn","ts":"2026-09-29T15:44:33.414396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.886379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-29T15:44:33.414425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.348125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T15:44:33.414474Z","caller":"traceutil/trace.go:171","msg":"trace[338470842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5307; }","duration":"323.005933ms","start":"2026-09-29T15:44:33.091451Z","end":"2026-09-29T15:44:33.414457Z","steps":["trace[338470842] 'range keys from in-memory index tree' (duration: 322.762746ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:44:33.414478Z","caller":"traceutil/trace.go:171","msg":"trace[979823715] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:5307; }","duration":"164.433728ms","start":"2026-09-29T15:44:33.250029Z","end":"2026-09-29T15:44:33.414463Z","steps":["trace[979823715] 'count revisions from in-memory index tree' (duration: 164.238842ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:44:33.414516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:44:33.091433Z","time spent":"323.069164ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-29T15:44:33.414732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.680781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-09-29T15:44:33.415245Z","caller":"traceutil/trace.go:171","msg":"trace[1185423200] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5307; }","duration":"309.238427ms","start":"2026-09-29T15:44:33.10598Z","end":"2026-09-29T15:44:33.415219Z","steps":["trace[1185423200] 'range keys from in-memory index tree' (duration: 308.291699ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:44:33.41543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:44:33.105962Z","time spent":"309.375111ms","remote":"127.0.0.1:35302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3340,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-09-29T15:45:24.003829Z","caller":"traceutil/trace.go:171","msg":"trace[708040053] linearizableReadLoop","detail":"{readStateIndex:5937; appliedIndex:5936; }","duration":"205.999559ms","start":"2026-09-29T15:45:23.797803Z","end":"2026-09-29T15:45:24.003802Z","steps":["trace[708040053] 'read index received' (duration: 205.757252ms)","trace[708040053] 'applied index is now lower than readState.Index' (duration: 241.357µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:45:24.003986Z","caller":"traceutil/trace.go:171","msg":"trace[1841512163] transaction","detail":"{read_only:false; response_revision:5721; number_of_response:1; }","duration":"209.679146ms","start":"2026-09-29T15:45:23.79429Z","end":"2026-09-29T15:45:24.00397Z","steps":["trace[1841512163] 'process raft request' (duration: 209.329266ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:45:24.004083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.336834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T15:45:24.004175Z","caller":"traceutil/trace.go:171","msg":"trace[2081958673] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:5721; }","duration":"199.463288ms","start":"2026-09-29T15:45:23.804682Z","end":"2026-09-29T15:45:24.004146Z","steps":["trace[2081958673] 'agreement among raft nodes before linearized reading' (duration: 199.322654ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:45:24.004392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.991413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T15:45:24.00445Z","caller":"traceutil/trace.go:171","msg":"trace[372633734] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5721; }","duration":"187.071976ms","start":"2026-09-29T15:45:23.817367Z","end":"2026-09-29T15:45:24.004439Z","steps":["trace[372633734] 'agreement among raft nodes before linearized reading' (duration: 186.968803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:45:24.004496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.483128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-29T15:45:24.004498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.663678ms","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-29T15:45:24.004569Z","caller":"traceutil/trace.go:171","msg":"trace[474138319] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5721; }","duration":"187.594882ms","start":"2026-09-29T15:45:23.816958Z","end":"2026-09-29T15:45:24.004553Z","steps":["trace[474138319] 'agreement among raft nodes before linearized reading' (duration: 187.448717ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:45:24.004591Z","caller":"traceutil/trace.go:171","msg":"trace[996906137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5721; }","duration":"206.801282ms","start":"2026-09-29T15:45:23.797769Z","end":"2026-09-29T15:45:24.004571Z","steps":["trace[996906137] 'agreement among raft nodes before linearized reading' (duration: 206.531194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:45:24.004603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.385186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-09-29T15:45:24.004707Z","caller":"traceutil/trace.go:171","msg":"trace[316228425] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5721; }","duration":"173.52973ms","start":"2026-09-29T15:45:23.831156Z","end":"2026-09-29T15:45:24.004686Z","steps":["trace[316228425] 'agreement among raft nodes before linearized reading' (duration: 173.291543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:45:24.0048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.535359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-09-29T15:45:24.004542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.325807ms","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-09-29T15:45:24.004881Z","caller":"traceutil/trace.go:171","msg":"trace[1240346009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5721; }","duration":"168.714338ms","start":"2026-09-29T15:45:23.836148Z","end":"2026-09-29T15:45:24.004862Z","steps":["trace[1240346009] 'agreement among raft nodes before linearized reading' (duration: 168.116461ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:45:24.004939Z","caller":"traceutil/trace.go:171","msg":"trace[1766326156] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5721; }","duration":"148.617022ms","start":"2026-09-29T15:45:23.856233Z","end":"2026-09-29T15:45:24.00485Z","steps":["trace[1766326156] 'agreement among raft nodes before linearized reading' (duration: 148.437517ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:45:24.118429Z","caller":"traceutil/trace.go:171","msg":"trace[680010938] linearizableReadLoop","detail":"{readStateIndex:5939; appliedIndex:5937; }","duration":"108.205861ms","start":"2026-09-29T15:45:24.010197Z","end":"2026-09-29T15:45:24.118403Z","steps":["trace[680010938] 'read index received' (duration: 51.197416ms)","trace[680010938] 'applied index is now lower than readState.Index' (duration: 57.005365ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:45:24.118473Z","caller":"traceutil/trace.go:171","msg":"trace[1739572745] transaction","detail":"{read_only:false; response_revision:5723; number_of_response:1; }","duration":"108.987014ms","start":"2026-09-29T15:45:24.009457Z","end":"2026-09-29T15:45:24.118444Z","steps":["trace[1739572745] 'process raft request' (duration: 73.715013ms)","trace[1739572745] 'compare' (duration: 35.101736ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:45:24.11933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.116957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:11918"} {"level":"info","ts":"2026-09-29T15:45:24.119397Z","caller":"traceutil/trace.go:171","msg":"trace[414680023] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5723; }","duration":"109.224901ms","start":"2026-09-29T15:45:24.010154Z","end":"2026-09-29T15:45:24.119379Z","steps":["trace[414680023] 'agreement among raft nodes before linearized reading' (duration: 108.425717ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:49:25.933032Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5226} {"level":"info","ts":"2026-09-29T15:49:26.014346Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5226,"took":"79.591516ms","hash":1015426362,"current-db-size-bytes":51720192,"current-db-size":"52 MB","current-db-size-in-use-bytes":19292160,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-29T15:49:26.014443Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1015426362,"revision":5226,"compact-revision":2835}