{"level":"warn","ts":"2026-03-25T13:11:55.404567Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T13:11:55.404678Z","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-03-25T13:11:55.404757Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T13:11:55.40477Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-03-25T13:11:55.40479Z","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-03-25T13:11:55.405568Z","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-03-25T13:11:55.405659Z","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":16,"max-cpu-available":16,"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-03-25T13:11:55.417646Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.874492ms"} {"level":"info","ts":"2026-03-25T13:11:55.426281Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-03-25T13:11:55.426406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T13:11:55.426453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-03-25T13:11:55.426469Z","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-03-25T13:11:55.426482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-03-25T13:11:55.426608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-03-25T13:11:55.431293Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T13:11:55.433218Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T13:11:55.436353Z","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-03-25T13:11:55.439188Z","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-03-25T13:11:55.439399Z","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-03-25T13:11:55.439555Z","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-03-25T13:11:55.439693Z","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-03-25T13:11:55.439719Z","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-03-25T13:11:55.441238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-03-25T13:11:55.441712Z","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-03-25T13:11:55.444718Z","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-03-25T13:11:55.44484Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-03-25T13:11:55.444896Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-03-25T13:11:55.4451Z","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-03-25T13:11:55.445182Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T13:11:56.227879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T13:11:56.227921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T13:11:56.227944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-03-25T13:11:56.227954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-03-25T13:11:56.227959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-03-25T13:11:56.227967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-03-25T13:11:56.227977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-03-25T13:11:56.229892Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T13:11:56.230659Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T13:11:56.230655Z","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-03-25T13:11:56.230733Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T13:11:56.230953Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T13:11:56.230995Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T13:11:56.231169Z","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-03-25T13:11:56.23123Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T13:11:56.231257Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T13:11:56.233369Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-25T13:11:56.23388Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-03-25T13:12:16.338774Z","caller":"traceutil/trace.go:171","msg":"trace[33695363] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"342.261691ms","start":"2026-03-25T13:12:15.996489Z","end":"2026-03-25T13:12:16.338751Z","steps":["trace[33695363] 'process raft request' (duration: 342.126607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:12:16.339378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:12:15.996464Z","time spent":"342.403084ms","remote":"127.0.0.1:38598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T13:12:16.663076Z","caller":"traceutil/trace.go:171","msg":"trace[2004654137] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"664.122063ms","start":"2026-03-25T13:12:15.998924Z","end":"2026-03-25T13:12:16.663046Z","steps":["trace[2004654137] 'process raft request' (duration: 617.276336ms)","trace[2004654137] 'compare' (duration: 46.620391ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:12:16.663213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:12:15.9989Z","time spent":"664.243685ms","remote":"127.0.0.1:38598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T13:12:24.940836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.465925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"warn","ts":"2026-03-25T13:12:24.94088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.927946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-03-25T13:12:24.940987Z","caller":"traceutil/trace.go:171","msg":"trace[1544299104] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:507; }","duration":"144.654611ms","start":"2026-03-25T13:12:24.796303Z","end":"2026-03-25T13:12:24.940958Z","steps":["trace[1544299104] 'range keys from in-memory index tree' (duration: 144.389893ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:12:24.941034Z","caller":"traceutil/trace.go:171","msg":"trace[1010558239] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:507; }","duration":"145.144492ms","start":"2026-03-25T13:12:24.795864Z","end":"2026-03-25T13:12:24.941008Z","steps":["trace[1010558239] 'range keys from in-memory index tree' (duration: 144.728941ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:12:26.399315Z","caller":"traceutil/trace.go:171","msg":"trace[1087662328] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"111.695306ms","start":"2026-03-25T13:12:26.287588Z","end":"2026-03-25T13:12:26.399283Z","steps":["trace[1087662328] 'process raft request' (duration: 111.495351ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:12:26.408607Z","caller":"traceutil/trace.go:171","msg":"trace[1555497912] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"118.411358ms","start":"2026-03-25T13:12:26.290175Z","end":"2026-03-25T13:12:26.408586Z","steps":["trace[1555497912] 'process raft request' (duration: 118.176362ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:12:26.408658Z","caller":"traceutil/trace.go:171","msg":"trace[1628021580] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"107.859804ms","start":"2026-03-25T13:12:26.300772Z","end":"2026-03-25T13:12:26.408632Z","steps":["trace[1628021580] 'process raft request' (duration: 107.728571ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:12:27.008803Z","caller":"traceutil/trace.go:171","msg":"trace[1465144195] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"199.317043ms","start":"2026-03-25T13:12:26.809459Z","end":"2026-03-25T13:12:27.008776Z","steps":["trace[1465144195] 'process raft request' (duration: 198.737729ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:12:27.014165Z","caller":"traceutil/trace.go:171","msg":"trace[1487415290] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"165.806638ms","start":"2026-03-25T13:12:26.848324Z","end":"2026-03-25T13:12:27.014131Z","steps":["trace[1487415290] 'process raft request' (duration: 165.602914ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:12:27.014159Z","caller":"traceutil/trace.go:171","msg":"trace[536231728] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"185.543933ms","start":"2026-03-25T13:12:26.828572Z","end":"2026-03-25T13:12:27.014116Z","steps":["trace[536231728] 'process raft request' (duration: 185.193774ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:12:32.853466Z","caller":"traceutil/trace.go:171","msg":"trace[412502153] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"116.589405ms","start":"2026-03-25T13:12:32.736856Z","end":"2026-03-25T13:12:32.853446Z","steps":["trace[412502153] 'process raft request' (duration: 116.493652ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:12:33.198936Z","caller":"traceutil/trace.go:171","msg":"trace[538877808] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"213.041324ms","start":"2026-03-25T13:12:32.985874Z","end":"2026-03-25T13:12:33.198915Z","steps":["trace[538877808] 'process raft request' (duration: 212.062761ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:12:33.545989Z","caller":"traceutil/trace.go:171","msg":"trace[1644349275] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"281.755625ms","start":"2026-03-25T13:12:33.264197Z","end":"2026-03-25T13:12:33.545953Z","steps":["trace[1644349275] 'process raft request' (duration: 281.477078ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:12:33.715517Z","caller":"traceutil/trace.go:171","msg":"trace[77690650] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"162.075408ms","start":"2026-03-25T13:12:33.553422Z","end":"2026-03-25T13:12:33.715498Z","steps":["trace[77690650] 'read index received' (duration: 129.192987ms)","trace[77690650] 'applied index is now lower than readState.Index' (duration: 32.881601ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:12:33.715654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.229021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-25T13:12:33.715676Z","caller":"traceutil/trace.go:171","msg":"trace[1418978174] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:626; }","duration":"162.290683ms","start":"2026-03-25T13:12:33.553378Z","end":"2026-03-25T13:12:33.715669Z","steps":["trace[1418978174] 'agreement among raft nodes before linearized reading' (duration: 162.200021ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:12:33.715657Z","caller":"traceutil/trace.go:171","msg":"trace[573030914] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"165.031199ms","start":"2026-03-25T13:12:33.550597Z","end":"2026-03-25T13:12:33.715628Z","steps":["trace[573030914] 'process raft request' (duration: 132.031905ms)","trace[573030914] 'compare' (duration: 32.778369ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:12:37.822466Z","caller":"traceutil/trace.go:171","msg":"trace[1920995597] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"141.645311ms","start":"2026-03-25T13:12:37.680794Z","end":"2026-03-25T13:12:37.822439Z","steps":["trace[1920995597] 'process raft request' (duration: 139.622011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:12:50.206501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.35211ms","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-03-25T13:12:50.206655Z","caller":"traceutil/trace.go:171","msg":"trace[819969092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:790; }","duration":"225.564436ms","start":"2026-03-25T13:12:49.981065Z","end":"2026-03-25T13:12:50.206629Z","steps":["trace[819969092] 'range keys from in-memory index tree' (duration: 225.109144ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:13:39.57325Z","caller":"traceutil/trace.go:171","msg":"trace[1930669190] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"424.47803ms","start":"2026-03-25T13:13:39.148758Z","end":"2026-03-25T13:13:39.573236Z","steps":["trace[1930669190] 'process raft request' (duration: 424.313806ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:13:39.57335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:13:39.148739Z","time spent":"424.553652ms","remote":"127.0.0.1:38598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T13:13:39.573226Z","caller":"traceutil/trace.go:171","msg":"trace[1612508365] linearizableReadLoop","detail":"{readStateIndex:1477; appliedIndex:1476; }","duration":"407.003503ms","start":"2026-03-25T13:13:39.1662Z","end":"2026-03-25T13:13:39.573204Z","steps":["trace[1612508365] 'read index received' (duration: 406.854559ms)","trace[1612508365] 'applied index is now lower than readState.Index' (duration: 148.054µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:13:39.57338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.169826ms","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-03-25T13:13:39.573425Z","caller":"traceutil/trace.go:171","msg":"trace[1845592309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1434; }","duration":"407.25584ms","start":"2026-03-25T13:13:39.166158Z","end":"2026-03-25T13:13:39.573414Z","steps":["trace[1845592309] 'agreement among raft nodes before linearized reading' (duration: 407.120066ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:13:39.573466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:13:39.16614Z","time spent":"407.318741ms","remote":"127.0.0.1:38598","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-03-25T13:13:39.582769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.779851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T13:13:39.58281Z","caller":"traceutil/trace.go:171","msg":"trace[1589235494] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1436; }","duration":"252.851563ms","start":"2026-03-25T13:13:39.329948Z","end":"2026-03-25T13:13:39.5828Z","steps":["trace[1589235494] 'agreement among raft nodes before linearized reading' (duration: 252.775751ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:13:39.582798Z","caller":"traceutil/trace.go:171","msg":"trace[1658456595] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"219.028086ms","start":"2026-03-25T13:13:39.363741Z","end":"2026-03-25T13:13:39.582769Z","steps":["trace[1658456595] 'process raft request' (duration: 218.904173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:13:39.583145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.641788ms","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-03-25T13:13:39.58324Z","caller":"traceutil/trace.go:171","msg":"trace[1653130058] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1436; }","duration":"133.780991ms","start":"2026-03-25T13:13:39.449436Z","end":"2026-03-25T13:13:39.583217Z","steps":["trace[1653130058] 'agreement among raft nodes before linearized reading' (duration: 133.464124ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:14:02.991617Z","caller":"traceutil/trace.go:171","msg":"trace[146641742] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"210.925484ms","start":"2026-03-25T13:14:02.780652Z","end":"2026-03-25T13:14:02.991577Z","steps":["trace[146641742] 'process raft request' (duration: 210.664518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:14:03.167273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.109569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T13:14:03.167302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.934839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T13:14:03.167385Z","caller":"traceutil/trace.go:171","msg":"trace[1767876050] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1851; }","duration":"169.086082ms","start":"2026-03-25T13:14:02.99828Z","end":"2026-03-25T13:14:03.167366Z","steps":["trace[1767876050] 'count revisions from in-memory index tree' (duration: 168.810355ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:14:03.167376Z","caller":"traceutil/trace.go:171","msg":"trace[227564392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1851; }","duration":"168.245472ms","start":"2026-03-25T13:14:02.999104Z","end":"2026-03-25T13:14:03.16735Z","steps":["trace[227564392] 'range keys from in-memory index tree' (duration: 168.050027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:14:03.167624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.500771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T13:14:03.167757Z","caller":"traceutil/trace.go:171","msg":"trace[277175544] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"168.14972ms","start":"2026-03-25T13:14:02.99958Z","end":"2026-03-25T13:14:03.167729Z","steps":["trace[277175544] 'process raft request' (duration: 34.27799ms)","trace[277175544] 'compare' (duration: 133.250175ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:14:03.516499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.546233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-25T13:14:03.516591Z","caller":"traceutil/trace.go:171","msg":"trace[26760992] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1853; }","duration":"157.727338ms","start":"2026-03-25T13:14:03.358842Z","end":"2026-03-25T13:14:03.51657Z","steps":["trace[26760992] 'range keys from in-memory index tree' (duration: 156.966449ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:14:19.673892Z","caller":"traceutil/trace.go:171","msg":"trace[1056332722] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"419.057552ms","start":"2026-03-25T13:14:19.254812Z","end":"2026-03-25T13:14:19.673869Z","steps":["trace[1056332722] 'process raft request' (duration: 418.881828ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:14:19.674031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:14:19.254795Z","time spent":"419.163094ms","remote":"127.0.0.1:38598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T13:14:59.302973Z","caller":"traceutil/trace.go:171","msg":"trace[1858190989] linearizableReadLoop","detail":"{readStateIndex:2287; appliedIndex:2286; }","duration":"357.625428ms","start":"2026-03-25T13:14:58.945317Z","end":"2026-03-25T13:14:59.302943Z","steps":["trace[1858190989] 'read index received' (duration: 357.433824ms)","trace[1858190989] 'applied index is now lower than readState.Index' (duration: 190.094µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:14:59.30302Z","caller":"traceutil/trace.go:171","msg":"trace[766512432] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"381.011556ms","start":"2026-03-25T13:14:58.921976Z","end":"2026-03-25T13:14:59.302988Z","steps":["trace[766512432] 'process raft request' (duration: 380.75607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:14:59.303256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.514576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T13:14:59.303343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.889674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-03-25T13:14:59.303333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:14:58.921945Z","time spent":"381.303403ms","remote":"127.0.0.1:38598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T13:14:59.303374Z","caller":"traceutil/trace.go:171","msg":"trace[1558197211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2205; }","duration":"303.735102ms","start":"2026-03-25T13:14:58.999619Z","end":"2026-03-25T13:14:59.303354Z","steps":["trace[1558197211] 'agreement among raft nodes before linearized reading' (duration: 303.503506ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:14:59.303595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:14:58.999592Z","time spent":"303.985718ms","remote":"127.0.0.1:38310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-25T13:14:59.303387Z","caller":"traceutil/trace.go:171","msg":"trace[1508375187] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2205; }","duration":"358.100989ms","start":"2026-03-25T13:14:58.945274Z","end":"2026-03-25T13:14:59.303375Z","steps":["trace[1508375187] 'agreement among raft nodes before linearized reading' (duration: 357.828583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:14:59.303709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:14:58.945255Z","time spent":"358.435038ms","remote":"127.0.0.1:55646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-03-25T13:15:34.230147Z","caller":"traceutil/trace.go:171","msg":"trace[1658173286] transaction","detail":"{read_only:false; response_revision:2571; number_of_response:1; }","duration":"203.421127ms","start":"2026-03-25T13:15:34.026699Z","end":"2026-03-25T13:15:34.23012Z","steps":["trace[1658173286] 'process raft request' (duration: 196.859189ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:15:34.232635Z","caller":"traceutil/trace.go:171","msg":"trace[1940134181] transaction","detail":"{read_only:false; response_revision:2572; number_of_response:1; }","duration":"122.992495ms","start":"2026-03-25T13:15:34.109629Z","end":"2026-03-25T13:15:34.232621Z","steps":["trace[1940134181] 'process raft request' (duration: 122.901693ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:16:12.493857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.059708ms","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-03-25T13:16:12.493976Z","caller":"traceutil/trace.go:171","msg":"trace[1918330115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2802; }","duration":"100.221383ms","start":"2026-03-25T13:16:12.393732Z","end":"2026-03-25T13:16:12.493953Z","steps":["trace[1918330115] 'agreement among raft nodes before linearized reading' (duration: 99.938565ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:16:12.49388Z","caller":"traceutil/trace.go:171","msg":"trace[1699357844] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"113.765566ms","start":"2026-03-25T13:16:12.380084Z","end":"2026-03-25T13:16:12.49385Z","steps":["trace[1699357844] 'process raft request' (duration: 96.824991ms)","trace[1699357844] 'compare' (duration: 16.551215ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:16:18.253597Z","caller":"traceutil/trace.go:171","msg":"trace[302556151] transaction","detail":"{read_only:false; response_revision:2831; number_of_response:1; }","duration":"102.667761ms","start":"2026-03-25T13:16:18.150906Z","end":"2026-03-25T13:16:18.253573Z","steps":["trace[302556151] 'process raft request' (duration: 81.820443ms)","trace[302556151] 'compare' (duration: 20.355116ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:16:18.253787Z","caller":"traceutil/trace.go:171","msg":"trace[282157957] transaction","detail":"{read_only:false; response_revision:2832; number_of_response:1; }","duration":"101.659708ms","start":"2026-03-25T13:16:18.152118Z","end":"2026-03-25T13:16:18.253778Z","steps":["trace[282157957] 'process raft request' (duration: 101.087264ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:16:24.518026Z","caller":"traceutil/trace.go:171","msg":"trace[1753862138] transaction","detail":"{read_only:false; response_revision:2863; number_of_response:1; }","duration":"244.53444ms","start":"2026-03-25T13:16:24.27347Z","end":"2026-03-25T13:16:24.518005Z","steps":["trace[1753862138] 'process raft request' (duration: 244.398616ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:16:24.51884Z","caller":"traceutil/trace.go:171","msg":"trace[1189712505] transaction","detail":"{read_only:false; response_revision:2864; number_of_response:1; }","duration":"244.817685ms","start":"2026-03-25T13:16:24.273987Z","end":"2026-03-25T13:16:24.518805Z","steps":["trace[1189712505] 'process raft request' (duration: 244.59834ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:16:56.949064Z","caller":"traceutil/trace.go:171","msg":"trace[337699638] transaction","detail":"{read_only:false; response_revision:3047; number_of_response:1; }","duration":"102.237041ms","start":"2026-03-25T13:16:56.846806Z","end":"2026-03-25T13:16:56.949043Z","steps":["trace[337699638] 'process raft request' (duration: 100.286014ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:19:56.330316Z","caller":"traceutil/trace.go:171","msg":"trace[1895259210] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"146.291893ms","start":"2026-03-25T13:19:56.183984Z","end":"2026-03-25T13:19:56.330276Z","steps":["trace[1895259210] 'process raft request' (duration: 145.946635ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:19:56.331346Z","caller":"traceutil/trace.go:171","msg":"trace[1486217849] linearizableReadLoop","detail":"{readStateIndex:4980; appliedIndex:4978; }","duration":"134.034549ms","start":"2026-03-25T13:19:56.197284Z","end":"2026-03-25T13:19:56.331318Z","steps":["trace[1486217849] 'read index received' (duration: 132.579114ms)","trace[1486217849] 'applied index is now lower than readState.Index' (duration: 1.453985ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:19:56.331447Z","caller":"traceutil/trace.go:171","msg":"trace[12537919] transaction","detail":"{read_only:false; response_revision:4796; number_of_response:1; }","duration":"134.767376ms","start":"2026-03-25T13:19:56.196656Z","end":"2026-03-25T13:19:56.331424Z","steps":["trace[12537919] 'process raft request' (duration: 134.51863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:19:56.331658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.365386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-hk7ln\" ","response":"range_response_count:1 size:5147"} {"level":"info","ts":"2026-03-25T13:19:56.3317Z","caller":"traceutil/trace.go:171","msg":"trace[1654208019] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-hk7ln; range_end:; response_count:1; response_revision:4796; }","duration":"134.429017ms","start":"2026-03-25T13:19:56.197257Z","end":"2026-03-25T13:19:56.331686Z","steps":["trace[1654208019] 'agreement among raft nodes before linearized reading' (duration: 134.210142ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:19:59.441615Z","caller":"traceutil/trace.go:171","msg":"trace[2041822426] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"103.953474ms","start":"2026-03-25T13:19:59.337623Z","end":"2026-03-25T13:19:59.441576Z","steps":["trace[2041822426] 'process raft request' (duration: 103.721838ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:19:59.518912Z","caller":"traceutil/trace.go:171","msg":"trace[1504957812] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"174.680477ms","start":"2026-03-25T13:19:59.344204Z","end":"2026-03-25T13:19:59.518884Z","steps":["trace[1504957812] 'process raft request' (duration: 174.623056ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:19:59.518988Z","caller":"traceutil/trace.go:171","msg":"trace[1120867350] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"179.35311ms","start":"2026-03-25T13:19:59.339605Z","end":"2026-03-25T13:19:59.518958Z","steps":["trace[1120867350] 'process raft request' (duration: 179.026332ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:19:59.519033Z","caller":"traceutil/trace.go:171","msg":"trace[1116354580] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"176.157813ms","start":"2026-03-25T13:19:59.342841Z","end":"2026-03-25T13:19:59.518998Z","steps":["trace[1116354580] 'process raft request' (duration: 175.929127ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:19:59.816813Z","caller":"traceutil/trace.go:171","msg":"trace[1632902078] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"104.489726ms","start":"2026-03-25T13:19:59.712284Z","end":"2026-03-25T13:19:59.816773Z","steps":["trace[1632902078] 'process raft request' (duration: 66.349889ms)","trace[1632902078] 'compare' (duration: 37.664916ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:20:01.74304Z","caller":"traceutil/trace.go:171","msg":"trace[1871304812] linearizableReadLoop","detail":"{readStateIndex:5183; appliedIndex:5182; }","duration":"135.922975ms","start":"2026-03-25T13:20:01.60709Z","end":"2026-03-25T13:20:01.743013Z","steps":["trace[1871304812] 'read index received' (duration: 116.23377ms)","trace[1871304812] 'applied index is now lower than readState.Index' (duration: 19.688055ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:20:01.743163Z","caller":"traceutil/trace.go:171","msg":"trace[942805556] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"150.151937ms","start":"2026-03-25T13:20:01.592981Z","end":"2026-03-25T13:20:01.743133Z","steps":["trace[942805556] 'process raft request' (duration: 130.460922ms)","trace[942805556] 'compare' (duration: 19.264104ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:20:01.743279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.16815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-25T13:20:01.743331Z","caller":"traceutil/trace.go:171","msg":"trace[1023167482] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4994; }","duration":"136.272793ms","start":"2026-03-25T13:20:01.607042Z","end":"2026-03-25T13:20:01.743315Z","steps":["trace[1023167482] 'agreement among raft nodes before linearized reading' (duration: 136.091809ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:20:01.743333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.502897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-25T13:20:01.743365Z","caller":"traceutil/trace.go:171","msg":"trace[939960666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4994; }","duration":"121.573018ms","start":"2026-03-25T13:20:01.621781Z","end":"2026-03-25T13:20:01.743355Z","steps":["trace[939960666] 'agreement among raft nodes before linearized reading' (duration: 121.379813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:20:17.133035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.320107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T13:20:17.133167Z","caller":"traceutil/trace.go:171","msg":"trace[827310296] linearizableReadLoop","detail":"{readStateIndex:5469; appliedIndex:5468; }","duration":"143.76107ms","start":"2026-03-25T13:20:16.989385Z","end":"2026-03-25T13:20:17.133146Z","steps":["trace[827310296] 'read index received' (duration: 35.581µs)","trace[827310296] 'applied index is now lower than readState.Index' (duration: 143.723969ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:20:17.133197Z","caller":"traceutil/trace.go:171","msg":"trace[287787519] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"194.485556ms","start":"2026-03-25T13:20:16.938678Z","end":"2026-03-25T13:20:17.133164Z","steps":["trace[287787519] 'process raft request' (duration: 32.944644ms)","trace[287787519] 'compare' (duration: 161.193344ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:20:17.133418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.175132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T13:20:17.133466Z","caller":"traceutil/trace.go:171","msg":"trace[376072494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5275; }","duration":"134.259753ms","start":"2026-03-25T13:20:16.999193Z","end":"2026-03-25T13:20:17.133453Z","steps":["trace[376072494] 'agreement among raft nodes before linearized reading' (duration: 134.15333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:20:17.133555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.174039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-25T13:20:17.133599Z","caller":"traceutil/trace.go:171","msg":"trace[1419701773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5275; }","duration":"144.257281ms","start":"2026-03-25T13:20:16.98933Z","end":"2026-03-25T13:20:17.133587Z","steps":["trace[1419701773] 'agreement among raft nodes before linearized reading' (duration: 143.916083ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:20:17.633352Z","caller":"traceutil/trace.go:171","msg":"trace[106944022] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"125.060703ms","start":"2026-03-25T13:20:17.508272Z","end":"2026-03-25T13:20:17.633332Z","steps":["trace[106944022] 'process raft request' (duration: 124.90517ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:20:17.69496Z","caller":"traceutil/trace.go:171","msg":"trace[104556160] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"111.739738ms","start":"2026-03-25T13:20:17.583193Z","end":"2026-03-25T13:20:17.694932Z","steps":["trace[104556160] 'process raft request' (duration: 111.693656ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:20:17.695197Z","caller":"traceutil/trace.go:171","msg":"trace[1922832709] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"114.548964ms","start":"2026-03-25T13:20:17.580628Z","end":"2026-03-25T13:20:17.695177Z","steps":["trace[1922832709] 'process raft request' (duration: 114.217547ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:20:17.695217Z","caller":"traceutil/trace.go:171","msg":"trace[988782892] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"115.967558ms","start":"2026-03-25T13:20:17.5792Z","end":"2026-03-25T13:20:17.695168Z","steps":["trace[988782892] 'process raft request' (duration: 115.474727ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:21:56.260836Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3039} {"level":"info","ts":"2026-03-25T13:21:56.363878Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3039,"took":"101.423501ms","hash":4237389421,"current-db-size-bytes":58572800,"current-db-size":"59 MB","current-db-size-in-use-bytes":31842304,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-25T13:21:56.363958Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4237389421,"revision":3039,"compact-revision":-1} {"level":"warn","ts":"2026-03-25T13:26:34.082858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.3264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T13:26:34.083028Z","caller":"traceutil/trace.go:171","msg":"trace[1559491239] linearizableReadLoop","detail":"{readStateIndex:8487; appliedIndex:8486; }","duration":"392.734143ms","start":"2026-03-25T13:26:33.690276Z","end":"2026-03-25T13:26:34.08301Z","steps":["trace[1559491239] 'read index received' (duration: 249.173968ms)","trace[1559491239] 'applied index is now lower than readState.Index' (duration: 143.558835ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:26:34.08311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.826045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-25T13:26:34.083152Z","caller":"traceutil/trace.go:171","msg":"trace[766920776] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8201; }","duration":"392.899017ms","start":"2026-03-25T13:26:33.690241Z","end":"2026-03-25T13:26:34.08314Z","steps":["trace[766920776] 'agreement among raft nodes before linearized reading' (duration: 392.817435ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:26:34.083197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:26:33.690228Z","time spent":"392.959088ms","remote":"127.0.0.1:38598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-25T13:26:34.083264Z","caller":"traceutil/trace.go:171","msg":"trace[75217994] transaction","detail":"{read_only:false; response_revision:8201; number_of_response:1; }","duration":"396.038241ms","start":"2026-03-25T13:26:33.687187Z","end":"2026-03-25T13:26:34.083225Z","steps":["trace[75217994] 'process raft request' (duration: 252.2241ms)","trace[75217994] 'compare' (duration: 143.215546ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:26:34.083396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T13:26:33.687157Z","time spent":"396.170055ms","remote":"127.0.0.1:38598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T13:26:46.463811Z","caller":"traceutil/trace.go:171","msg":"trace[1830149483] linearizableReadLoop","detail":"{readStateIndex:8558; appliedIndex:8557; }","duration":"255.61633ms","start":"2026-03-25T13:26:46.208161Z","end":"2026-03-25T13:26:46.463778Z","steps":["trace[1830149483] 'read index received' (duration: 210.759383ms)","trace[1830149483] 'applied index is now lower than readState.Index' (duration: 44.855257ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:26:46.463874Z","caller":"traceutil/trace.go:171","msg":"trace[1024067834] transaction","detail":"{read_only:false; response_revision:8268; number_of_response:1; }","duration":"290.93945ms","start":"2026-03-25T13:26:46.172868Z","end":"2026-03-25T13:26:46.463807Z","steps":["trace[1024067834] 'process raft request' (duration: 246.179246ms)","trace[1024067834] 'compare' (duration: 44.478288ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T13:26:46.464077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.880707ms","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-03-25T13:26:46.464196Z","caller":"traceutil/trace.go:171","msg":"trace[1409664961] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8268; }","duration":"256.01143ms","start":"2026-03-25T13:26:46.208156Z","end":"2026-03-25T13:26:46.464167Z","steps":["trace[1409664961] 'agreement among raft nodes before linearized reading' (duration: 255.718363ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:26:56.267151Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6155} {"level":"info","ts":"2026-03-25T13:26:56.363265Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6155,"took":"93.036413ms","hash":3069176809,"current-db-size-bytes":58572800,"current-db-size":"59 MB","current-db-size-in-use-bytes":23486464,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-25T13:26:56.363366Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3069176809,"revision":6155,"compact-revision":3039} {"level":"info","ts":"2026-03-25T13:27:18.975521Z","caller":"traceutil/trace.go:171","msg":"trace[317654332] transaction","detail":"{read_only:false; response_revision:8496; number_of_response:1; }","duration":"274.558671ms","start":"2026-03-25T13:27:18.700943Z","end":"2026-03-25T13:27:18.975501Z","steps":["trace[317654332] 'process raft request' (duration: 274.378837ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:27:18.979511Z","caller":"traceutil/trace.go:171","msg":"trace[2099670531] linearizableReadLoop","detail":"{readStateIndex:8796; appliedIndex:8794; }","duration":"164.102853ms","start":"2026-03-25T13:27:18.815386Z","end":"2026-03-25T13:27:18.979489Z","steps":["trace[2099670531] 'read index received' (duration: 159.949295ms)","trace[2099670531] 'applied index is now lower than readState.Index' (duration: 4.152718ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:27:18.979646Z","caller":"traceutil/trace.go:171","msg":"trace[567081783] transaction","detail":"{read_only:false; response_revision:8497; number_of_response:1; }","duration":"278.392052ms","start":"2026-03-25T13:27:18.701211Z","end":"2026-03-25T13:27:18.979603Z","steps":["trace[567081783] 'process raft request' (duration: 278.159106ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:27:18.979682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.291337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nxkm2\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-25T13:27:18.979845Z","caller":"traceutil/trace.go:171","msg":"trace[1797066424] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nxkm2; range_end:; response_count:1; response_revision:8497; }","duration":"164.521852ms","start":"2026-03-25T13:27:18.815313Z","end":"2026-03-25T13:27:18.979835Z","steps":["trace[1797066424] 'agreement among raft nodes before linearized reading' (duration: 164.298207ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:27:29.160423Z","caller":"traceutil/trace.go:171","msg":"trace[1292090689] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"142.429798ms","start":"2026-03-25T13:27:29.017924Z","end":"2026-03-25T13:27:29.160354Z","steps":["trace[1292090689] 'process raft request' (duration: 142.267274ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:27:29.188063Z","caller":"traceutil/trace.go:171","msg":"trace[1890177077] linearizableReadLoop","detail":"{readStateIndex:8898; appliedIndex:8896; }","duration":"153.238994ms","start":"2026-03-25T13:27:29.0348Z","end":"2026-03-25T13:27:29.188039Z","steps":["trace[1890177077] 'read index received' (duration: 125.391452ms)","trace[1890177077] 'applied index is now lower than readState.Index' (duration: 27.846252ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:27:29.188162Z","caller":"traceutil/trace.go:171","msg":"trace[1941207449] transaction","detail":"{read_only:false; response_revision:8592; number_of_response:1; }","duration":"166.674124ms","start":"2026-03-25T13:27:29.02145Z","end":"2026-03-25T13:27:29.188124Z","steps":["trace[1941207449] 'process raft request' (duration: 166.450219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:27:29.188291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.47102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nxkm2\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-25T13:27:29.188352Z","caller":"traceutil/trace.go:171","msg":"trace[1240196791] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nxkm2; range_end:; response_count:1; response_revision:8592; }","duration":"153.576742ms","start":"2026-03-25T13:27:29.034764Z","end":"2026-03-25T13:27:29.18834Z","steps":["trace[1240196791] 'agreement among raft nodes before linearized reading' (duration: 153.434839ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:27:29.36846Z","caller":"traceutil/trace.go:171","msg":"trace[2010349616] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"134.164791ms","start":"2026-03-25T13:27:29.234273Z","end":"2026-03-25T13:27:29.368438Z","steps":["trace[2010349616] 'process raft request' (duration: 134.008187ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:27:29.368686Z","caller":"traceutil/trace.go:171","msg":"trace[1963402931] transaction","detail":"{read_only:false; response_revision:8595; number_of_response:1; }","duration":"121.812428ms","start":"2026-03-25T13:27:29.246839Z","end":"2026-03-25T13:27:29.368652Z","steps":["trace[1963402931] 'process raft request' (duration: 121.536271ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:27:30.106314Z","caller":"traceutil/trace.go:171","msg":"trace[1397246995] linearizableReadLoop","detail":"{readStateIndex:8911; appliedIndex:8910; }","duration":"183.458993ms","start":"2026-03-25T13:27:29.922829Z","end":"2026-03-25T13:27:30.106288Z","steps":["trace[1397246995] 'read index received' (duration: 183.219088ms)","trace[1397246995] 'applied index is now lower than readState.Index' (duration: 238.735µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T13:27:30.106408Z","caller":"traceutil/trace.go:171","msg":"trace[1928856466] transaction","detail":"{read_only:false; response_revision:8604; number_of_response:1; }","duration":"205.875976ms","start":"2026-03-25T13:27:29.900505Z","end":"2026-03-25T13:27:30.106381Z","steps":["trace[1928856466] 'process raft request' (duration: 205.62263ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:27:30.106507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.643398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T13:27:30.106572Z","caller":"traceutil/trace.go:171","msg":"trace[908056921] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:8604; }","duration":"183.74287ms","start":"2026-03-25T13:27:29.922795Z","end":"2026-03-25T13:27:30.106538Z","steps":["trace[908056921] 'agreement among raft nodes before linearized reading' (duration: 183.638468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T13:27:30.106582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.526934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T13:27:30.10664Z","caller":"traceutil/trace.go:171","msg":"trace[524976772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8604; }","duration":"106.608576ms","start":"2026-03-25T13:27:30.000015Z","end":"2026-03-25T13:27:30.106623Z","steps":["trace[524976772] 'agreement among raft nodes before linearized reading' (duration: 106.508454ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T13:27:55.651853Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023f5.wal"} {"level":"info","ts":"2026-03-25T13:29:30.523359Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f8844a9a4263041d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-25T13:29:30.528055Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-25T13:29:30.528205Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-25T13:31:56.272879Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8327} {"level":"info","ts":"2026-03-25T13:31:56.349844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8327,"took":"74.894462ms","hash":2952234903,"current-db-size-bytes":58572800,"current-db-size":"59 MB","current-db-size-in-use-bytes":23887872,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-25T13:31:56.349918Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2952234903,"revision":8327,"compact-revision":6155} {"level":"info","ts":"2026-03-25T13:36:56.2795Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10539} {"level":"info","ts":"2026-03-25T13:36:56.355321Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10539,"took":"73.590653ms","hash":3413972253,"current-db-size-bytes":58572800,"current-db-size":"59 MB","current-db-size-in-use-bytes":21192704,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-03-25T13:36:56.355409Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3413972253,"revision":10539,"compact-revision":8327}