{"level":"warn","ts":"2026-10-04T12:39:26.277055Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T12:39:26.277131Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.127: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.127:2380","--initial-cluster=instance=https://162.253.55.127:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.127:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.127:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-04T12:39:26.277215Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T12:39:26.277229Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.127:2380"]} {"level":"info","ts":"2026-10-04T12:39:26.277251Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-04T12:39:26.277812Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.127:2379"]} {"level":"info","ts":"2026-10-04T12:39:26.277882Z","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.127:2380"],"listen-peer-urls":["https://162.253.55.127:2380"],"advertise-client-urls":["https://162.253.55.127:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.127:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.127:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-04T12:39:26.282065Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.993887ms"} {"level":"info","ts":"2026-10-04T12:39:26.287842Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9e5e76b85fbfd435","cluster-id":"7d97e4b11cbd31c2"} {"level":"info","ts":"2026-10-04T12:39:26.287889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 switched to configuration voters=()"} {"level":"info","ts":"2026-10-04T12:39:26.287961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became follower at term 0"} {"level":"info","ts":"2026-10-04T12:39:26.28797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9e5e76b85fbfd435 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-04T12:39:26.287976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became follower at term 1"} {"level":"info","ts":"2026-10-04T12:39:26.288003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 switched to configuration voters=(11411689040055882805)"} {"level":"warn","ts":"2026-10-04T12:39:26.29471Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-04T12:39:26.296082Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-04T12:39:26.297169Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-04T12:39:26.299056Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9e5e76b85fbfd435","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-04T12:39:26.299274Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9e5e76b85fbfd435","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-04T12:39:26.299566Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-04T12:39:26.299649Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-04T12:39:26.299661Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-04T12:39:26.30054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 switched to configuration voters=(11411689040055882805)"} {"level":"info","ts":"2026-10-04T12:39:26.300645Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7d97e4b11cbd31c2","local-member-id":"9e5e76b85fbfd435","added-peer-id":"9e5e76b85fbfd435","added-peer-peer-urls":["https://162.253.55.127:2380"]} {"level":"info","ts":"2026-10-04T12:39:26.301961Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-04T12:39:26.302037Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.127:2380"} {"level":"info","ts":"2026-10-04T12:39:26.302072Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.127:2380"} {"level":"info","ts":"2026-10-04T12:39:26.302159Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9e5e76b85fbfd435","initial-advertise-peer-urls":["https://162.253.55.127:2380"],"listen-peer-urls":["https://162.253.55.127:2380"],"advertise-client-urls":["https://162.253.55.127:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.127:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-04T12:39:26.30222Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-04T12:39:26.488205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 is starting a new election at term 1"} {"level":"info","ts":"2026-10-04T12:39:26.488253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-04T12:39:26.488285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 received MsgPreVoteResp from 9e5e76b85fbfd435 at term 1"} {"level":"info","ts":"2026-10-04T12:39:26.488298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became candidate at term 2"} {"level":"info","ts":"2026-10-04T12:39:26.488306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 received MsgVoteResp from 9e5e76b85fbfd435 at term 2"} {"level":"info","ts":"2026-10-04T12:39:26.488316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became leader at term 2"} {"level":"info","ts":"2026-10-04T12:39:26.488326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9e5e76b85fbfd435 elected leader 9e5e76b85fbfd435 at term 2"} {"level":"info","ts":"2026-10-04T12:39:26.489253Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9e5e76b85fbfd435","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.127:2379]}","request-path":"/0/members/9e5e76b85fbfd435/attributes","cluster-id":"7d97e4b11cbd31c2","publish-timeout":"7s"} {"level":"info","ts":"2026-10-04T12:39:26.489365Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T12:39:26.489467Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T12:39:26.489498Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T12:39:26.489704Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-04T12:39:26.489733Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-04T12:39:26.491777Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7d97e4b11cbd31c2","local-member-id":"9e5e76b85fbfd435","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T12:39:26.491883Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T12:39:26.49193Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T12:39:26.493461Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.127:2379"} {"level":"info","ts":"2026-10-04T12:39:26.494463Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-10-04T12:39:30.328831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.81058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T12:39:30.328952Z","caller":"traceutil/trace.go:171","msg":"trace[191444113] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"162.118153ms","start":"2026-10-04T12:39:30.166812Z","end":"2026-10-04T12:39:30.328931Z","steps":["trace[191444113] 'process raft request' (duration: 49.677692ms)","trace[191444113] 'compare' (duration: 111.701609ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:39:30.32913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.739226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T12:39:30.328934Z","caller":"traceutil/trace.go:171","msg":"trace[116076282] linearizableReadLoop","detail":"{readStateIndex:238; appliedIndex:237; }","duration":"141.528353ms","start":"2026-10-04T12:39:30.187389Z","end":"2026-10-04T12:39:30.328918Z","steps":["trace[116076282] 'read index received' (duration: 29.059851ms)","trace[116076282] 'applied index is now lower than readState.Index' (duration: 112.467312ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:39:30.329162Z","caller":"traceutil/trace.go:171","msg":"trace[271103155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:233; }","duration":"141.796498ms","start":"2026-10-04T12:39:30.187357Z","end":"2026-10-04T12:39:30.329153Z","steps":["trace[271103155] 'agreement among raft nodes before linearized reading' (duration: 141.651715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:39:36.052011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.505465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T12:39:36.052243Z","caller":"traceutil/trace.go:171","msg":"trace[219965877] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:370; }","duration":"461.357218ms","start":"2026-10-04T12:39:35.590868Z","end":"2026-10-04T12:39:36.052226Z","steps":["trace[219965877] 'read index received' (duration: 115.545957ms)","trace[219965877] 'applied index is now lower than readState.Index' (duration: 345.809901ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:39:36.052269Z","caller":"traceutil/trace.go:171","msg":"trace[713948040] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"488.77654ms","start":"2026-10-04T12:39:35.563452Z","end":"2026-10-04T12:39:36.052228Z","steps":["trace[713948040] 'process raft request' (duration: 142.949769ms)","trace[713948040] 'compare' (duration: 345.352643ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:39:36.0524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.52327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-10-04T12:39:36.052444Z","caller":"traceutil/trace.go:171","msg":"trace[2097527459] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:362; }","duration":"461.620782ms","start":"2026-10-04T12:39:35.590814Z","end":"2026-10-04T12:39:36.052435Z","steps":["trace[2097527459] 'agreement among raft nodes before linearized reading' (duration: 461.537071ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:39:36.052477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:39:35.59077Z","time spent":"461.698003ms","remote":"127.0.0.1:58562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-10-04T12:39:36.052619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.510213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-10-04T12:39:36.052657Z","caller":"traceutil/trace.go:171","msg":"trace[1660499774] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:362; }","duration":"411.593234ms","start":"2026-10-04T12:39:35.641054Z","end":"2026-10-04T12:39:36.052647Z","steps":["trace[1660499774] 'agreement among raft nodes before linearized reading' (duration: 411.492292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:39:36.052463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:39:35.563426Z","time spent":"488.946773ms","remote":"127.0.0.1:58402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T12:39:36.05272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:39:35.641033Z","time spent":"411.670126ms","remote":"127.0.0.1:58562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-10-04T12:39:36.052793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.998599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-04T12:39:36.052866Z","caller":"traceutil/trace.go:171","msg":"trace[1593181207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:362; }","duration":"197.1224ms","start":"2026-10-04T12:39:35.855724Z","end":"2026-10-04T12:39:36.052846Z","steps":["trace[1593181207] 'agreement among raft nodes before linearized reading' (duration: 196.993058ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:39:36.052335Z","caller":"traceutil/trace.go:171","msg":"trace[1543549418] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"486.069137ms","start":"2026-10-04T12:39:35.566256Z","end":"2026-10-04T12:39:36.052325Z","steps":["trace[1543549418] 'process raft request' (duration: 485.876783ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:39:36.053215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:39:35.566232Z","time spent":"486.674026ms","remote":"127.0.0.1:58872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T12:39:36.253476Z","caller":"traceutil/trace.go:171","msg":"trace[1362771728] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"191.337325ms","start":"2026-10-04T12:39:36.06211Z","end":"2026-10-04T12:39:36.253448Z","steps":["trace[1362771728] 'process raft request' (duration: 183.709673ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:39:36.253534Z","caller":"traceutil/trace.go:171","msg":"trace[476445325] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"187.615656ms","start":"2026-10-04T12:39:36.065892Z","end":"2026-10-04T12:39:36.253508Z","steps":["trace[476445325] 'process raft request' (duration: 187.479644ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:39:36.253607Z","caller":"traceutil/trace.go:171","msg":"trace[1854793168] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:373; }","duration":"155.461508ms","start":"2026-10-04T12:39:36.098119Z","end":"2026-10-04T12:39:36.253581Z","steps":["trace[1854793168] 'read index received' (duration: 147.711574ms)","trace[1854793168] 'applied index is now lower than readState.Index' (duration: 7.747284ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:39:36.253768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.584203ms","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-10-04T12:39:36.253844Z","caller":"traceutil/trace.go:171","msg":"trace[1806179487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:366; }","duration":"157.699634ms","start":"2026-10-04T12:39:36.096123Z","end":"2026-10-04T12:39:36.253823Z","steps":["trace[1806179487] 'agreement among raft nodes before linearized reading' (duration: 157.522582ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:39:36.254035Z","caller":"traceutil/trace.go:171","msg":"trace[130275252] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"147.43909ms","start":"2026-10-04T12:39:36.1061Z","end":"2026-10-04T12:39:36.253539Z","steps":["trace[130275252] 'process raft request' (duration: 147.360198ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:40:03.506491Z","caller":"traceutil/trace.go:171","msg":"trace[1950313581] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"126.849948ms","start":"2026-10-04T12:40:03.379606Z","end":"2026-10-04T12:40:03.506456Z","steps":["trace[1950313581] 'process raft request' (duration: 113.942032ms)","trace[1950313581] 'compare' (duration: 12.547661ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:40:03.506769Z","caller":"traceutil/trace.go:171","msg":"trace[2031519247] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"111.895082ms","start":"2026-10-04T12:40:03.394845Z","end":"2026-10-04T12:40:03.50674Z","steps":["trace[2031519247] 'process raft request' (duration: 111.583059ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:40:03.507604Z","caller":"traceutil/trace.go:171","msg":"trace[1325768220] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"112.667084ms","start":"2026-10-04T12:40:03.39492Z","end":"2026-10-04T12:40:03.507587Z","steps":["trace[1325768220] 'process raft request' (duration: 111.976484ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:40:03.507658Z","caller":"traceutil/trace.go:171","msg":"trace[1017882856] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"107.659471ms","start":"2026-10-04T12:40:03.399985Z","end":"2026-10-04T12:40:03.507644Z","steps":["trace[1017882856] 'process raft request' (duration: 107.267805ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:40:09.493684Z","caller":"traceutil/trace.go:171","msg":"trace[687499070] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"177.522278ms","start":"2026-10-04T12:40:09.316133Z","end":"2026-10-04T12:40:09.493655Z","steps":["trace[687499070] 'process raft request' (duration: 177.373615ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:40:09.674598Z","caller":"traceutil/trace.go:171","msg":"trace[1934734987] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"350.139675ms","start":"2026-10-04T12:40:09.32442Z","end":"2026-10-04T12:40:09.67456Z","steps":["trace[1934734987] 'process raft request' (duration: 349.635498ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:40:09.674748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:40:09.324394Z","time spent":"350.276187ms","remote":"127.0.0.1:58430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T12:40:10.140299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.669946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T12:40:10.140393Z","caller":"traceutil/trace.go:171","msg":"trace[2095911936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"413.809768ms","start":"2026-10-04T12:40:09.726559Z","end":"2026-10-04T12:40:10.140369Z","steps":["trace[2095911936] 'range keys from in-memory index tree' (duration: 413.585695ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:40:10.140442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:40:09.726536Z","time spent":"413.893669ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-04T12:40:10.150549Z","caller":"traceutil/trace.go:171","msg":"trace[1198871089] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"388.326258ms","start":"2026-10-04T12:40:09.762194Z","end":"2026-10-04T12:40:10.15052Z","steps":["trace[1198871089] 'process raft request' (duration: 388.146246ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:40:10.150686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:40:09.762166Z","time spent":"388.46296ms","remote":"127.0.0.1:58918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T12:40:25.514806Z","caller":"traceutil/trace.go:171","msg":"trace[1016392434] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"109.599698ms","start":"2026-10-04T12:40:25.405182Z","end":"2026-10-04T12:40:25.514782Z","steps":["trace[1016392434] 'process raft request' (duration: 109.414656ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:40:56.733708Z","caller":"traceutil/trace.go:171","msg":"trace[1084283091] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1233; }","duration":"119.426799ms","start":"2026-10-04T12:40:56.614259Z","end":"2026-10-04T12:40:56.733686Z","steps":["trace[1084283091] 'read index received' (duration: 119.297737ms)","trace[1084283091] 'applied index is now lower than readState.Index' (duration: 128.262µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:40:56.733845Z","caller":"traceutil/trace.go:171","msg":"trace[1628580218] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"136.737757ms","start":"2026-10-04T12:40:56.597075Z","end":"2026-10-04T12:40:56.733813Z","steps":["trace[1628580218] 'process raft request' (duration: 136.490974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:40:56.733929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.660631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-04T12:40:56.733976Z","caller":"traceutil/trace.go:171","msg":"trace[1451951643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1199; }","duration":"119.757713ms","start":"2026-10-04T12:40:56.614209Z","end":"2026-10-04T12:40:56.733966Z","steps":["trace[1451951643] 'agreement among raft nodes before linearized reading' (duration: 119.620751ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:40:56.733993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.639494ms","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-10-04T12:40:56.734094Z","caller":"traceutil/trace.go:171","msg":"trace[993216424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1199; }","duration":"105.759345ms","start":"2026-10-04T12:40:56.628322Z","end":"2026-10-04T12:40:56.734082Z","steps":["trace[993216424] 'agreement among raft nodes before linearized reading' (duration: 105.546722ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:41:46.956147Z","caller":"traceutil/trace.go:171","msg":"trace[1428644765] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1949; }","duration":"148.93203ms","start":"2026-10-04T12:41:46.807194Z","end":"2026-10-04T12:41:46.956126Z","steps":["trace[1428644765] 'read index received' (duration: 148.817439ms)","trace[1428644765] 'applied index is now lower than readState.Index' (duration: 113.631µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:41:46.956202Z","caller":"traceutil/trace.go:171","msg":"trace[299804705] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"170.972352ms","start":"2026-10-04T12:41:46.785199Z","end":"2026-10-04T12:41:46.956171Z","steps":["trace[299804705] 'process raft request' (duration: 170.790159ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:41:46.956354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.144212ms","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-10-04T12:41:46.956398Z","caller":"traceutil/trace.go:171","msg":"trace[839931805] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1881; }","duration":"149.238124ms","start":"2026-10-04T12:41:46.807144Z","end":"2026-10-04T12:41:46.956382Z","steps":["trace[839931805] 'agreement among raft nodes before linearized reading' (duration: 149.095042ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:41:47.187033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.157296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T12:41:47.187177Z","caller":"traceutil/trace.go:171","msg":"trace[1994764519] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"147.814155ms","start":"2026-10-04T12:41:47.039334Z","end":"2026-10-04T12:41:47.187148Z","steps":["trace[1994764519] 'process raft request' (duration: 43.463327ms)","trace[1994764519] 'compare' (duration: 104.054804ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:41:47.466201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.174507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T12:41:47.466555Z","caller":"traceutil/trace.go:171","msg":"trace[366785180] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"139.165143ms","start":"2026-10-04T12:41:47.327365Z","end":"2026-10-04T12:41:47.46653Z","steps":["trace[366785180] 'process raft request' (duration: 139.003241ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:41:47.466553Z","caller":"traceutil/trace.go:171","msg":"trace[1775998474] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"275.861806ms","start":"2026-10-04T12:41:47.190656Z","end":"2026-10-04T12:41:47.466518Z","steps":["trace[1775998474] 'process raft request' (duration: 144.278164ms)","trace[1775998474] 'compare' (duration: 131.052165ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:42:01.177829Z","caller":"traceutil/trace.go:171","msg":"trace[701630692] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"147.302931ms","start":"2026-10-04T12:42:01.030486Z","end":"2026-10-04T12:42:01.177789Z","steps":["trace[701630692] 'process raft request' (duration: 114.90047ms)","trace[701630692] 'compare' (duration: 32.202829ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:42:01.179111Z","caller":"traceutil/trace.go:171","msg":"trace[1883987281] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"148.505895ms","start":"2026-10-04T12:42:01.030575Z","end":"2026-10-04T12:42:01.179081Z","steps":["trace[1883987281] 'process raft request' (duration: 148.283362ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:42:01.179118Z","caller":"traceutil/trace.go:171","msg":"trace[1931084163] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"148.10452ms","start":"2026-10-04T12:42:01.030989Z","end":"2026-10-04T12:42:01.179094Z","steps":["trace[1931084163] 'process raft request' (duration: 147.997929ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:42:01.179249Z","caller":"traceutil/trace.go:171","msg":"trace[1644962067] linearizableReadLoop","detail":"{readStateIndex:2082; appliedIndex:2079; }","duration":"133.665321ms","start":"2026-10-04T12:42:01.045563Z","end":"2026-10-04T12:42:01.179228Z","steps":["trace[1644962067] 'read index received' (duration: 99.833993ms)","trace[1644962067] 'applied index is now lower than readState.Index' (duration: 33.830558ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:42:01.179533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.961144ms","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-10-04T12:42:01.179573Z","caller":"traceutil/trace.go:171","msg":"trace[1816690362] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2010; }","duration":"134.047625ms","start":"2026-10-04T12:42:01.045512Z","end":"2026-10-04T12:42:01.179559Z","steps":["trace[1816690362] 'agreement among raft nodes before linearized reading' (duration: 133.913193ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:42:18.005127Z","caller":"traceutil/trace.go:171","msg":"trace[1558285272] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"116.550627ms","start":"2026-10-04T12:42:17.888528Z","end":"2026-10-04T12:42:18.005078Z","steps":["trace[1558285272] 'process raft request' (duration: 102.143216ms)","trace[1558285272] 'compare' (duration: 14.255827ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:42:18.324176Z","caller":"traceutil/trace.go:171","msg":"trace[1298687460] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"258.901211ms","start":"2026-10-04T12:42:18.06524Z","end":"2026-10-04T12:42:18.324141Z","steps":["trace[1298687460] 'process raft request' (duration: 188.914061ms)","trace[1298687460] 'compare' (duration: 69.859066ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:42:18.327081Z","caller":"traceutil/trace.go:171","msg":"trace[98683016] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2189; }","duration":"150.301143ms","start":"2026-10-04T12:42:18.176761Z","end":"2026-10-04T12:42:18.327062Z","steps":["trace[98683016] 'read index received' (duration: 77.327038ms)","trace[98683016] 'applied index is now lower than readState.Index' (duration: 72.973335ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:42:18.327151Z","caller":"traceutil/trace.go:171","msg":"trace[1215518257] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"201.834889ms","start":"2026-10-04T12:42:18.125296Z","end":"2026-10-04T12:42:18.327131Z","steps":["trace[1215518257] 'process raft request' (duration: 201.683475ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:42:18.327454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.619733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-04T12:42:18.327518Z","caller":"traceutil/trace.go:171","msg":"trace[2029622449] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2115; }","duration":"150.768427ms","start":"2026-10-04T12:42:18.176732Z","end":"2026-10-04T12:42:18.327501Z","steps":["trace[2029622449] 'agreement among raft nodes before linearized reading' (duration: 150.393656ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:43:34.365427Z","caller":"traceutil/trace.go:171","msg":"trace[472767994] transaction","detail":"{read_only:false; response_revision:2625; number_of_response:1; }","duration":"106.733678ms","start":"2026-10-04T12:43:34.258677Z","end":"2026-10-04T12:43:34.365411Z","steps":["trace[472767994] 'process raft request' (duration: 106.630845ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:48:33.818649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.4874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T12:48:33.818843Z","caller":"traceutil/trace.go:171","msg":"trace[118484388] linearizableReadLoop","detail":"{readStateIndex:5161; appliedIndex:5159; }","duration":"141.360817ms","start":"2026-10-04T12:48:33.677467Z","end":"2026-10-04T12:48:33.818828Z","steps":["trace[118484388] 'read index received' (duration: 11.632474ms)","trace[118484388] 'applied index is now lower than readState.Index' (duration: 129.727223ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:48:33.818913Z","caller":"traceutil/trace.go:171","msg":"trace[1038849194] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"145.928231ms","start":"2026-10-04T12:48:33.672975Z","end":"2026-10-04T12:48:33.818903Z","steps":["trace[1038849194] 'process raft request' (duration: 145.767218ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:48:33.819035Z","caller":"traceutil/trace.go:171","msg":"trace[1050095196] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"151.385496ms","start":"2026-10-04T12:48:33.66762Z","end":"2026-10-04T12:48:33.819005Z","steps":["trace[1050095196] 'process raft request' (duration: 21.459971ms)","trace[1050095196] 'compare' (duration: 129.294167ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:48:33.819359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.738782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-04T12:48:33.819395Z","caller":"traceutil/trace.go:171","msg":"trace[825206060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4974; }","duration":"141.960044ms","start":"2026-10-04T12:48:33.677422Z","end":"2026-10-04T12:48:33.819382Z","steps":["trace[825206060] 'agreement among raft nodes before linearized reading' (duration: 141.68431ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:48:33.978069Z","caller":"traceutil/trace.go:171","msg":"trace[1695802363] linearizableReadLoop","detail":"{readStateIndex:5162; appliedIndex:5161; }","duration":"159.167267ms","start":"2026-10-04T12:48:33.818875Z","end":"2026-10-04T12:48:33.978042Z","steps":["trace[1695802363] 'read index received' (duration: 154.048794ms)","trace[1695802363] 'applied index is now lower than readState.Index' (duration: 5.116803ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:48:33.978131Z","caller":"traceutil/trace.go:171","msg":"trace[1308886575] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"237.165043ms","start":"2026-10-04T12:48:33.740941Z","end":"2026-10-04T12:48:33.978106Z","steps":["trace[1308886575] 'process raft request' (duration: 231.8941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:48:33.978289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.005487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-04T12:48:33.978327Z","caller":"traceutil/trace.go:171","msg":"trace[1318042550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4975; }","duration":"268.058198ms","start":"2026-10-04T12:48:33.710259Z","end":"2026-10-04T12:48:33.978317Z","steps":["trace[1318042550] 'agreement among raft nodes before linearized reading' (duration: 267.904755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:48:33.978318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.455207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-9944dfb5d-56km6\" ","response":"range_response_count:1 size:11252"} {"level":"info","ts":"2026-10-04T12:48:33.978379Z","caller":"traceutil/trace.go:171","msg":"trace[410677319] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-9944dfb5d-56km6; range_end:; response_count:1; response_revision:4975; }","duration":"237.561829ms","start":"2026-10-04T12:48:33.740796Z","end":"2026-10-04T12:48:33.978358Z","steps":["trace[410677319] 'agreement among raft nodes before linearized reading' (duration: 237.317866ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:48:33.982787Z","caller":"traceutil/trace.go:171","msg":"trace[55408056] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"159.447491ms","start":"2026-10-04T12:48:33.823318Z","end":"2026-10-04T12:48:33.982766Z","steps":["trace[55408056] 'process raft request' (duration: 159.292478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:48:33.98312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.5638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-89aa90b0-2e11-4497-8dad-f819fe2ff6c0\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-04T12:48:33.983165Z","caller":"traceutil/trace.go:171","msg":"trace[253600057] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-89aa90b0-2e11-4497-8dad-f819fe2ff6c0; range_end:; response_count:1; response_revision:4977; }","duration":"124.656762ms","start":"2026-10-04T12:48:33.858495Z","end":"2026-10-04T12:48:33.983151Z","steps":["trace[253600057] 'agreement among raft nodes before linearized reading' (duration: 124.46893ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:48:34.365623Z","caller":"traceutil/trace.go:171","msg":"trace[438552810] linearizableReadLoop","detail":"{readStateIndex:5169; appliedIndex:5168; }","duration":"148.865851ms","start":"2026-10-04T12:48:34.216727Z","end":"2026-10-04T12:48:34.365593Z","steps":["trace[438552810] 'read index received' (duration: 90.230587ms)","trace[438552810] 'applied index is now lower than readState.Index' (duration: 58.633624ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:48:34.366059Z","caller":"traceutil/trace.go:171","msg":"trace[1538494541] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"321.633486ms","start":"2026-10-04T12:48:34.044392Z","end":"2026-10-04T12:48:34.366026Z","steps":["trace[1538494541] 'process raft request' (duration: 262.541156ms)","trace[1538494541] 'compare' (duration: 58.44082ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:48:34.36623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:48:34.044377Z","time spent":"321.757218ms","remote":"127.0.0.1:58548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T12:48:34.367147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.396263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-04T12:48:34.367241Z","caller":"traceutil/trace.go:171","msg":"trace[429344551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4982; }","duration":"150.561365ms","start":"2026-10-04T12:48:34.216662Z","end":"2026-10-04T12:48:34.367223Z","steps":["trace[429344551] 'agreement among raft nodes before linearized reading' (duration: 149.042403ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:48:48.879523Z","caller":"traceutil/trace.go:171","msg":"trace[82534854] linearizableReadLoop","detail":"{readStateIndex:5363; appliedIndex:5362; }","duration":"163.857589ms","start":"2026-10-04T12:48:48.715643Z","end":"2026-10-04T12:48:48.879501Z","steps":["trace[82534854] 'read index received' (duration: 154.872134ms)","trace[82534854] 'applied index is now lower than readState.Index' (duration: 8.984575ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:48:48.879692Z","caller":"traceutil/trace.go:171","msg":"trace[741674434] transaction","detail":"{read_only:false; response_revision:5169; number_of_response:1; }","duration":"212.907663ms","start":"2026-10-04T12:48:48.666769Z","end":"2026-10-04T12:48:48.879676Z","steps":["trace[741674434] 'process raft request' (duration: 203.800725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:48:48.879806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.108693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-04T12:48:48.880138Z","caller":"traceutil/trace.go:171","msg":"trace[1545601176] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5169; }","duration":"164.406757ms","start":"2026-10-04T12:48:48.715626Z","end":"2026-10-04T12:48:48.880033Z","steps":["trace[1545601176] 'agreement among raft nodes before linearized reading' (duration: 164.053562ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:48:49.055389Z","caller":"traceutil/trace.go:171","msg":"trace[1920037789] linearizableReadLoop","detail":"{readStateIndex:5364; appliedIndex:5363; }","duration":"112.905726ms","start":"2026-10-04T12:48:48.942459Z","end":"2026-10-04T12:48:49.055364Z","steps":["trace[1920037789] 'read index received' (duration: 112.779204ms)","trace[1920037789] 'applied index is now lower than readState.Index' (duration: 125.402µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:48:49.055858Z","caller":"traceutil/trace.go:171","msg":"trace[358471937] transaction","detail":"{read_only:false; response_revision:5170; number_of_response:1; }","duration":"117.387899ms","start":"2026-10-04T12:48:48.938437Z","end":"2026-10-04T12:48:49.055825Z","steps":["trace[358471937] 'process raft request' (duration: 116.76161ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:48:49.056086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.604656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-10-04T12:48:49.056174Z","caller":"traceutil/trace.go:171","msg":"trace[1634899991] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5170; }","duration":"113.727129ms","start":"2026-10-04T12:48:48.942425Z","end":"2026-10-04T12:48:49.056152Z","steps":["trace[1634899991] 'agreement among raft nodes before linearized reading' (duration: 113.528376ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:49:26.561321Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2908} {"level":"info","ts":"2026-10-04T12:49:26.638934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2908,"took":"76.163279ms","hash":3862760244,"current-db-size-bytes":53911552,"current-db-size":"54 MB","current-db-size-in-use-bytes":28651520,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-04T12:49:26.639026Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3862760244,"revision":2908,"compact-revision":-1} {"level":"info","ts":"2026-10-04T12:54:26.568818Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5492} {"level":"info","ts":"2026-10-04T12:54:26.682369Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5492,"took":"112.340938ms","hash":4270271060,"current-db-size-bytes":53911552,"current-db-size":"54 MB","current-db-size-in-use-bytes":18731008,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-04T12:54:26.682425Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4270271060,"revision":5492,"compact-revision":2908}