{"level":"warn","ts":"2026-09-29T03:36:26.871176Z","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-29T03:36:26.871269Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.109: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.109:2380","--initial-cluster=instance=https://162.253.55.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.109: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-29T03:36:26.87134Z","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-29T03:36:26.87135Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.109:2380"]} {"level":"info","ts":"2026-09-29T03:36:26.871389Z","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-29T03:36:26.87212Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.109:2379"]} {"level":"info","ts":"2026-09-29T03:36:26.872301Z","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.109:2380"],"listen-peer-urls":["https://162.253.55.109:2380"],"advertise-client-urls":["https://162.253.55.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.109: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-29T03:36:26.878134Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.583506ms"} {"level":"info","ts":"2026-09-29T03:36:26.887427Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1ee91cef4f0023c4","cluster-id":"8dccb05df97999f"} {"level":"info","ts":"2026-09-29T03:36:26.887523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 switched to configuration voters=()"} {"level":"info","ts":"2026-09-29T03:36:26.887562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 became follower at term 0"} {"level":"info","ts":"2026-09-29T03:36:26.887591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1ee91cef4f0023c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-29T03:36:26.887622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 became follower at term 1"} {"level":"info","ts":"2026-09-29T03:36:26.88768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 switched to configuration voters=(2227343304859591620)"} {"level":"warn","ts":"2026-09-29T03:36:26.89514Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-29T03:36:26.896769Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-29T03:36:26.898204Z","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-29T03:36:26.900453Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1ee91cef4f0023c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-29T03:36:26.900669Z","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-29T03:36:26.90187Z","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-29T03:36:26.90191Z","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-29T03:36:26.901211Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1ee91cef4f0023c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-29T03:36:26.901649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 switched to configuration voters=(2227343304859591620)"} {"level":"info","ts":"2026-09-29T03:36:26.902293Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8dccb05df97999f","local-member-id":"1ee91cef4f0023c4","added-peer-id":"1ee91cef4f0023c4","added-peer-peer-urls":["https://162.253.55.109:2380"]} {"level":"info","ts":"2026-09-29T03:36:26.904344Z","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-29T03:36:26.904473Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.109:2380"} {"level":"info","ts":"2026-09-29T03:36:26.904662Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.109:2380"} {"level":"info","ts":"2026-09-29T03:36:26.905448Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1ee91cef4f0023c4","initial-advertise-peer-urls":["https://162.253.55.109:2380"],"listen-peer-urls":["https://162.253.55.109:2380"],"advertise-client-urls":["https://162.253.55.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-29T03:36:26.905518Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-29T03:36:27.188305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 is starting a new election at term 1"} {"level":"info","ts":"2026-09-29T03:36:27.188349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-29T03:36:27.188464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 received MsgPreVoteResp from 1ee91cef4f0023c4 at term 1"} {"level":"info","ts":"2026-09-29T03:36:27.188487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 became candidate at term 2"} {"level":"info","ts":"2026-09-29T03:36:27.188499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 received MsgVoteResp from 1ee91cef4f0023c4 at term 2"} {"level":"info","ts":"2026-09-29T03:36:27.188519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 became leader at term 2"} {"level":"info","ts":"2026-09-29T03:36:27.188531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1ee91cef4f0023c4 elected leader 1ee91cef4f0023c4 at term 2"} {"level":"info","ts":"2026-09-29T03:36:27.18936Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T03:36:27.19036Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1ee91cef4f0023c4","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.109:2379]}","request-path":"/0/members/1ee91cef4f0023c4/attributes","cluster-id":"8dccb05df97999f","publish-timeout":"7s"} {"level":"info","ts":"2026-09-29T03:36:27.19042Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-29T03:36:27.190452Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-29T03:36:27.190762Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-29T03:36:27.190849Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-29T03:36:27.190841Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8dccb05df97999f","local-member-id":"1ee91cef4f0023c4","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T03:36:27.190948Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T03:36:27.190992Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T03:36:27.195673Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-29T03:36:27.206573Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.109:2379"} {"level":"info","ts":"2026-09-29T03:36:32.102683Z","caller":"traceutil/trace.go:171","msg":"trace[376723483] linearizableReadLoop","detail":"{readStateIndex:236; appliedIndex:235; }","duration":"218.841243ms","start":"2026-09-29T03:36:31.883821Z","end":"2026-09-29T03:36:32.102663Z","steps":["trace[376723483] 'read index received' (duration: 186.17254ms)","trace[376723483] 'applied index is now lower than readState.Index' (duration: 32.667593ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:36:32.102797Z","caller":"traceutil/trace.go:171","msg":"trace[458596107] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"220.77714ms","start":"2026-09-29T03:36:31.882009Z","end":"2026-09-29T03:36:32.102786Z","steps":["trace[458596107] 'process raft request' (duration: 187.974151ms)","trace[458596107] 'compare' (duration: 32.56899ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:36:32.102955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.126034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T03:36:32.103016Z","caller":"traceutil/trace.go:171","msg":"trace[884298940] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:230; }","duration":"219.217898ms","start":"2026-09-29T03:36:31.883787Z","end":"2026-09-29T03:36:32.103005Z","steps":["trace[884298940] 'agreement among raft nodes before linearized reading' (duration: 219.126434ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:36:32.949336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.26286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-29T03:36:32.949697Z","caller":"traceutil/trace.go:171","msg":"trace[1656488182] linearizableReadLoop","detail":"{readStateIndex:239; appliedIndex:236; }","duration":"810.635398ms","start":"2026-09-29T03:36:32.139042Z","end":"2026-09-29T03:36:32.949678Z","steps":["trace[1656488182] 'read index received' (duration: 177.476353ms)","trace[1656488182] 'applied index is now lower than readState.Index' (duration: 633.157725ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:36:32.949713Z","caller":"traceutil/trace.go:171","msg":"trace[1481002070] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"843.159315ms","start":"2026-09-29T03:36:32.106527Z","end":"2026-09-29T03:36:32.949686Z","steps":["trace[1481002070] 'process raft request' (duration: 209.97968ms)","trace[1481002070] 'compare' (duration: 632.045781ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:36:32.94979Z","caller":"traceutil/trace.go:171","msg":"trace[352953510] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"842.615144ms","start":"2026-09-29T03:36:32.107163Z","end":"2026-09-29T03:36:32.949778Z","steps":["trace[352953510] 'process raft request' (duration: 842.360234ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:36:32.949787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:36:32.106514Z","time spent":"843.240758ms","remote":"127.0.0.1:50010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-29T03:36:32.949868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:36:32.107138Z","time spent":"842.693867ms","remote":"127.0.0.1:50102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":290,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-29T03:36:32.950052Z","caller":"traceutil/trace.go:171","msg":"trace[1608394280] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"768.18768ms","start":"2026-09-29T03:36:32.181849Z","end":"2026-09-29T03:36:32.950037Z","steps":["trace[1608394280] 'process raft request' (duration: 767.787965ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:36:32.950058Z","caller":"traceutil/trace.go:171","msg":"trace[62893305] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"842.244299ms","start":"2026-09-29T03:36:32.107784Z","end":"2026-09-29T03:36:32.950028Z","steps":["trace[62893305] 'process raft request' (duration: 841.804072ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:36:32.950117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.063855ms","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-29T03:36:32.950128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:36:32.181827Z","time spent":"768.268404ms","remote":"127.0.0.1:49908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-29T03:36:32.950153Z","caller":"traceutil/trace.go:171","msg":"trace[1376692536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:234; }","duration":"811.119076ms","start":"2026-09-29T03:36:32.139022Z","end":"2026-09-29T03:36:32.950141Z","steps":["trace[1376692536] 'agreement among raft nodes before linearized reading' (duration: 811.021723ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:36:32.950179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:36:32.139013Z","time spent":"811.159689ms","remote":"127.0.0.1:50010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-29T03:36:32.950194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:36:32.107772Z","time spent":"842.361194ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-29T03:36:32.983714Z","caller":"traceutil/trace.go:171","msg":"trace[277408048] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"621.773448ms","start":"2026-09-29T03:36:32.361916Z","end":"2026-09-29T03:36:32.983689Z","steps":["trace[277408048] 'process raft request' (duration: 621.644843ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:36:32.983823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:36:32.361891Z","time spent":"621.885432ms","remote":"127.0.0.1:49804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-29T03:36:33.168816Z","caller":"traceutil/trace.go:171","msg":"trace[1005478340] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"214.086557ms","start":"2026-09-29T03:36:32.95471Z","end":"2026-09-29T03:36:33.168797Z","steps":["trace[1005478340] 'process raft request' (duration: 207.244031ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:36:33.168886Z","caller":"traceutil/trace.go:171","msg":"trace[680514992] linearizableReadLoop","detail":"{readStateIndex:243; appliedIndex:241; }","duration":"185.208602ms","start":"2026-09-29T03:36:32.983655Z","end":"2026-09-29T03:36:33.168864Z","steps":["trace[680514992] 'read index received' (duration: 178.310644ms)","trace[680514992] 'applied index is now lower than readState.Index' (duration: 6.896218ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:36:33.168908Z","caller":"traceutil/trace.go:171","msg":"trace[1609339427] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"180.839ms","start":"2026-09-29T03:36:32.988046Z","end":"2026-09-29T03:36:33.168885Z","steps":["trace[1609339427] 'process raft request' (duration: 180.789108ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:36:33.169002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.247713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-29T03:36:33.169039Z","caller":"traceutil/trace.go:171","msg":"trace[425760999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:239; }","duration":"214.325076ms","start":"2026-09-29T03:36:32.954704Z","end":"2026-09-29T03:36:33.169029Z","steps":["trace[425760999] 'agreement among raft nodes before linearized reading' (duration: 214.220391ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:36:33.168969Z","caller":"traceutil/trace.go:171","msg":"trace[1958840337] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"213.531434ms","start":"2026-09-29T03:36:32.955385Z","end":"2026-09-29T03:36:33.168916Z","steps":["trace[1958840337] 'process raft request' (duration: 213.368108ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:36:33.16915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.086877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T03:36:33.169074Z","caller":"traceutil/trace.go:171","msg":"trace[256010536] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"181.703184ms","start":"2026-09-29T03:36:32.987349Z","end":"2026-09-29T03:36:33.169052Z","steps":["trace[256010536] 'process raft request' (duration: 181.452725ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:36:33.169193Z","caller":"traceutil/trace.go:171","msg":"trace[372889759] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:239; }","duration":"214.173729ms","start":"2026-09-29T03:36:32.955006Z","end":"2026-09-29T03:36:33.16918Z","steps":["trace[372889759] 'agreement among raft nodes before linearized reading' (duration: 214.109827ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:37:07.735237Z","caller":"traceutil/trace.go:171","msg":"trace[484670078] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"101.661097ms","start":"2026-09-29T03:37:07.633553Z","end":"2026-09-29T03:37:07.735214Z","steps":["trace[484670078] 'process raft request' (duration: 101.232866ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:37:09.539235Z","caller":"traceutil/trace.go:171","msg":"trace[992701898] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"118.689996ms","start":"2026-09-29T03:37:09.420521Z","end":"2026-09-29T03:37:09.539211Z","steps":["trace[992701898] 'process raft request' (duration: 118.543792ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:37:09.546046Z","caller":"traceutil/trace.go:171","msg":"trace[1917726918] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"123.208296ms","start":"2026-09-29T03:37:09.422815Z","end":"2026-09-29T03:37:09.546023Z","steps":["trace[1917726918] 'process raft request' (duration: 123.078272ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:37:09.546212Z","caller":"traceutil/trace.go:171","msg":"trace[532875680] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"112.612455ms","start":"2026-09-29T03:37:09.433501Z","end":"2026-09-29T03:37:09.546114Z","steps":["trace[532875680] 'process raft request' (duration: 112.478491ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:37:09.546316Z","caller":"traceutil/trace.go:171","msg":"trace[870051195] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"106.223316ms","start":"2026-09-29T03:37:09.440072Z","end":"2026-09-29T03:37:09.546296Z","steps":["trace[870051195] 'process raft request' (duration: 105.935128ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:37:09.546895Z","caller":"traceutil/trace.go:171","msg":"trace[1930377826] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"106.78735ms","start":"2026-09-29T03:37:09.440095Z","end":"2026-09-29T03:37:09.546883Z","steps":["trace[1930377826] 'process raft request' (duration: 106.494493ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:37:09.547261Z","caller":"traceutil/trace.go:171","msg":"trace[1237055543] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"106.944144ms","start":"2026-09-29T03:37:09.440301Z","end":"2026-09-29T03:37:09.547245Z","steps":["trace[1237055543] 'process raft request' (duration: 106.473371ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:37:09.547422Z","caller":"traceutil/trace.go:171","msg":"trace[1317304148] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"104.554551ms","start":"2026-09-29T03:37:09.442853Z","end":"2026-09-29T03:37:09.547408Z","steps":["trace[1317304148] 'process raft request' (duration: 104.308905ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:37:09.547568Z","caller":"traceutil/trace.go:171","msg":"trace[173045030] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"103.095123ms","start":"2026-09-29T03:37:09.444418Z","end":"2026-09-29T03:37:09.547513Z","steps":["trace[173045030] 'process raft request' (duration: 102.882297ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:37:16.002173Z","caller":"traceutil/trace.go:171","msg":"trace[219430356] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"220.493842ms","start":"2026-09-29T03:37:15.781655Z","end":"2026-09-29T03:37:16.002149Z","steps":["trace[219430356] 'process raft request' (duration: 220.01381ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:37:16.550148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.495583ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-29T03:37:16.550283Z","caller":"traceutil/trace.go:171","msg":"trace[1270716380] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"527.024019ms","start":"2026-09-29T03:37:16.023206Z","end":"2026-09-29T03:37:16.550231Z","steps":["trace[1270716380] 'read index received' (duration: 32.85µs)","trace[1270716380] 'applied index is now lower than readState.Index' (duration: 526.989839ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:37:16.550363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.145843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T03:37:16.550387Z","caller":"traceutil/trace.go:171","msg":"trace[566723796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"527.218975ms","start":"2026-09-29T03:37:16.023159Z","end":"2026-09-29T03:37:16.550378Z","steps":["trace[566723796] 'agreement among raft nodes before linearized reading' (duration: 527.157523ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:37:16.550438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:37:16.023143Z","time spent":"527.287286ms","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-29T03:37:16.622273Z","caller":"traceutil/trace.go:171","msg":"trace[249021767] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"429.008107ms","start":"2026-09-29T03:37:16.193235Z","end":"2026-09-29T03:37:16.622243Z","steps":["trace[249021767] 'process raft request' (duration: 427.622743ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:37:16.622616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:37:16.19321Z","time spent":"429.14199ms","remote":"127.0.0.1:49820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T03:37:18.021349Z","caller":"traceutil/trace.go:171","msg":"trace[1723763036] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"135.019877ms","start":"2026-09-29T03:37:17.886301Z","end":"2026-09-29T03:37:18.021321Z","steps":["trace[1723763036] 'process raft request' (duration: 131.639874ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:38:02.406968Z","caller":"traceutil/trace.go:171","msg":"trace[197602847] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"149.774449ms","start":"2026-09-29T03:38:02.257171Z","end":"2026-09-29T03:38:02.406946Z","steps":["trace[197602847] 'process raft request' (duration: 149.672768ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:38:33.137048Z","caller":"traceutil/trace.go:171","msg":"trace[40270818] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1593; }","duration":"122.86741ms","start":"2026-09-29T03:38:33.014154Z","end":"2026-09-29T03:38:33.137022Z","steps":["trace[40270818] 'process raft request' (duration: 122.780169ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:38:51.913303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.846455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-469ad569-2b16-45c7-9403-964cc6bf2178\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-29T03:38:51.913412Z","caller":"traceutil/trace.go:171","msg":"trace[156828679] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-469ad569-2b16-45c7-9403-964cc6bf2178; range_end:; response_count:1; response_revision:1913; }","duration":"334.038707ms","start":"2026-09-29T03:38:51.579334Z","end":"2026-09-29T03:38:51.913373Z","steps":["trace[156828679] 'range keys from in-memory index tree' (duration: 333.528761ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:38:51.913452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:38:51.579299Z","time spent":"334.143019ms","remote":"127.0.0.1:49908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-469ad569-2b16-45c7-9403-964cc6bf2178\" "} {"level":"info","ts":"2026-09-29T03:38:52.389759Z","caller":"traceutil/trace.go:171","msg":"trace[754124054] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1991; }","duration":"271.112099ms","start":"2026-09-29T03:38:52.11861Z","end":"2026-09-29T03:38:52.389722Z","steps":["trace[754124054] 'read index received' (duration: 270.891036ms)","trace[754124054] 'applied index is now lower than readState.Index' (duration: 219.393µs)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:38:52.390092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.442494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-29T03:38:52.389876Z","caller":"traceutil/trace.go:171","msg":"trace[50282984] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"294.84853ms","start":"2026-09-29T03:38:52.094988Z","end":"2026-09-29T03:38:52.389836Z","steps":["trace[50282984] 'process raft request' (duration: 294.493085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:38:52.390264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.48973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-469ad569-2b16-45c7-9403-964cc6bf2178\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-09-29T03:38:52.390248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.061621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-29T03:38:52.390308Z","caller":"traceutil/trace.go:171","msg":"trace[1465520602] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-469ad569-2b16-45c7-9403-964cc6bf2178; range_end:; response_count:1; response_revision:1914; }","duration":"182.57091ms","start":"2026-09-29T03:38:52.207722Z","end":"2026-09-29T03:38:52.390293Z","steps":["trace[1465520602] 'agreement among raft nodes before linearized reading' (duration: 182.451949ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:38:52.39033Z","caller":"traceutil/trace.go:171","msg":"trace[1113020585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1914; }","duration":"266.199923ms","start":"2026-09-29T03:38:52.124108Z","end":"2026-09-29T03:38:52.390308Z","steps":["trace[1113020585] 'agreement among raft nodes before linearized reading' (duration: 265.918389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:38:52.390461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.502552ms","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-29T03:38:52.390539Z","caller":"traceutil/trace.go:171","msg":"trace[1086159421] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1914; }","duration":"129.619984ms","start":"2026-09-29T03:38:52.2609Z","end":"2026-09-29T03:38:52.39052Z","steps":["trace[1086159421] 'agreement among raft nodes before linearized reading' (duration: 129.331541ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:38:52.39068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.139209ms","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-29T03:38:52.390832Z","caller":"traceutil/trace.go:171","msg":"trace[1821319711] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1914; }","duration":"254.309721ms","start":"2026-09-29T03:38:52.1365Z","end":"2026-09-29T03:38:52.39081Z","steps":["trace[1821319711] 'agreement among raft nodes before linearized reading' (duration: 253.759574ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:38:52.390143Z","caller":"traceutil/trace.go:171","msg":"trace[1343774419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1914; }","duration":"271.546655ms","start":"2026-09-29T03:38:52.118581Z","end":"2026-09-29T03:38:52.390128Z","steps":["trace[1343774419] 'agreement among raft nodes before linearized reading' (duration: 271.335242ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:39:06.569739Z","caller":"traceutil/trace.go:171","msg":"trace[965682593] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"178.084878ms","start":"2026-09-29T03:39:06.39163Z","end":"2026-09-29T03:39:06.569715Z","steps":["trace[965682593] 'process raft request' (duration: 177.763184ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:39:06.884316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.306146ms","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-29T03:39:06.884423Z","caller":"traceutil/trace.go:171","msg":"trace[1825249040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2035; }","duration":"125.472969ms","start":"2026-09-29T03:39:06.758925Z","end":"2026-09-29T03:39:06.884398Z","steps":["trace[1825249040] 'range keys from in-memory index tree' (duration: 124.966472ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:39:30.911432Z","caller":"traceutil/trace.go:171","msg":"trace[1174930616] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"180.022548ms","start":"2026-09-29T03:39:30.731388Z","end":"2026-09-29T03:39:30.911411Z","steps":["trace[1174930616] 'process raft request' (duration: 161.060328ms)","trace[1174930616] 'compare' (duration: 18.739537ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:39:30.911623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.139848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-29T03:39:30.911678Z","caller":"traceutil/trace.go:171","msg":"trace[1560159205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2174; }","duration":"165.249269ms","start":"2026-09-29T03:39:30.746418Z","end":"2026-09-29T03:39:30.911667Z","steps":["trace[1560159205] 'agreement among raft nodes before linearized reading' (duration: 165.040876ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:39:30.911433Z","caller":"traceutil/trace.go:171","msg":"trace[2057843578] linearizableReadLoop","detail":"{readStateIndex:2260; appliedIndex:2259; }","duration":"164.966336ms","start":"2026-09-29T03:39:30.746441Z","end":"2026-09-29T03:39:30.911407Z","steps":["trace[2057843578] 'read index received' (duration: 145.953496ms)","trace[2057843578] 'applied index is now lower than readState.Index' (duration: 19.010959ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:39:30.911898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.613218ms","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-29T03:39:30.911931Z","caller":"traceutil/trace.go:171","msg":"trace[130563765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2174; }","duration":"110.679059ms","start":"2026-09-29T03:39:30.801239Z","end":"2026-09-29T03:39:30.911919Z","steps":["trace[130563765] 'agreement among raft nodes before linearized reading' (duration: 110.575457ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:39:30.912125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.866427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-29T03:39:30.912139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.453981ms","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-29T03:39:30.912159Z","caller":"traceutil/trace.go:171","msg":"trace[658423539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2174; }","duration":"108.919607ms","start":"2026-09-29T03:39:30.803231Z","end":"2026-09-29T03:39:30.912151Z","steps":["trace[658423539] 'agreement among raft nodes before linearized reading' (duration: 108.830686ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:39:30.912236Z","caller":"traceutil/trace.go:171","msg":"trace[1642570694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2174; }","duration":"108.520523ms","start":"2026-09-29T03:39:30.803652Z","end":"2026-09-29T03:39:30.912172Z","steps":["trace[1642570694] 'agreement among raft nodes before linearized reading' (duration: 108.413051ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:39:31.654584Z","caller":"traceutil/trace.go:171","msg":"trace[2093636439] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"315.308136ms","start":"2026-09-29T03:39:31.339248Z","end":"2026-09-29T03:39:31.654556Z","steps":["trace[2093636439] 'process raft request' (duration: 315.091243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:39:31.654771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:39:31.33922Z","time spent":"315.450397ms","remote":"127.0.0.1:50010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T03:39:31.876958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.885727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T03:39:31.877159Z","caller":"traceutil/trace.go:171","msg":"trace[1133174203] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"188.05183ms","start":"2026-09-29T03:39:31.689071Z","end":"2026-09-29T03:39:31.877123Z","steps":["trace[1133174203] 'process raft request' (duration: 67.89384ms)","trace[1133174203] 'compare' (duration: 119.745165ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:41:25.237447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.238219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T03:41:25.237663Z","caller":"traceutil/trace.go:171","msg":"trace[760041315] linearizableReadLoop","detail":"{readStateIndex:3005; appliedIndex:3004; }","duration":"274.487879ms","start":"2026-09-29T03:41:24.963154Z","end":"2026-09-29T03:41:25.237642Z","steps":["trace[760041315] 'read index received' (duration: 95.917531ms)","trace[760041315] 'applied index is now lower than readState.Index' (duration: 178.568068ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:41:25.237704Z","caller":"traceutil/trace.go:171","msg":"trace[825316394] transaction","detail":"{read_only:false; response_revision:2894; number_of_response:1; }","duration":"315.589255ms","start":"2026-09-29T03:41:24.922073Z","end":"2026-09-29T03:41:25.237663Z","steps":["trace[825316394] 'process raft request' (duration: 137.019156ms)","trace[825316394] 'compare' (duration: 177.887209ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:41:25.237969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:41:24.922022Z","time spent":"315.79811ms","remote":"127.0.0.1:50010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T03:41:25.238032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.685454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-29T03:41:25.237952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.771507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-29T03:41:25.238142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.932603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T03:41:25.238175Z","caller":"traceutil/trace.go:171","msg":"trace[1560806567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2894; }","duration":"216.964844ms","start":"2026-09-29T03:41:25.021201Z","end":"2026-09-29T03:41:25.238166Z","steps":["trace[1560806567] 'agreement among raft nodes before linearized reading' (duration: 216.921083ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:41:25.238157Z","caller":"traceutil/trace.go:171","msg":"trace[429050602] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2894; }","duration":"274.993063ms","start":"2026-09-29T03:41:24.963135Z","end":"2026-09-29T03:41:25.238128Z","steps":["trace[429050602] 'agreement among raft nodes before linearized reading' (duration: 274.598052ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:41:25.238094Z","caller":"traceutil/trace.go:171","msg":"trace[2141178798] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2894; }","duration":"177.776096ms","start":"2026-09-29T03:41:25.060296Z","end":"2026-09-29T03:41:25.238072Z","steps":["trace[2141178798] 'agreement among raft nodes before linearized reading' (duration: 177.710124ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:41:25.237936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.596047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T03:41:25.238408Z","caller":"traceutil/trace.go:171","msg":"trace[1119561754] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:2894; }","duration":"251.131982ms","start":"2026-09-29T03:41:24.987264Z","end":"2026-09-29T03:41:25.238396Z","steps":["trace[1119561754] 'agreement among raft nodes before linearized reading' (duration: 250.520845ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:45:51.506244Z","caller":"traceutil/trace.go:171","msg":"trace[189192706] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"159.753928ms","start":"2026-09-29T03:45:51.34616Z","end":"2026-09-29T03:45:51.505913Z","steps":["trace[189192706] 'process raft request' (duration: 148.020512ms)","trace[189192706] 'compare' (duration: 11.588844ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:45:56.587196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.121206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-15f42e49-49e1-493c-88c9-646f3422c11a\" ","response":"range_response_count:1 size:4063"} {"level":"info","ts":"2026-09-29T03:45:56.58725Z","caller":"traceutil/trace.go:171","msg":"trace[2015680849] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-15f42e49-49e1-493c-88c9-646f3422c11a; range_end:; response_count:1; response_revision:4947; }","duration":"108.188217ms","start":"2026-09-29T03:45:56.47905Z","end":"2026-09-29T03:45:56.587238Z","steps":["trace[2015680849] 'range keys from in-memory index tree' (duration: 107.990204ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:45:58.81666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.012475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T03:45:58.816788Z","caller":"traceutil/trace.go:171","msg":"trace[130741357] linearizableReadLoop","detail":"{readStateIndex:5215; appliedIndex:5214; }","duration":"185.655309ms","start":"2026-09-29T03:45:58.631116Z","end":"2026-09-29T03:45:58.816771Z","steps":["trace[130741357] 'read index received' (duration: 9.37589ms)","trace[130741357] 'applied index is now lower than readState.Index' (duration: 176.277819ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:45:58.817008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.884983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16085"} {"level":"info","ts":"2026-09-29T03:45:58.817038Z","caller":"traceutil/trace.go:171","msg":"trace[1845102659] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5020; }","duration":"185.926753ms","start":"2026-09-29T03:45:58.6311Z","end":"2026-09-29T03:45:58.817027Z","steps":["trace[1845102659] 'agreement among raft nodes before linearized reading' (duration: 185.71429ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:45:58.817012Z","caller":"traceutil/trace.go:171","msg":"trace[722085788] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"201.361184ms","start":"2026-09-29T03:45:58.615581Z","end":"2026-09-29T03:45:58.816942Z","steps":["trace[722085788] 'process raft request' (duration: 24.951063ms)","trace[722085788] 'compare' (duration: 175.72252ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:46:04.701358Z","caller":"traceutil/trace.go:171","msg":"trace[1092936738] linearizableReadLoop","detail":"{readStateIndex:5265; appliedIndex:5264; }","duration":"184.666629ms","start":"2026-09-29T03:46:04.516661Z","end":"2026-09-29T03:46:04.701327Z","steps":["trace[1092936738] 'read index received' (duration: 165.789359ms)","trace[1092936738] 'applied index is now lower than readState.Index' (duration: 18.87602ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:46:04.701616Z","caller":"traceutil/trace.go:171","msg":"trace[164989588] transaction","detail":"{read_only:false; response_revision:5068; number_of_response:1; }","duration":"209.61727ms","start":"2026-09-29T03:46:04.491958Z","end":"2026-09-29T03:46:04.701575Z","steps":["trace[164989588] 'process raft request' (duration: 190.395885ms)","trace[164989588] 'compare' (duration: 18.836029ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:46:04.702685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.319939ms","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-29T03:46:04.702799Z","caller":"traceutil/trace.go:171","msg":"trace[632555707] 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:5068; }","duration":"186.146782ms","start":"2026-09-29T03:46:04.516627Z","end":"2026-09-29T03:46:04.702774Z","steps":["trace[632555707] 'agreement among raft nodes before linearized reading' (duration: 184.941284ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:46:05.310304Z","caller":"traceutil/trace.go:171","msg":"trace[1043242959] transaction","detail":"{read_only:false; response_revision:5080; number_of_response:1; }","duration":"124.283262ms","start":"2026-09-29T03:46:05.185991Z","end":"2026-09-29T03:46:05.310275Z","steps":["trace[1043242959] 'process raft request' (duration: 122.947492ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:46:27.502835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2907} {"level":"info","ts":"2026-09-29T03:46:27.574623Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2907,"took":"70.928923ms","hash":2577610749,"current-db-size-bytes":51257344,"current-db-size":"51 MB","current-db-size-in-use-bytes":27426816,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-29T03:46:27.574784Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2577610749,"revision":2907,"compact-revision":-1} {"level":"warn","ts":"2026-09-29T03:46:33.048621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.169474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T03:46:33.048789Z","caller":"traceutil/trace.go:171","msg":"trace[994209067] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"210.139367ms","start":"2026-09-29T03:46:32.838596Z","end":"2026-09-29T03:46:33.048736Z","steps":["trace[994209067] 'process raft request' (duration: 102.76584ms)","trace[994209067] 'compare' (duration: 107.070182ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:46:33.048844Z","caller":"traceutil/trace.go:171","msg":"trace[529116755] linearizableReadLoop","detail":"{readStateIndex:5588; appliedIndex:5587; }","duration":"192.692204ms","start":"2026-09-29T03:46:32.856125Z","end":"2026-09-29T03:46:33.048818Z","steps":["trace[529116755] 'read index received' (duration: 85.186415ms)","trace[529116755] 'applied index is now lower than readState.Index' (duration: 107.503729ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:46:33.049094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.948978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-37162af8-e58d-46ed-8ffe-1af3ea8adc3d\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-09-29T03:46:33.049127Z","caller":"traceutil/trace.go:171","msg":"trace[854170731] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-37162af8-e58d-46ed-8ffe-1af3ea8adc3d; range_end:; response_count:1; response_revision:5383; }","duration":"193.015818ms","start":"2026-09-29T03:46:32.856099Z","end":"2026-09-29T03:46:33.049115Z","steps":["trace[854170731] 'agreement among raft nodes before linearized reading' (duration: 192.856806ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:47:24.969364Z","caller":"traceutil/trace.go:171","msg":"trace[1503536900] transaction","detail":"{read_only:false; response_revision:5793; number_of_response:1; }","duration":"120.372662ms","start":"2026-09-29T03:47:24.848721Z","end":"2026-09-29T03:47:24.969093Z","steps":["trace[1503536900] 'process raft request' (duration: 120.20973ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:51:27.509982Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5292} {"level":"info","ts":"2026-09-29T03:51:27.621594Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5292,"took":"110.034666ms","hash":988895049,"current-db-size-bytes":51257344,"current-db-size":"51 MB","current-db-size-in-use-bytes":19050496,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-29T03:51:27.621665Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":988895049,"revision":5292,"compact-revision":2907}