{"level":"warn","ts":"2026-04-16T05:35:47.863121Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T05:35:47.863263Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70: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.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-04-16T05:35:47.863368Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T05:35:47.863387Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-04-16T05:35:47.86342Z","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-04-16T05:35:47.864344Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-04-16T05:35:47.864454Z","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.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-04-16T05:35:47.869596Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.821994ms"} {"level":"info","ts":"2026-04-16T05:35:47.883734Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-04-16T05:35:47.883838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T05:35:47.883865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-04-16T05:35:47.883883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T05:35:47.883896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-04-16T05:35:47.883941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-04-16T05:35:47.888622Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T05:35:47.891737Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T05:35:47.893018Z","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-04-16T05:35:47.897429Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T05:35:47.898148Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T05:35:47.898746Z","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-04-16T05:35:47.898789Z","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-04-16T05:35:47.898798Z","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-04-16T05:35:47.900364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-04-16T05:35:47.902288Z","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-04-16T05:35:47.902425Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-04-16T05:35:47.90245Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-04-16T05:35:47.90266Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T05:35:47.902746Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T05:35:47.903419Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-04-16T05:35:48.586083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T05:35:48.586154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T05:35:48.586186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-04-16T05:35:48.586202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-04-16T05:35:48.58624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-04-16T05:35:48.586253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-04-16T05:35:48.586268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-04-16T05:35:48.594297Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T05:35:48.59827Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T05:35:48.598276Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T05:35:48.598321Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T05:35:48.598835Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T05:35:48.598878Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T05:35:48.5991Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T05:35:48.599223Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T05:35:48.599258Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T05:35:48.601684Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T05:35:48.602365Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-04-16T05:35:53.055002Z","caller":"traceutil/trace.go:171","msg":"trace[175837545] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"734.12775ms","start":"2026-04-16T05:35:52.320847Z","end":"2026-04-16T05:35:53.054975Z","steps":["trace[175837545] 'process raft request' (duration: 733.947856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:35:53.055762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:35:52.320818Z","time spent":"734.292683ms","remote":"127.0.0.1:36114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T05:35:53.745714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.482425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T05:35:53.745858Z","caller":"traceutil/trace.go:171","msg":"trace[1716514196] linearizableReadLoop","detail":"{readStateIndex:216; appliedIndex:215; }","duration":"460.05972ms","start":"2026-04-16T05:35:53.285776Z","end":"2026-04-16T05:35:53.745836Z","steps":["trace[1716514196] 'read index received' (duration: 119.034813ms)","trace[1716514196] 'applied index is now lower than readState.Index' (duration: 341.023597ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T05:35:53.745949Z","caller":"traceutil/trace.go:171","msg":"trace[1950114705] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"682.064282ms","start":"2026-04-16T05:35:53.063824Z","end":"2026-04-16T05:35:53.745888Z","steps":["trace[1950114705] 'process raft request' (duration: 341.095438ms)","trace[1950114705] 'compare' (duration: 340.358212ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T05:35:53.746029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.232942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T05:35:53.746142Z","caller":"traceutil/trace.go:171","msg":"trace[937094224] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:211; }","duration":"460.375575ms","start":"2026-04-16T05:35:53.285753Z","end":"2026-04-16T05:35:53.746129Z","steps":["trace[937094224] 'agreement among raft nodes before linearized reading' (duration: 460.13664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:35:53.746195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:35:53.063806Z","time spent":"682.287097ms","remote":"127.0.0.1:36008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T05:35:53.746216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:35:53.285667Z","time spent":"460.536859ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T05:35:53.957031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.862915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T05:35:53.957155Z","caller":"traceutil/trace.go:171","msg":"trace[1032362154] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"113.426593ms","start":"2026-04-16T05:35:53.843705Z","end":"2026-04-16T05:35:53.957131Z","steps":["trace[1032362154] 'process raft request' (duration: 11.390214ms)","trace[1032362154] 'compare' (duration: 101.756533ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T05:35:54.695909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.291754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T05:35:54.696045Z","caller":"traceutil/trace.go:171","msg":"trace[1696456271] linearizableReadLoop","detail":"{readStateIndex:235; appliedIndex:234; }","duration":"554.192843ms","start":"2026-04-16T05:35:54.141832Z","end":"2026-04-16T05:35:54.696025Z","steps":["trace[1696456271] 'read index received' (duration: 379.070781ms)","trace[1696456271] 'applied index is now lower than readState.Index' (duration: 175.119982ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T05:35:54.696096Z","caller":"traceutil/trace.go:171","msg":"trace[1404477332] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"574.386865ms","start":"2026-04-16T05:35:54.121683Z","end":"2026-04-16T05:35:54.69607Z","steps":["trace[1404477332] 'process raft request' (duration: 399.163631ms)","trace[1404477332] 'compare' (duration: 173.958746ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T05:35:54.696394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.53977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T05:35:54.696482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:35:54.121666Z","time spent":"574.652521ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":197,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T05:35:54.696473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.703974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"info","ts":"2026-04-16T05:35:54.696524Z","caller":"traceutil/trace.go:171","msg":"trace[1695167063] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:229; }","duration":"554.719995ms","start":"2026-04-16T05:35:54.141763Z","end":"2026-04-16T05:35:54.696483Z","steps":["trace[1695167063] 'agreement among raft nodes before linearized reading' (duration: 554.46885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:35:54.696581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:35:54.141748Z","time spent":"554.823607ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T05:35:54.696585Z","caller":"traceutil/trace.go:171","msg":"trace[652926350] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:229; }","duration":"393.877869ms","start":"2026-04-16T05:35:54.302681Z","end":"2026-04-16T05:35:54.696559Z","steps":["trace[652926350] 'agreement among raft nodes before linearized reading' (duration: 393.603662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:35:54.696643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:35:54.302664Z","time spent":"393.966251ms","remote":"127.0.0.1:36114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3731,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-16T05:35:54.858969Z","caller":"traceutil/trace.go:171","msg":"trace[1054378895] linearizableReadLoop","detail":"{readStateIndex:236; appliedIndex:235; }","duration":"157.496383ms","start":"2026-04-16T05:35:54.701425Z","end":"2026-04-16T05:35:54.858921Z","steps":["trace[1054378895] 'read index received' (duration: 139.62872ms)","trace[1054378895] 'applied index is now lower than readState.Index' (duration: 17.865953ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T05:35:54.859069Z","caller":"traceutil/trace.go:171","msg":"trace[1229681391] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"157.76781ms","start":"2026-04-16T05:35:54.701259Z","end":"2026-04-16T05:35:54.859026Z","steps":["trace[1229681391] 'process raft request' (duration: 139.781594ms)","trace[1229681391] 'compare' (duration: 17.723431ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T05:35:54.859211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.74599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T05:35:54.859536Z","caller":"traceutil/trace.go:171","msg":"trace[1350936818] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:230; }","duration":"157.930124ms","start":"2026-04-16T05:35:54.701342Z","end":"2026-04-16T05:35:54.859272Z","steps":["trace[1350936818] 'agreement among raft nodes before linearized reading' (duration: 157.742579ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T05:35:54.890341Z","caller":"traceutil/trace.go:171","msg":"trace[51727282] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"185.704518ms","start":"2026-04-16T05:35:54.704612Z","end":"2026-04-16T05:35:54.890316Z","steps":["trace[51727282] 'process raft request' (duration: 185.527564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:35:55.219024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.197345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T05:35:55.219347Z","caller":"traceutil/trace.go:171","msg":"trace[198918284] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"304.736613ms","start":"2026-04-16T05:35:54.91458Z","end":"2026-04-16T05:35:55.219317Z","steps":["trace[198918284] 'process raft request' (duration: 66.961815ms)","trace[198918284] 'compare' (duration: 236.93145ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T05:35:55.219477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:35:54.914553Z","time spent":"304.887136ms","remote":"127.0.0.1:36268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":290,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T05:35:55.219602Z","caller":"traceutil/trace.go:171","msg":"trace[2107127974] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"303.443664ms","start":"2026-04-16T05:35:54.916134Z","end":"2026-04-16T05:35:55.219577Z","steps":["trace[2107127974] 'process raft request' (duration: 302.993935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:35:55.219698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:35:54.916112Z","time spent":"303.545287ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T05:35:55.219973Z","caller":"traceutil/trace.go:171","msg":"trace[228517410] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"238.638067ms","start":"2026-04-16T05:35:54.98132Z","end":"2026-04-16T05:35:55.219958Z","steps":["trace[228517410] 'process raft request' (duration: 238.050204ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T05:36:17.490483Z","caller":"traceutil/trace.go:171","msg":"trace[1837017325] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"108.581334ms","start":"2026-04-16T05:36:17.381882Z","end":"2026-04-16T05:36:17.490463Z","steps":["trace[1837017325] 'process raft request' (duration: 108.457122ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T05:36:17.498017Z","caller":"traceutil/trace.go:171","msg":"trace[851859769] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"112.576099ms","start":"2026-04-16T05:36:17.385424Z","end":"2026-04-16T05:36:17.498Z","steps":["trace[851859769] 'process raft request' (duration: 112.18443ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T05:36:17.498134Z","caller":"traceutil/trace.go:171","msg":"trace[1697998687] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"104.116922ms","start":"2026-04-16T05:36:17.39401Z","end":"2026-04-16T05:36:17.498127Z","steps":["trace[1697998687] 'process raft request' (duration: 103.630412ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T05:36:23.646802Z","caller":"traceutil/trace.go:171","msg":"trace[587082916] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"221.248908ms","start":"2026-04-16T05:36:23.425509Z","end":"2026-04-16T05:36:23.646758Z","steps":["trace[587082916] 'process raft request' (duration: 172.696232ms)","trace[587082916] 'compare' (duration: 48.346142ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T05:36:23.873687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.016354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6ddxd\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-16T05:36:23.873783Z","caller":"traceutil/trace.go:171","msg":"trace[26779542] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6ddxd; range_end:; response_count:1; response_revision:623; }","duration":"100.241158ms","start":"2026-04-16T05:36:23.773522Z","end":"2026-04-16T05:36:23.873763Z","steps":["trace[26779542] 'range keys from in-memory index tree' (duration: 99.80989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:36:24.755436Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566412843069724,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T05:36:25.150959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171315588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T05:36:25.151064Z","caller":"traceutil/trace.go:171","msg":"trace[1109701923] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"1.171469872s","start":"2026-04-16T05:36:23.979568Z","end":"2026-04-16T05:36:25.151038Z","steps":["trace[1109701923] 'range keys from in-memory index tree' (duration: 1.171087633s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:36:25.151117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:36:23.97955Z","time spent":"1.171554452s","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T05:36:25.256106Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566412843069724,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T05:36:25.756733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566412843069724,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T05:36:25.787574Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.725830316s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T05:36:25.793752Z","caller":"traceutil/trace.go:171","msg":"trace[1753657921] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.734825243s","start":"2026-04-16T05:36:24.058899Z","end":"2026-04-16T05:36:25.793724Z","steps":["trace[1753657921] 'process raft request' (duration: 1.732900133s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:36:25.793928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:36:24.058859Z","time spent":"1.734973295s","remote":"127.0.0.1:36008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T05:36:25.79393Z","caller":"traceutil/trace.go:171","msg":"trace[837284635] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:648; }","duration":"1.539397535s","start":"2026-04-16T05:36:24.254477Z","end":"2026-04-16T05:36:25.793875Z","steps":["trace[837284635] 'read index received' (duration: 1.533293378s)","trace[837284635] 'applied index is now lower than readState.Index' (duration: 6.101957ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T05:36:25.794301Z","caller":"traceutil/trace.go:171","msg":"trace[476484921] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"638.288914ms","start":"2026-04-16T05:36:25.155985Z","end":"2026-04-16T05:36:25.794274Z","steps":["trace[476484921] 'process raft request' (duration: 637.672092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:36:25.794447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:36:25.155953Z","time spent":"638.430027ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T05:36:25.794833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539956605s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-16T05:36:25.794901Z","caller":"traceutil/trace.go:171","msg":"trace[1516642300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"1.540041626s","start":"2026-04-16T05:36:24.254849Z","end":"2026-04-16T05:36:25.794891Z","steps":["trace[1516642300] 'agreement among raft nodes before linearized reading' (duration: 1.539892994s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:36:25.794924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:36:24.254841Z","time spent":"1.540077477s","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T05:36:25.795025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.343413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-16T05:36:25.79505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.61411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T05:36:25.795068Z","caller":"traceutil/trace.go:171","msg":"trace[305830626] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"720.436065ms","start":"2026-04-16T05:36:25.07462Z","end":"2026-04-16T05:36:25.795056Z","steps":["trace[305830626] 'agreement among raft nodes before linearized reading' (duration: 720.144799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:36:25.795108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:36:25.074594Z","time spent":"720.509056ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T05:36:25.795072Z","caller":"traceutil/trace.go:171","msg":"trace[1316545979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"987.67269ms","start":"2026-04-16T05:36:24.807393Z","end":"2026-04-16T05:36:25.795065Z","steps":["trace[1316545979] 'agreement among raft nodes before linearized reading' (duration: 987.639009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:36:25.795359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:36:24.807366Z","time spent":"987.984398ms","remote":"127.0.0.1:35912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T05:36:25.796429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539639049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T05:36:25.796481Z","caller":"traceutil/trace.go:171","msg":"trace[1873621350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"1.542047088s","start":"2026-04-16T05:36:24.254419Z","end":"2026-04-16T05:36:25.796466Z","steps":["trace[1873621350] 'agreement among raft nodes before linearized reading' (duration: 1.539544286s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:36:25.796509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:36:24.254392Z","time spent":"1.542105429s","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T05:36:28.205681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.821008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T05:36:28.205767Z","caller":"traceutil/trace.go:171","msg":"trace[660789239] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:671; }","duration":"201.96685ms","start":"2026-04-16T05:36:28.003779Z","end":"2026-04-16T05:36:28.205746Z","steps":["trace[660789239] 'range keys from in-memory index tree' (duration: 201.695375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:36:46.58104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.489327ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T05:36:46.581291Z","caller":"traceutil/trace.go:171","msg":"trace[629782772] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:804; }","duration":"256.785253ms","start":"2026-04-16T05:36:46.324478Z","end":"2026-04-16T05:36:46.581263Z","steps":["trace[629782772] 'range keys from in-memory index tree' (duration: 256.453776ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T05:36:47.21509Z","caller":"traceutil/trace.go:171","msg":"trace[2096796227] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"317.233632ms","start":"2026-04-16T05:36:46.897833Z","end":"2026-04-16T05:36:47.215066Z","steps":["trace[2096796227] 'process raft request' (duration: 317.083089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:36:47.215246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:36:46.89781Z","time spent":"317.352854ms","remote":"127.0.0.1:36182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T05:36:47.221848Z","caller":"traceutil/trace.go:171","msg":"trace[629835907] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"119.721298ms","start":"2026-04-16T05:36:47.10211Z","end":"2026-04-16T05:36:47.221832Z","steps":["trace[629835907] 'process raft request' (duration: 119.633166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:37:16.767611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566412843070572,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T05:37:17.04215Z","caller":"traceutil/trace.go:171","msg":"trace[1768503713] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1062; }","duration":"774.640027ms","start":"2026-04-16T05:37:16.267486Z","end":"2026-04-16T05:37:17.042126Z","steps":["trace[1768503713] 'read index received' (duration: 774.415062ms)","trace[1768503713] 'applied index is now lower than readState.Index' (duration: 223.655µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T05:37:17.042595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.071456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T05:37:17.042683Z","caller":"traceutil/trace.go:171","msg":"trace[1227961564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1027; }","duration":"775.201839ms","start":"2026-04-16T05:37:16.26746Z","end":"2026-04-16T05:37:17.042662Z","steps":["trace[1227961564] 'agreement among raft nodes before linearized reading' (duration: 774.784881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:37:17.042918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:37:16.267446Z","time spent":"775.378632ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T05:37:17.043408Z","caller":"traceutil/trace.go:171","msg":"trace[1613144157] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"778.782941ms","start":"2026-04-16T05:37:16.264606Z","end":"2026-04-16T05:37:17.043389Z","steps":["trace[1613144157] 'process raft request' (duration: 777.327592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:37:17.043639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:37:16.264581Z","time spent":"778.876012ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T05:37:17.155631Z","caller":"traceutil/trace.go:171","msg":"trace[1543081355] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"109.107619ms","start":"2026-04-16T05:37:17.046506Z","end":"2026-04-16T05:37:17.155614Z","steps":["trace[1543081355] 'process raft request' (duration: 109.007517ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T05:37:17.155633Z","caller":"traceutil/trace.go:171","msg":"trace[1981616586] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"113.391085ms","start":"2026-04-16T05:37:17.042214Z","end":"2026-04-16T05:37:17.155605Z","steps":["trace[1981616586] 'read index received' (duration: 104.500707ms)","trace[1981616586] 'applied index is now lower than readState.Index' (duration: 8.887898ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T05:37:17.155845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.425031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T05:37:17.155938Z","caller":"traceutil/trace.go:171","msg":"trace[967148125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1028; }","duration":"348.566395ms","start":"2026-04-16T05:37:16.80735Z","end":"2026-04-16T05:37:17.155917Z","steps":["trace[967148125] 'agreement among raft nodes before linearized reading' (duration: 348.412853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:37:17.155867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.035413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-04-16T05:37:17.155959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.545763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-16T05:37:17.155988Z","caller":"traceutil/trace.go:171","msg":"trace[1206421538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1028; }","duration":"888.214047ms","start":"2026-04-16T05:37:16.267761Z","end":"2026-04-16T05:37:17.155975Z","steps":["trace[1206421538] 'agreement among raft nodes before linearized reading' (duration: 887.933831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:37:17.155995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:37:16.807327Z","time spent":"348.652347ms","remote":"127.0.0.1:35912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T05:37:17.15603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:37:16.267754Z","time spent":"888.262927ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-16T05:37:17.156053Z","caller":"traceutil/trace.go:171","msg":"trace[683223978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1028; }","duration":"887.664896ms","start":"2026-04-16T05:37:16.268338Z","end":"2026-04-16T05:37:17.156003Z","steps":["trace[683223978] 'agreement among raft nodes before linearized reading' (duration: 887.428132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:37:17.156149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:37:16.268329Z","time spent":"887.762858ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T05:37:22.425291Z","caller":"traceutil/trace.go:171","msg":"trace[1280024699] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1099; }","duration":"156.887511ms","start":"2026-04-16T05:37:22.268378Z","end":"2026-04-16T05:37:22.425265Z","steps":["trace[1280024699] 'read index received' (duration: 156.677547ms)","trace[1280024699] 'applied index is now lower than readState.Index' (duration: 208.924µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T05:37:22.425561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.164266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-16T05:37:22.425627Z","caller":"traceutil/trace.go:171","msg":"trace[1391689747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1062; }","duration":"157.262879ms","start":"2026-04-16T05:37:22.268335Z","end":"2026-04-16T05:37:22.425598Z","steps":["trace[1391689747] 'agreement among raft nodes before linearized reading' (duration: 157.043475ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T05:37:22.425689Z","caller":"traceutil/trace.go:171","msg":"trace[538277825] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"161.531594ms","start":"2026-04-16T05:37:22.264095Z","end":"2026-04-16T05:37:22.425626Z","steps":["trace[538277825] 'process raft request' (duration: 161.027855ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T05:37:22.573231Z","caller":"traceutil/trace.go:171","msg":"trace[427949478] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"142.896841ms","start":"2026-04-16T05:37:22.430292Z","end":"2026-04-16T05:37:22.573189Z","steps":["trace[427949478] 'process raft request' (duration: 59.684755ms)","trace[427949478] 'compare' (duration: 83.036632ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T05:37:29.539136Z","caller":"traceutil/trace.go:171","msg":"trace[29636936] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"325.237064ms","start":"2026-04-16T05:37:29.213862Z","end":"2026-04-16T05:37:29.539099Z","steps":["trace[29636936] 'process raft request' (duration: 324.893415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:37:29.539363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:37:29.213838Z","time spent":"325.412368ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T05:37:29.561523Z","caller":"traceutil/trace.go:171","msg":"trace[233546903] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1142; }","duration":"344.917094ms","start":"2026-04-16T05:37:29.216569Z","end":"2026-04-16T05:37:29.561486Z","steps":["trace[233546903] 'read index received' (duration: 322.173777ms)","trace[233546903] 'applied index is now lower than readState.Index' (duration: 22.742067ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T05:37:29.561621Z","caller":"traceutil/trace.go:171","msg":"trace[124554059] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"346.189519ms","start":"2026-04-16T05:37:29.21538Z","end":"2026-04-16T05:37:29.561569Z","steps":["trace[124554059] 'process raft request' (duration: 345.327675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:37:29.561929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.255883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-16T05:37:29.562007Z","caller":"traceutil/trace.go:171","msg":"trace[1244962286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1105; }","duration":"345.427077ms","start":"2026-04-16T05:37:29.216561Z","end":"2026-04-16T05:37:29.561988Z","steps":["trace[1244962286] 'agreement among raft nodes before linearized reading' (duration: 345.088098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:37:29.562074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:37:29.215361Z","time spent":"346.488128ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T05:37:29.562099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T05:37:29.216534Z","time spent":"345.547981ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T05:37:37.843501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.874899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T05:37:37.843593Z","caller":"traceutil/trace.go:171","msg":"trace[2023133088] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1135; }","duration":"105.963431ms","start":"2026-04-16T05:37:37.737578Z","end":"2026-04-16T05:37:37.843541Z","steps":["trace[2023133088] 'range keys from in-memory index tree' (duration: 105.765946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:37:37.844005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.023714ms","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-04-16T05:37:37.844094Z","caller":"traceutil/trace.go:171","msg":"trace[766680539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1135; }","duration":"232.167428ms","start":"2026-04-16T05:37:37.611903Z","end":"2026-04-16T05:37:37.844071Z","steps":["trace[766680539] 'range keys from in-memory index tree' (duration: 231.813617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:37:37.844148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.772045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T05:37:37.844242Z","caller":"traceutil/trace.go:171","msg":"trace[1089642861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1135; }","duration":"232.925529ms","start":"2026-04-16T05:37:37.611294Z","end":"2026-04-16T05:37:37.844219Z","steps":["trace[1089642861] 'range keys from in-memory index tree' (duration: 232.404504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:37:37.844302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.917803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T05:37:37.844328Z","caller":"traceutil/trace.go:171","msg":"trace[65078542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1135; }","duration":"235.979584ms","start":"2026-04-16T05:37:37.608341Z","end":"2026-04-16T05:37:37.844321Z","steps":["trace[65078542] 'range keys from in-memory index tree' (duration: 235.216773ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T05:37:51.920697Z","caller":"traceutil/trace.go:171","msg":"trace[1253635976] linearizableReadLoop","detail":"{readStateIndex:1229; appliedIndex:1228; }","duration":"218.857308ms","start":"2026-04-16T05:37:51.701804Z","end":"2026-04-16T05:37:51.920661Z","steps":["trace[1253635976] 'read index received' (duration: 218.55499ms)","trace[1253635976] 'applied index is now lower than readState.Index' (duration: 300.128µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T05:37:51.920964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.524689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:statefulset-controller\" ","response":"range_response_count:1 size:990"} {"level":"info","ts":"2026-04-16T05:37:51.921016Z","caller":"traceutil/trace.go:171","msg":"trace[454089184] range","detail":"{range_begin:/registry/clusterroles/system:controller:statefulset-controller; range_end:; response_count:1; response_revision:1185; }","duration":"218.618991ms","start":"2026-04-16T05:37:51.70238Z","end":"2026-04-16T05:37:51.920999Z","steps":["trace[454089184] 'agreement among raft nodes before linearized reading' (duration: 218.486918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T05:37:51.921314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.412233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-04-16T05:37:51.921545Z","caller":"traceutil/trace.go:171","msg":"trace[360328438] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:1185; }","duration":"219.67705ms","start":"2026-04-16T05:37:51.701749Z","end":"2026-04-16T05:37:51.921426Z","steps":["trace[360328438] 'agreement among raft nodes before linearized reading' (duration: 219.099374ms)"],"step_count":1}