{"level":"warn","ts":"2026-05-29T21:58:04.35873Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T21:58:04.358815Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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://199.204.45.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-05-29T21:58:04.358875Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T21:58:04.358888Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-05-29T21:58:04.358902Z","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-05-29T21:58:04.359504Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-05-29T21:58:04.359637Z","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://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-05-29T21:58:04.363784Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.955196ms"} {"level":"info","ts":"2026-05-29T21:58:04.369765Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-05-29T21:58:04.369841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T21:58:04.369864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-05-29T21:58:04.369991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T21:58:04.37002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-05-29T21:58:04.370056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-05-29T21:58:04.376619Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T21:58:04.377726Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T21:58:04.378699Z","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-05-29T21:58:04.381628Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T21:58:04.381798Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T21:58:04.381876Z","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-05-29T21:58:04.381925Z","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-05-29T21:58:04.381935Z","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-05-29T21:58:04.387905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-05-29T21:58:04.388842Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-05-29T21:58:04.389731Z","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-05-29T21:58:04.389923Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T21:58:04.389923Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-05-29T21:58:04.389946Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T21:58:04.389948Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-05-29T21:58:04.771167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T21:58:04.771238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T21:58:04.771254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-05-29T21:58:04.771268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-05-29T21:58:04.771275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-05-29T21:58:04.771285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-05-29T21:58:04.771294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-05-29T21:58:04.773544Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T21:58:04.77438Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T21:58:04.774398Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T21:58:04.774839Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:58:04.778001Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T21:58:04.779151Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T21:58:04.779201Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T21:58:04.779469Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:58:04.779561Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:58:04.779599Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:58:04.78223Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-05-29T21:58:09.502889Z","caller":"traceutil/trace.go:171","msg":"trace[1579996897] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"115.842002ms","start":"2026-05-29T21:58:09.387018Z","end":"2026-05-29T21:58:09.50286Z","steps":["trace[1579996897] 'process raft request' (duration: 115.477623ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:09.682111Z","caller":"traceutil/trace.go:171","msg":"trace[627633371] linearizableReadLoop","detail":"{readStateIndex:238; appliedIndex:237; }","duration":"151.853725ms","start":"2026-05-29T21:58:09.530233Z","end":"2026-05-29T21:58:09.682087Z","steps":["trace[627633371] 'read index received' (duration: 123.404994ms)","trace[627633371] 'applied index is now lower than readState.Index' (duration: 28.447721ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:58:09.6822Z","caller":"traceutil/trace.go:171","msg":"trace[118331727] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"167.046254ms","start":"2026-05-29T21:58:09.515129Z","end":"2026-05-29T21:58:09.682175Z","steps":["trace[118331727] 'process raft request' (duration: 138.451459ms)","trace[118331727] 'compare' (duration: 28.373629ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:58:09.682259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.921024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-05-29T21:58:09.682247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.623923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:58:09.682346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.029002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:58:09.682465Z","caller":"traceutil/trace.go:171","msg":"trace[602263998] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:232; }","duration":"174.205827ms","start":"2026-05-29T21:58:09.508226Z","end":"2026-05-29T21:58:09.682432Z","steps":["trace[602263998] 'agreement among raft nodes before linearized reading' (duration: 174.012892ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:09.682473Z","caller":"traceutil/trace.go:171","msg":"trace[799868133] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:232; }","duration":"166.832808ms","start":"2026-05-29T21:58:09.515574Z","end":"2026-05-29T21:58:09.682407Z","steps":["trace[799868133] 'agreement among raft nodes before linearized reading' (duration: 166.533561ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:09.682324Z","caller":"traceutil/trace.go:171","msg":"trace[1218956239] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:232; }","duration":"168.017987ms","start":"2026-05-29T21:58:09.514294Z","end":"2026-05-29T21:58:09.682312Z","steps":["trace[1218956239] 'agreement among raft nodes before linearized reading' (duration: 167.903664ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:09.835932Z","caller":"traceutil/trace.go:171","msg":"trace[236947546] linearizableReadLoop","detail":"{readStateIndex:245; appliedIndex:238; }","duration":"153.73473ms","start":"2026-05-29T21:58:09.682174Z","end":"2026-05-29T21:58:09.835909Z","steps":["trace[236947546] 'read index received' (duration: 153.457943ms)","trace[236947546] 'applied index is now lower than readState.Index' (duration: 275.907µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:58:09.836036Z","caller":"traceutil/trace.go:171","msg":"trace[1147971269] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"203.529059ms","start":"2026-05-29T21:58:09.632497Z","end":"2026-05-29T21:58:09.836026Z","steps":["trace[1147971269] 'process raft request' (duration: 203.383316ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:09.836058Z","caller":"traceutil/trace.go:171","msg":"trace[1526415721] transaction","detail":"{read_only:false; number_of_response:0; response_revision:233; }","duration":"217.521439ms","start":"2026-05-29T21:58:09.618523Z","end":"2026-05-29T21:58:09.836045Z","steps":["trace[1526415721] 'process raft request' (duration: 217.21012ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:09.836078Z","caller":"traceutil/trace.go:171","msg":"trace[976995712] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"203.896818ms","start":"2026-05-29T21:58:09.632136Z","end":"2026-05-29T21:58:09.836033Z","steps":["trace[976995712] 'process raft request' (duration: 203.713474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:09.836192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.826582ms","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-05-29T21:58:09.83621Z","caller":"traceutil/trace.go:171","msg":"trace[745167290] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"205.189969ms","start":"2026-05-29T21:58:09.631012Z","end":"2026-05-29T21:58:09.836202Z","steps":["trace[745167290] 'process raft request' (duration: 204.754388ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:09.836243Z","caller":"traceutil/trace.go:171","msg":"trace[585483527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:238; }","duration":"303.914155ms","start":"2026-05-29T21:58:09.532316Z","end":"2026-05-29T21:58:09.83623Z","steps":["trace[585483527] 'agreement among raft nodes before linearized reading' (duration: 303.749641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:09.836272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:09.532302Z","time spent":"303.964046ms","remote":"127.0.0.1:41988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T21:58:09.836362Z","caller":"traceutil/trace.go:171","msg":"trace[1655819885] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"205.283052ms","start":"2026-05-29T21:58:09.631063Z","end":"2026-05-29T21:58:09.836346Z","steps":["trace[1655819885] 'process raft request' (duration: 204.740889ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:09.836511Z","caller":"traceutil/trace.go:171","msg":"trace[747704864] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"299.727533ms","start":"2026-05-29T21:58:09.536085Z","end":"2026-05-29T21:58:09.835812Z","steps":["trace[747704864] 'process raft request' (duration: 299.526357ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:09.836627Z","caller":"traceutil/trace.go:171","msg":"trace[1314277939] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"204.840291ms","start":"2026-05-29T21:58:09.631118Z","end":"2026-05-29T21:58:09.835959Z","steps":["trace[1314277939] 'process raft request' (duration: 204.707678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:09.837083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:09.536073Z","time spent":"300.479481ms","remote":"127.0.0.1:41940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:58:09.929305Z","caller":"traceutil/trace.go:171","msg":"trace[775141831] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"243.070008ms","start":"2026-05-29T21:58:09.686212Z","end":"2026-05-29T21:58:09.929282Z","steps":["trace[775141831] 'process raft request' (duration: 240.25562ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:09.929332Z","caller":"traceutil/trace.go:171","msg":"trace[743265869] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"242.266999ms","start":"2026-05-29T21:58:09.687035Z","end":"2026-05-29T21:58:09.929302Z","steps":["trace[743265869] 'process raft request' (duration: 242.158596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:09.929603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.618182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-29T21:58:09.929643Z","caller":"traceutil/trace.go:171","msg":"trace[826671579] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:241; }","duration":"243.685253ms","start":"2026-05-29T21:58:09.685949Z","end":"2026-05-29T21:58:09.929634Z","steps":["trace[826671579] 'agreement among raft nodes before linearized reading' (duration: 243.609911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:09.929853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.044182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-29T21:58:09.929915Z","caller":"traceutil/trace.go:171","msg":"trace[247321322] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:241; }","duration":"244.160984ms","start":"2026-05-29T21:58:09.685741Z","end":"2026-05-29T21:58:09.929902Z","steps":["trace[247321322] 'agreement among raft nodes before linearized reading' (duration: 243.927519ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:40.623Z","caller":"traceutil/trace.go:171","msg":"trace[814666434] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"148.133375ms","start":"2026-05-29T21:58:40.474829Z","end":"2026-05-29T21:58:40.622963Z","steps":["trace[814666434] 'process raft request' (duration: 132.933126ms)","trace[814666434] 'compare' (duration: 14.628364ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:58:40.623128Z","caller":"traceutil/trace.go:171","msg":"trace[457595767] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"145.385777ms","start":"2026-05-29T21:58:40.477722Z","end":"2026-05-29T21:58:40.623108Z","steps":["trace[457595767] 'process raft request' (duration: 145.187102ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:40.683482Z","caller":"traceutil/trace.go:171","msg":"trace[1863903179] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:574; }","duration":"150.307567ms","start":"2026-05-29T21:58:40.533159Z","end":"2026-05-29T21:58:40.683466Z","steps":["trace[1863903179] 'read index received' (duration: 74.58985ms)","trace[1863903179] 'applied index is now lower than readState.Index' (duration: 75.717027ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:58:40.683592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.42186ms","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-05-29T21:58:40.683611Z","caller":"traceutil/trace.go:171","msg":"trace[868066055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:554; }","duration":"150.496182ms","start":"2026-05-29T21:58:40.533111Z","end":"2026-05-29T21:58:40.683607Z","steps":["trace[868066055] 'agreement among raft nodes before linearized reading' (duration: 150.41956ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:40.683837Z","caller":"traceutil/trace.go:171","msg":"trace[332707836] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"201.160231ms","start":"2026-05-29T21:58:40.482671Z","end":"2026-05-29T21:58:40.683832Z","steps":["trace[332707836] 'process raft request' (duration: 200.662529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:40.984296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.866635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:58:40.984579Z","caller":"traceutil/trace.go:171","msg":"trace[552853312] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"310.791711ms","start":"2026-05-29T21:58:40.673735Z","end":"2026-05-29T21:58:40.984527Z","steps":["trace[552853312] 'process raft request' (duration: 193.382362ms)","trace[552853312] 'compare' (duration: 116.746762ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:58:40.984789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:40.673718Z","time spent":"310.957486ms","remote":"127.0.0.1:41758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:58:40.984894Z","caller":"traceutil/trace.go:171","msg":"trace[140922593] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"278.596691ms","start":"2026-05-29T21:58:40.706276Z","end":"2026-05-29T21:58:40.984872Z","steps":["trace[140922593] 'process raft request' (duration: 278.123749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:40.985044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.909457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-05-29T21:58:40.984896Z","caller":"traceutil/trace.go:171","msg":"trace[806916673] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:577; }","duration":"266.754304ms","start":"2026-05-29T21:58:40.718128Z","end":"2026-05-29T21:58:40.984883Z","steps":["trace[806916673] 'read index received' (duration: 149.077798ms)","trace[806916673] 'applied index is now lower than readState.Index' (duration: 117.675716ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:58:40.985079Z","caller":"traceutil/trace.go:171","msg":"trace[37429610] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:557; }","duration":"266.962018ms","start":"2026-05-29T21:58:40.718106Z","end":"2026-05-29T21:58:40.985068Z","steps":["trace[37429610] 'agreement among raft nodes before linearized reading' (duration: 266.816845ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:40.985117Z","caller":"traceutil/trace.go:171","msg":"trace[207467619] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"168.975191ms","start":"2026-05-29T21:58:40.816131Z","end":"2026-05-29T21:58:40.985106Z","steps":["trace[207467619] 'process raft request' (duration: 168.449279ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:46.220299Z","caller":"traceutil/trace.go:171","msg":"trace[583553067] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"391.09596ms","start":"2026-05-29T21:58:45.829176Z","end":"2026-05-29T21:58:46.220272Z","steps":["trace[583553067] 'read index received' (duration: 389.251355ms)","trace[583553067] 'applied index is now lower than readState.Index' (duration: 1.843375ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:58:46.220432Z","caller":"traceutil/trace.go:171","msg":"trace[189518762] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"418.794953ms","start":"2026-05-29T21:58:45.801592Z","end":"2026-05-29T21:58:46.220387Z","steps":["trace[189518762] 'process raft request' (duration: 416.927017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:46.220536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.011568ms","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-05-29T21:58:46.220568Z","caller":"traceutil/trace.go:171","msg":"trace[70328892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"133.08008ms","start":"2026-05-29T21:58:46.087476Z","end":"2026-05-29T21:58:46.220557Z","steps":["trace[70328892] 'agreement among raft nodes before linearized reading' (duration: 133.025419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:46.220593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:45.801568Z","time spent":"418.948786ms","remote":"127.0.0.1:42218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:58:46.220473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.282814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:58:46.220768Z","caller":"traceutil/trace.go:171","msg":"trace[1438540841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"391.603733ms","start":"2026-05-29T21:58:45.829147Z","end":"2026-05-29T21:58:46.220751Z","steps":["trace[1438540841] 'agreement among raft nodes before linearized reading' (duration: 391.252674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:46.22082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:45.829135Z","time spent":"391.666574ms","remote":"127.0.0.1:41698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T21:59:58.270472Z","caller":"traceutil/trace.go:171","msg":"trace[1620090492] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"197.804579ms","start":"2026-05-29T21:59:58.072639Z","end":"2026-05-29T21:59:58.270444Z","steps":["trace[1620090492] 'process raft request' (duration: 122.111172ms)","trace[1620090492] 'compare' (duration: 75.347999ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:59:58.846543Z","caller":"traceutil/trace.go:171","msg":"trace[758921602] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"198.037836ms","start":"2026-05-29T21:59:58.648464Z","end":"2026-05-29T21:59:58.846502Z","steps":["trace[758921602] 'read index received' (duration: 197.8058ms)","trace[758921602] 'applied index is now lower than readState.Index' (duration: 230.306µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:59:58.846594Z","caller":"traceutil/trace.go:171","msg":"trace[1074456410] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"295.584593ms","start":"2026-05-29T21:59:58.550961Z","end":"2026-05-29T21:59:58.846546Z","steps":["trace[1074456410] 'process raft request' (duration: 295.275095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:58.846893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.385183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:59:58.846998Z","caller":"traceutil/trace.go:171","msg":"trace[2054852730] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1043; }","duration":"198.581199ms","start":"2026-05-29T21:59:58.648394Z","end":"2026-05-29T21:59:58.846976Z","steps":["trace[2054852730] 'agreement among raft nodes before linearized reading' (duration: 198.372744ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:58.85894Z","caller":"traceutil/trace.go:171","msg":"trace[1169529300] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"159.781776ms","start":"2026-05-29T21:59:58.699128Z","end":"2026-05-29T21:59:58.858909Z","steps":["trace[1169529300] 'process raft request' (duration: 159.594972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:58.859464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.034442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:59:58.859518Z","caller":"traceutil/trace.go:171","msg":"trace[537166706] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1044; }","duration":"153.263948ms","start":"2026-05-29T21:59:58.706242Z","end":"2026-05-29T21:59:58.859506Z","steps":["trace[537166706] 'agreement among raft nodes before linearized reading' (duration: 152.628242ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:58.984783Z","caller":"traceutil/trace.go:171","msg":"trace[705124614] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"110.694976ms","start":"2026-05-29T21:59:58.87405Z","end":"2026-05-29T21:59:58.984745Z","steps":["trace[705124614] 'process raft request' (duration: 102.942337ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:15.193277Z","caller":"traceutil/trace.go:171","msg":"trace[280641679] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"243.513939ms","start":"2026-05-29T22:00:14.949746Z","end":"2026-05-29T22:00:15.19326Z","steps":["trace[280641679] 'process raft request' (duration: 243.247783ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:15.1932Z","caller":"traceutil/trace.go:171","msg":"trace[404207594] linearizableReadLoop","detail":"{readStateIndex:1194; appliedIndex:1193; }","duration":"128.748251ms","start":"2026-05-29T22:00:15.064428Z","end":"2026-05-29T22:00:15.193176Z","steps":["trace[404207594] 'read index received' (duration: 128.646428ms)","trace[404207594] 'applied index is now lower than readState.Index' (duration: 100.773µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:15.193479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.016757ms","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-05-29T22:00:15.193817Z","caller":"traceutil/trace.go:171","msg":"trace[1741193459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1147; }","duration":"129.410707ms","start":"2026-05-29T22:00:15.064388Z","end":"2026-05-29T22:00:15.193799Z","steps":["trace[1741193459] 'agreement among raft nodes before linearized reading' (duration: 128.915595ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:15.405512Z","caller":"traceutil/trace.go:171","msg":"trace[1283248461] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"338.780601ms","start":"2026-05-29T22:00:15.066448Z","end":"2026-05-29T22:00:15.405229Z","steps":["trace[1283248461] 'process raft request' (duration: 338.578586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:15.405676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:15.066422Z","time spent":"339.16611ms","remote":"127.0.0.1:41988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:00:15.407049Z","caller":"traceutil/trace.go:171","msg":"trace[1318824063] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"208.381239ms","start":"2026-05-29T22:00:15.198642Z","end":"2026-05-29T22:00:15.407023Z","steps":["trace[1318824063] 'process raft request' (duration: 208.247896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:31.374214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.351549ms","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-05-29T22:00:31.374321Z","caller":"traceutil/trace.go:171","msg":"trace[225550790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1226; }","duration":"112.531674ms","start":"2026-05-29T22:00:31.261766Z","end":"2026-05-29T22:00:31.374298Z","steps":["trace[225550790] 'range keys from in-memory index tree' (duration: 111.99814ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:31.551474Z","caller":"traceutil/trace.go:171","msg":"trace[1102658058] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"171.698051ms","start":"2026-05-29T22:00:31.379756Z","end":"2026-05-29T22:00:31.551454Z","steps":["trace[1102658058] 'process raft request' (duration: 171.580428ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:31.78068Z","caller":"traceutil/trace.go:171","msg":"trace[2024606383] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1276; }","duration":"313.806459ms","start":"2026-05-29T22:00:31.46685Z","end":"2026-05-29T22:00:31.780656Z","steps":["trace[2024606383] 'read index received' (duration: 84.542218ms)","trace[2024606383] 'applied index is now lower than readState.Index' (duration: 229.262581ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:00:31.780748Z","caller":"traceutil/trace.go:171","msg":"trace[768889384] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"330.432416ms","start":"2026-05-29T22:00:31.450278Z","end":"2026-05-29T22:00:31.78071Z","steps":["trace[768889384] 'process raft request' (duration: 288.264704ms)","trace[768889384] 'compare' (duration: 41.932567ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:31.780896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.033885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T22:00:31.78092Z","caller":"traceutil/trace.go:171","msg":"trace[1806477452] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1228; }","duration":"314.111607ms","start":"2026-05-29T22:00:31.466801Z","end":"2026-05-29T22:00:31.780913Z","steps":["trace[1806477452] 'agreement among raft nodes before linearized reading' (duration: 313.979884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:31.781044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:31.466765Z","time spent":"314.26522ms","remote":"127.0.0.1:41988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T22:00:31.781009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.085514ms","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-05-29T22:00:31.781127Z","caller":"traceutil/trace.go:171","msg":"trace[450857635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1228; }","duration":"168.250767ms","start":"2026-05-29T22:00:31.612853Z","end":"2026-05-29T22:00:31.781104Z","steps":["trace[450857635] 'agreement among raft nodes before linearized reading' (duration: 167.96152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:31.781179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:31.450246Z","time spent":"330.777575ms","remote":"127.0.0.1:41988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:00:43.703384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.74244ms","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-05-29T22:00:43.703499Z","caller":"traceutil/trace.go:171","msg":"trace[937745956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1308; }","duration":"101.893264ms","start":"2026-05-29T22:00:43.601585Z","end":"2026-05-29T22:00:43.703479Z","steps":["trace[937745956] 'range keys from in-memory index tree' (duration: 101.365511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:43.702897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.014728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-29T22:00:43.703579Z","caller":"traceutil/trace.go:171","msg":"trace[1452859229] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1308; }","duration":"118.727945ms","start":"2026-05-29T22:00:43.58482Z","end":"2026-05-29T22:00:43.703548Z","steps":["trace[1452859229] 'range keys from in-memory index tree' (duration: 117.872565ms)"],"step_count":1}