{"level":"warn","ts":"2026-09-01T18:25:54.820946Z","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-01T18:25:54.821058Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.84: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.84:2380","--initial-cluster=instance=https://162.253.55.84:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.84:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.84: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-01T18:25:54.821134Z","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-01T18:25:54.821151Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-09-01T18:25:54.821173Z","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-01T18:25:54.823016Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"]} {"level":"info","ts":"2026-09-01T18:25:54.823104Z","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.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.84: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-01T18:25:54.830146Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.798965ms"} {"level":"info","ts":"2026-09-01T18:25:54.83954Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca4c710a8d5349d7","cluster-id":"8e28a51debf1c8ab"} {"level":"info","ts":"2026-09-01T18:25:54.839642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T18:25:54.839665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 0"} {"level":"info","ts":"2026-09-01T18:25:54.839678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca4c710a8d5349d7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T18:25:54.839685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 1"} {"level":"info","ts":"2026-09-01T18:25:54.839849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"warn","ts":"2026-09-01T18:25:54.858582Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T18:25:54.860212Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T18:25:54.863954Z","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-01T18:25:54.866069Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca4c710a8d5349d7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T18:25:54.870771Z","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-01T18:25:54.870877Z","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-01T18:25:54.870909Z","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-01T18:25:54.870801Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca4c710a8d5349d7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T18:25:54.871618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"info","ts":"2026-09-01T18:25:54.871966Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","added-peer-id":"ca4c710a8d5349d7","added-peer-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-09-01T18:25:54.879639Z","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-01T18:25:54.879793Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-09-01T18:25:54.879839Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-09-01T18:25:54.879963Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca4c710a8d5349d7","initial-advertise-peer-urls":["https://162.253.55.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T18:25:54.880014Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T18:25:55.740792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T18:25:55.740898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T18:25:55.740916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgPreVoteResp from ca4c710a8d5349d7 at term 1"} {"level":"info","ts":"2026-09-01T18:25:55.740933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became candidate at term 2"} {"level":"info","ts":"2026-09-01T18:25:55.740939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgVoteResp from ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-09-01T18:25:55.74095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became leader at term 2"} {"level":"info","ts":"2026-09-01T18:25:55.740959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca4c710a8d5349d7 elected leader ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-09-01T18:25:55.74179Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:25:55.742699Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T18:25:55.7427Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca4c710a8d5349d7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.84:2379]}","request-path":"/0/members/ca4c710a8d5349d7/attributes","cluster-id":"8e28a51debf1c8ab","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T18:25:55.742724Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T18:25:55.743056Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T18:25:55.743082Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T18:25:55.74351Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:25:55.743793Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:25:55.743831Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:25:55.746384Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.84:2379"} {"level":"info","ts":"2026-09-01T18:25:55.746534Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} 2026/09/01 18:26:00 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-09-01T18:26:35.240829Z","caller":"traceutil/trace.go:171","msg":"trace[72456090] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"133.494868ms","start":"2026-09-01T18:26:35.107312Z","end":"2026-09-01T18:26:35.240807Z","steps":["trace[72456090] 'process raft request' (duration: 133.260163ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:26:35.248761Z","caller":"traceutil/trace.go:171","msg":"trace[1563626041] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"140.937911ms","start":"2026-09-01T18:26:35.1077Z","end":"2026-09-01T18:26:35.248638Z","steps":["trace[1563626041] 'process raft request' (duration: 140.317858ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:26:35.248804Z","caller":"traceutil/trace.go:171","msg":"trace[124139712] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"141.143344ms","start":"2026-09-01T18:26:35.107624Z","end":"2026-09-01T18:26:35.248767Z","steps":["trace[124139712] 'process raft request' (duration: 140.329007ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:26:42.018041Z","caller":"traceutil/trace.go:171","msg":"trace[1023760617] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"368.103576ms","start":"2026-09-01T18:26:41.649887Z","end":"2026-09-01T18:26:42.017991Z","steps":["trace[1023760617] 'process raft request' (duration: 366.555404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:42.019031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:26:41.649846Z","time spent":"368.30089ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:26:42.586702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.231194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:26:42.587077Z","caller":"traceutil/trace.go:171","msg":"trace[2053542868] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"546.388ms","start":"2026-09-01T18:26:42.040601Z","end":"2026-09-01T18:26:42.586989Z","steps":["trace[2053542868] 'process raft request' (duration: 274.135405ms)","trace[2053542868] 'compare' (duration: 270.866756ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:26:42.587174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:26:42.040564Z","time spent":"546.552954ms","remote":"127.0.0.1:58894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:26:42.58808Z","caller":"traceutil/trace.go:171","msg":"trace[1710323722] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"479.60041ms","start":"2026-09-01T18:26:42.108396Z","end":"2026-09-01T18:26:42.587996Z","steps":["trace[1710323722] 'read index received' (duration: 206.356644ms)","trace[1710323722] 'applied index is now lower than readState.Index' (duration: 273.241366ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:26:42.588839Z","caller":"traceutil/trace.go:171","msg":"trace[3535790] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"481.95527ms","start":"2026-09-01T18:26:42.106865Z","end":"2026-09-01T18:26:42.58882Z","steps":["trace[3535790] 'process raft request' (duration: 480.906217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:42.588965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:26:42.106799Z","time spent":"482.074252ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-01T18:26:42.589405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.005909ms","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-01T18:26:42.589458Z","caller":"traceutil/trace.go:171","msg":"trace[1729407219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"481.081741ms","start":"2026-09-01T18:26:42.108348Z","end":"2026-09-01T18:26:42.58943Z","steps":["trace[1729407219] 'agreement among raft nodes before linearized reading' (duration: 480.841706ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:42.589487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:26:42.108335Z","time spent":"481.145222ms","remote":"127.0.0.1:58980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-01T18:26:42.590122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.236585ms","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-01T18:26:42.590268Z","caller":"traceutil/trace.go:171","msg":"trace[1599671914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"355.426969ms","start":"2026-09-01T18:26:42.234796Z","end":"2026-09-01T18:26:42.590223Z","steps":["trace[1599671914] 'agreement among raft nodes before linearized reading' (duration: 355.108242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:42.59032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:26:42.234767Z","time spent":"355.5412ms","remote":"127.0.0.1:58980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-01T18:26:42.590488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.090663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:26:42.590541Z","caller":"traceutil/trace.go:171","msg":"trace[253416682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"336.151474ms","start":"2026-09-01T18:26:42.254364Z","end":"2026-09-01T18:26:42.590516Z","steps":["trace[253416682] 'agreement among raft nodes before linearized reading' (duration: 336.073703ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:42.59057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:26:42.254353Z","time spent":"336.208475ms","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-01T18:27:30.306517Z","caller":"traceutil/trace.go:171","msg":"trace[292168078] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"280.343416ms","start":"2026-09-01T18:27:30.026137Z","end":"2026-09-01T18:27:30.30648Z","steps":["trace[292168078] 'read index received' (duration: 279.619063ms)","trace[292168078] 'applied index is now lower than readState.Index' (duration: 722.223µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:27:30.306877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.6463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T18:27:30.306622Z","caller":"traceutil/trace.go:171","msg":"trace[760357478] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"288.595385ms","start":"2026-09-01T18:27:30.017971Z","end":"2026-09-01T18:27:30.306566Z","steps":["trace[760357478] 'process raft request' (duration: 288.233659ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:30.307271Z","caller":"traceutil/trace.go:171","msg":"trace[1137534485] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1213; }","duration":"281.134929ms","start":"2026-09-01T18:27:30.026101Z","end":"2026-09-01T18:27:30.307236Z","steps":["trace[1137534485] 'agreement among raft nodes before linearized reading' (duration: 280.580919ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:56.79512Z","caller":"traceutil/trace.go:171","msg":"trace[2102797784] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"191.737818ms","start":"2026-09-01T18:27:56.603346Z","end":"2026-09-01T18:27:56.795084Z","steps":["trace[2102797784] 'process raft request' (duration: 170.221741ms)","trace[2102797784] 'compare' (duration: 21.270303ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:28:05.425638Z","caller":"traceutil/trace.go:171","msg":"trace[686603735] transaction","detail":"{read_only:false; response_revision:1599; number_of_response:1; }","duration":"105.636419ms","start":"2026-09-01T18:28:05.319969Z","end":"2026-09-01T18:28:05.425605Z","steps":["trace[686603735] 'process raft request' (duration: 82.836089ms)","trace[686603735] 'compare' (duration: 22.640507ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:28:24.13627Z","caller":"traceutil/trace.go:171","msg":"trace[237945557] linearizableReadLoop","detail":"{readStateIndex:1997; appliedIndex:1996; }","duration":"107.951805ms","start":"2026-09-01T18:28:24.028286Z","end":"2026-09-01T18:28:24.136237Z","steps":["trace[237945557] 'read index received' (duration: 107.674761ms)","trace[237945557] 'applied index is now lower than readState.Index' (duration: 275.834µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:28:24.136699Z","caller":"traceutil/trace.go:171","msg":"trace[1197309171] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"146.284823ms","start":"2026-09-01T18:28:23.990396Z","end":"2026-09-01T18:28:24.136681Z","steps":["trace[1197309171] 'process raft request' (duration: 145.661763ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:24.137715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.392176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9cda78ee-b117-4e5a-8bf6-f9ae20eea4b2\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-01T18:28:24.137802Z","caller":"traceutil/trace.go:171","msg":"trace[512369307] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9cda78ee-b117-4e5a-8bf6-f9ae20eea4b2; range_end:; response_count:1; response_revision:1919; }","duration":"109.530249ms","start":"2026-09-01T18:28:24.028247Z","end":"2026-09-01T18:28:24.137777Z","steps":["trace[512369307] 'agreement among raft nodes before linearized reading' (duration: 108.626305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:24.490018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.839913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:28:24.490547Z","caller":"traceutil/trace.go:171","msg":"trace[1762697298] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"196.702978ms","start":"2026-09-01T18:28:24.293818Z","end":"2026-09-01T18:28:24.490521Z","steps":["trace[1762697298] 'process raft request' (duration: 196.580996ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:28:24.490709Z","caller":"traceutil/trace.go:171","msg":"trace[1071660171] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"273.877148ms","start":"2026-09-01T18:28:24.216793Z","end":"2026-09-01T18:28:24.49067Z","steps":["trace[1071660171] 'process raft request' (duration: 163.225042ms)","trace[1071660171] 'compare' (duration: 109.633521ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:28:24.490638Z","caller":"traceutil/trace.go:171","msg":"trace[1418840837] linearizableReadLoop","detail":"{readStateIndex:1998; appliedIndex:1997; }","duration":"235.855097ms","start":"2026-09-01T18:28:24.254758Z","end":"2026-09-01T18:28:24.490613Z","steps":["trace[1418840837] 'read index received' (duration: 125.16177ms)","trace[1418840837] 'applied index is now lower than readState.Index' (duration: 110.691717ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:28:24.491001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.232052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:28:24.491812Z","caller":"traceutil/trace.go:171","msg":"trace[1587496781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1921; }","duration":"236.402213ms","start":"2026-09-01T18:28:24.254687Z","end":"2026-09-01T18:28:24.49109Z","steps":["trace[1587496781] 'agreement among raft nodes before linearized reading' (duration: 236.051009ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:25.273588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.974646ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-01T18:28:25.273964Z","caller":"traceutil/trace.go:171","msg":"trace[967914260] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"369.581316ms","start":"2026-09-01T18:28:24.904324Z","end":"2026-09-01T18:28:25.273906Z","steps":["trace[967914260] 'process raft request' (duration: 369.384613ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:28:25.273986Z","caller":"traceutil/trace.go:171","msg":"trace[37353889] linearizableReadLoop","detail":"{readStateIndex:2001; appliedIndex:2000; }","duration":"634.649564ms","start":"2026-09-01T18:28:24.639233Z","end":"2026-09-01T18:28:25.273882Z","steps":["trace[37353889] 'read index received' (duration: 62.321µs)","trace[37353889] 'applied index is now lower than readState.Index' (duration: 634.583723ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:28:25.274113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:28:24.904296Z","time spent":"369.724868ms","remote":"127.0.0.1:58980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:28:25.27433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.09109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9cda78ee-b117-4e5a-8bf6-f9ae20eea4b2\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-01T18:28:25.274372Z","caller":"traceutil/trace.go:171","msg":"trace[1691076297] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9cda78ee-b117-4e5a-8bf6-f9ae20eea4b2; range_end:; response_count:1; response_revision:1923; }","duration":"635.196172ms","start":"2026-09-01T18:28:24.63916Z","end":"2026-09-01T18:28:25.274356Z","steps":["trace[1691076297] 'agreement among raft nodes before linearized reading' (duration: 634.969839ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:25.274415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:28:24.639133Z","time spent":"635.267983ms","remote":"127.0.0.1:58894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9cda78ee-b117-4e5a-8bf6-f9ae20eea4b2\" "} {"level":"warn","ts":"2026-09-01T18:28:25.274495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.114617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-01T18:28:25.274564Z","caller":"traceutil/trace.go:171","msg":"trace[994490786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1923; }","duration":"513.236729ms","start":"2026-09-01T18:28:24.761312Z","end":"2026-09-01T18:28:25.274549Z","steps":["trace[994490786] 'agreement among raft nodes before linearized reading' (duration: 513.060936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:25.274593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:28:24.761291Z","time spent":"513.29774ms","remote":"127.0.0.1:58980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-01T18:28:25.274806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.506261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9cda78ee-b117-4e5a-8bf6-f9ae20eea4b2\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-01T18:28:25.274849Z","caller":"traceutil/trace.go:171","msg":"trace[1620819963] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9cda78ee-b117-4e5a-8bf6-f9ae20eea4b2; range_end:; response_count:1; response_revision:1923; }","duration":"131.594312ms","start":"2026-09-01T18:28:25.143244Z","end":"2026-09-01T18:28:25.274838Z","steps":["trace[1620819963] 'agreement among raft nodes before linearized reading' (duration: 131.457841ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:25.274877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.056189ms","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-01T18:28:25.274961Z","caller":"traceutil/trace.go:171","msg":"trace[63715671] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1923; }","duration":"296.14473ms","start":"2026-09-01T18:28:24.978803Z","end":"2026-09-01T18:28:25.274948Z","steps":["trace[63715671] 'agreement among raft nodes before linearized reading' (duration: 295.914377ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:04.888597Z","caller":"traceutil/trace.go:171","msg":"trace[1700791380] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"178.038263ms","start":"2026-09-01T18:29:04.710526Z","end":"2026-09-01T18:29:04.888564Z","steps":["trace[1700791380] 'process raft request' (duration: 172.13408ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:05.626471Z","caller":"traceutil/trace.go:171","msg":"trace[677778555] linearizableReadLoop","detail":"{readStateIndex:2281; appliedIndex:2280; }","duration":"163.393774ms","start":"2026-09-01T18:29:05.463057Z","end":"2026-09-01T18:29:05.626451Z","steps":["trace[677778555] 'read index received' (duration: 163.295503ms)","trace[677778555] 'applied index is now lower than readState.Index' (duration: 97.341µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:29:05.626514Z","caller":"traceutil/trace.go:171","msg":"trace[427759198] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"166.274255ms","start":"2026-09-01T18:29:05.46022Z","end":"2026-09-01T18:29:05.626494Z","steps":["trace[427759198] 'process raft request' (duration: 166.115573ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:05.626857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.78603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-01T18:29:05.62693Z","caller":"traceutil/trace.go:171","msg":"trace[204786022] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2192; }","duration":"163.896012ms","start":"2026-09-01T18:29:05.463021Z","end":"2026-09-01T18:29:05.626917Z","steps":["trace[204786022] 'agreement among raft nodes before linearized reading' (duration: 163.553338ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:05.795296Z","caller":"traceutil/trace.go:171","msg":"trace[1368989801] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"162.130206ms","start":"2026-09-01T18:29:05.633137Z","end":"2026-09-01T18:29:05.795267Z","steps":["trace[1368989801] 'process raft request' (duration: 161.996185ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:05.795423Z","caller":"traceutil/trace.go:171","msg":"trace[1802251560] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"161.424887ms","start":"2026-09-01T18:29:05.633974Z","end":"2026-09-01T18:29:05.795399Z","steps":["trace[1802251560] 'process raft request' (duration: 161.344596ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:05.795573Z","caller":"traceutil/trace.go:171","msg":"trace[608312332] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"162.981639ms","start":"2026-09-01T18:29:05.632565Z","end":"2026-09-01T18:29:05.795546Z","steps":["trace[608312332] 'process raft request' (duration: 93.830737ms)","trace[608312332] 'compare' (duration: 68.586194ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:29:05.795788Z","caller":"traceutil/trace.go:171","msg":"trace[1331401175] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"161.851863ms","start":"2026-09-01T18:29:05.633862Z","end":"2026-09-01T18:29:05.795714Z","steps":["trace[1331401175] 'process raft request' (duration: 161.354706ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:30:32.404117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.983712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:30:32.404301Z","caller":"traceutil/trace.go:171","msg":"trace[2037846643] linearizableReadLoop","detail":"{readStateIndex:2877; appliedIndex:2876; }","duration":"133.880059ms","start":"2026-09-01T18:30:32.270402Z","end":"2026-09-01T18:30:32.404282Z","steps":["trace[2037846643] 'read index received' (duration: 13.582672ms)","trace[2037846643] 'applied index is now lower than readState.Index' (duration: 120.296217ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:30:32.404746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.394975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-01T18:30:32.405106Z","caller":"traceutil/trace.go:171","msg":"trace[1789756996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2769; }","duration":"116.79289ms","start":"2026-09-01T18:30:32.2883Z","end":"2026-09-01T18:30:32.405093Z","steps":["trace[1789756996] 'agreement among raft nodes before linearized reading' (duration: 116.283592ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:30:32.404806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.376916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-01T18:30:32.405706Z","caller":"traceutil/trace.go:171","msg":"trace[145733180] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2769; }","duration":"135.333799ms","start":"2026-09-01T18:30:32.270358Z","end":"2026-09-01T18:30:32.405692Z","steps":["trace[145733180] 'agreement among raft nodes before linearized reading' (duration: 134.202903ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:32.404892Z","caller":"traceutil/trace.go:171","msg":"trace[1137492600] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"247.290913ms","start":"2026-09-01T18:30:32.157579Z","end":"2026-09-01T18:30:32.40487Z","steps":["trace[1137492600] 'process raft request' (duration: 126.466069ms)","trace[1137492600] 'compare' (duration: 119.847421ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:30:32.405009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.662824ms","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-01T18:30:32.406149Z","caller":"traceutil/trace.go:171","msg":"trace[316557024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2769; }","duration":"113.792349ms","start":"2026-09-01T18:30:32.292312Z","end":"2026-09-01T18:30:32.406105Z","steps":["trace[316557024] 'agreement among raft nodes before linearized reading' (duration: 112.581613ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:32.545038Z","caller":"traceutil/trace.go:171","msg":"trace[1566603396] transaction","detail":"{read_only:false; response_revision:2770; number_of_response:1; }","duration":"129.058564ms","start":"2026-09-01T18:30:32.415952Z","end":"2026-09-01T18:30:32.545011Z","steps":["trace[1566603396] 'process raft request' (duration: 108.952493ms)","trace[1566603396] 'compare' (duration: 20.01087ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:30:32.545519Z","caller":"traceutil/trace.go:171","msg":"trace[821031066] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"127.559874ms","start":"2026-09-01T18:30:32.417946Z","end":"2026-09-01T18:30:32.545506Z","steps":["trace[821031066] 'process raft request' (duration: 127.505173ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:32.545718Z","caller":"traceutil/trace.go:171","msg":"trace[68900621] transaction","detail":"{read_only:false; response_revision:2771; number_of_response:1; }","duration":"127.848568ms","start":"2026-09-01T18:30:32.417857Z","end":"2026-09-01T18:30:32.545706Z","steps":["trace[68900621] 'process raft request' (duration: 127.519383ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:09.125969Z","caller":"traceutil/trace.go:171","msg":"trace[470383013] linearizableReadLoop","detail":"{readStateIndex:5046; appliedIndex:5045; }","duration":"102.703409ms","start":"2026-09-01T18:35:09.023246Z","end":"2026-09-01T18:35:09.125949Z","steps":["trace[470383013] 'read index received' (duration: 102.513366ms)","trace[470383013] 'applied index is now lower than readState.Index' (duration: 189.403µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:35:09.126311Z","caller":"traceutil/trace.go:171","msg":"trace[1466297447] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"163.9066ms","start":"2026-09-01T18:35:08.96237Z","end":"2026-09-01T18:35:09.126277Z","steps":["trace[1466297447] 'process raft request' (duration: 163.481134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:09.126484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.913882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-09-01T18:35:09.126535Z","caller":"traceutil/trace.go:171","msg":"trace[785901666] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4856; }","duration":"103.299476ms","start":"2026-09-01T18:35:09.023222Z","end":"2026-09-01T18:35:09.126522Z","steps":["trace[785901666] 'agreement among raft nodes before linearized reading' (duration: 102.81672ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:15.074057Z","caller":"traceutil/trace.go:171","msg":"trace[138721907] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4956; }","duration":"112.006983ms","start":"2026-09-01T18:35:14.962024Z","end":"2026-09-01T18:35:15.074031Z","steps":["trace[138721907] 'process raft request' (duration: 111.957602ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:15.074336Z","caller":"traceutil/trace.go:171","msg":"trace[2033084802] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"118.60758ms","start":"2026-09-01T18:35:14.95571Z","end":"2026-09-01T18:35:15.074318Z","steps":["trace[2033084802] 'process raft request' (duration: 60.072895ms)","trace[2033084802] 'compare' (duration: 57.991868ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:35:15.07454Z","caller":"traceutil/trace.go:171","msg":"trace[698632762] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5151; }","duration":"116.719755ms","start":"2026-09-01T18:35:14.957763Z","end":"2026-09-01T18:35:15.074482Z","steps":["trace[698632762] 'read index received' (duration: 58.12119ms)","trace[698632762] 'applied index is now lower than readState.Index' (duration: 58.596475ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:35:15.074863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.06547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-01T18:35:15.074901Z","caller":"traceutil/trace.go:171","msg":"trace[1888348215] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4956; }","duration":"117.156961ms","start":"2026-09-01T18:35:14.95773Z","end":"2026-09-01T18:35:15.074887Z","steps":["trace[1888348215] 'agreement among raft nodes before linearized reading' (duration: 116.965949ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:34.559647Z","caller":"traceutil/trace.go:171","msg":"trace[1196047130] transaction","detail":"{read_only:false; response_revision:5231; number_of_response:1; }","duration":"143.347918ms","start":"2026-09-01T18:35:34.416272Z","end":"2026-09-01T18:35:34.55962Z","steps":["trace[1196047130] 'process raft request' (duration: 143.165476ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:35.994054Z","caller":"traceutil/trace.go:171","msg":"trace[746930264] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"146.753642ms","start":"2026-09-01T18:35:35.847275Z","end":"2026-09-01T18:35:35.994029Z","steps":["trace[746930264] 'process raft request' (duration: 146.544059ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:36.206618Z","caller":"traceutil/trace.go:171","msg":"trace[1356364677] linearizableReadLoop","detail":"{readStateIndex:5440; appliedIndex:5439; }","duration":"191.390393ms","start":"2026-09-01T18:35:36.015186Z","end":"2026-09-01T18:35:36.206577Z","steps":["trace[1356364677] 'read index received' (duration: 146.998925ms)","trace[1356364677] 'applied index is now lower than readState.Index' (duration: 44.389398ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:35:36.207522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.328975ms","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-09-01T18:35:36.207635Z","caller":"traceutil/trace.go:171","msg":"trace[2089032024] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"211.58818ms","start":"2026-09-01T18:35:35.995987Z","end":"2026-09-01T18:35:36.207575Z","steps":["trace[2089032024] 'process raft request' (duration: 166.26037ms)","trace[2089032024] 'compare' (duration: 43.949011ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:35:36.207681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.678147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-01T18:35:36.207787Z","caller":"traceutil/trace.go:171","msg":"trace[1669959289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5236; }","duration":"126.840138ms","start":"2026-09-01T18:35:36.080917Z","end":"2026-09-01T18:35:36.207757Z","steps":["trace[1669959289] 'agreement among raft nodes before linearized reading' (duration: 126.497154ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:36.207689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.25142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-01T18:35:36.207871Z","caller":"traceutil/trace.go:171","msg":"trace[1094049101] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5236; }","duration":"104.447042ms","start":"2026-09-01T18:35:36.103395Z","end":"2026-09-01T18:35:36.207842Z","steps":["trace[1094049101] 'agreement among raft nodes before linearized reading' (duration: 104.039087ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:36.207957Z","caller":"traceutil/trace.go:171","msg":"trace[209271990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5236; }","duration":"192.770751ms","start":"2026-09-01T18:35:36.015103Z","end":"2026-09-01T18:35:36.207874Z","steps":["trace[209271990] 'agreement among raft nodes before linearized reading' (duration: 192.222994ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:36.208039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.787102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-01T18:35:36.208235Z","caller":"traceutil/trace.go:171","msg":"trace[861213706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5236; }","duration":"109.994236ms","start":"2026-09-01T18:35:36.098223Z","end":"2026-09-01T18:35:36.208217Z","steps":["trace[861213706] 'agreement among raft nodes before linearized reading' (duration: 109.741432ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:55.786652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2894} {"level":"info","ts":"2026-09-01T18:35:55.871518Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2894,"took":"83.14934ms","hash":2629364845,"current-db-size-bytes":52375552,"current-db-size":"52 MB","current-db-size-in-use-bytes":27652096,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T18:35:55.871638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2629364845,"revision":2894,"compact-revision":-1} {"level":"info","ts":"2026-09-01T18:40:55.793173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5385} {"level":"info","ts":"2026-09-01T18:40:55.888394Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5385,"took":"93.628543ms","hash":1637108447,"current-db-size-bytes":52375552,"current-db-size":"52 MB","current-db-size-in-use-bytes":18788352,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-01T18:40:55.888471Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1637108447,"revision":5385,"compact-revision":2894}