{"level":"warn","ts":"2026-04-12T20:44:05.647036Z","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-12T20:44:05.647132Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-12T20:44:05.647221Z","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-12T20:44:05.647229Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-12T20:44:05.647246Z","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-12T20:44:05.647881Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-04-12T20:44:05.647957Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-12T20:44:05.652015Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.855018ms"} {"level":"info","ts":"2026-04-12T20:44:05.657912Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-04-12T20:44:05.658016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-04-12T20:44:05.658055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-04-12T20:44:05.658072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-12T20:44:05.658099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-04-12T20:44:05.658161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-04-12T20:44:05.664189Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-12T20:44:05.666153Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-12T20:44:05.668863Z","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-12T20:44:05.672161Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-12T20:44:05.672275Z","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-12T20:44:05.672383Z","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-12T20:44:05.672432Z","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-12T20:44:05.672701Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-12T20:44:05.673854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-04-12T20:44:05.674941Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-12T20:44:05.678774Z","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-12T20:44:05.678957Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-12T20:44:05.678977Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-12T20:44:05.679166Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-12T20:44:05.679223Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-12T20:44:06.258452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-04-12T20:44:06.258554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-04-12T20:44:06.258607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-04-12T20:44:06.258651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-04-12T20:44:06.258681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-12T20:44:06.258708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-04-12T20:44:06.258732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-12T20:44:06.25968Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:44:06.260299Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-04-12T20:44:06.260411Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:44:06.260639Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:44:06.260727Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-12T20:44:06.260808Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-12T20:44:06.261246Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:44:06.261415Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:44:06.261482Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:44:06.263684Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-12T20:44:06.267104Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"warn","ts":"2026-04-12T20:44:10.593619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.327466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:44:10.593774Z","caller":"traceutil/trace.go:171","msg":"trace[1162331538] linearizableReadLoop","detail":"{readStateIndex:216; appliedIndex:215; }","duration":"163.521988ms","start":"2026-04-12T20:44:10.430234Z","end":"2026-04-12T20:44:10.593756Z","steps":["trace[1162331538] 'read index received' (duration: 16.262286ms)","trace[1162331538] 'applied index is now lower than readState.Index' (duration: 147.258312ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:10.593866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.625199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-04-12T20:44:10.593898Z","caller":"traceutil/trace.go:171","msg":"trace[795207852] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:211; }","duration":"163.698259ms","start":"2026-04-12T20:44:10.430191Z","end":"2026-04-12T20:44:10.593889Z","steps":["trace[795207852] 'agreement among raft nodes before linearized reading' (duration: 163.621858ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:10.594158Z","caller":"traceutil/trace.go:171","msg":"trace[1039158005] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"232.953289ms","start":"2026-04-12T20:44:10.361168Z","end":"2026-04-12T20:44:10.594121Z","steps":["trace[1039158005] 'process raft request' (duration: 85.400735ms)","trace[1039158005] 'compare' (duration: 146.162425ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:10.859632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.350024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:44:10.859785Z","caller":"traceutil/trace.go:171","msg":"trace[931648997] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"206.710741ms","start":"2026-04-12T20:44:10.653056Z","end":"2026-04-12T20:44:10.859767Z","steps":["trace[931648997] 'process raft request' (duration: 43.131946ms)","trace[931648997] 'compare' (duration: 163.219003ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:11.527748Z","caller":"traceutil/trace.go:171","msg":"trace[556849681] linearizableReadLoop","detail":"{readStateIndex:241; appliedIndex:240; }","duration":"110.198544ms","start":"2026-04-12T20:44:11.417527Z","end":"2026-04-12T20:44:11.527725Z","steps":["trace[556849681] 'read index received' (duration: 109.887511ms)","trace[556849681] 'applied index is now lower than readState.Index' (duration: 310.173µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:11.527847Z","caller":"traceutil/trace.go:171","msg":"trace[2045816663] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"146.62795ms","start":"2026-04-12T20:44:11.381209Z","end":"2026-04-12T20:44:11.527837Z","steps":["trace[2045816663] 'process raft request' (duration: 146.136366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:11.527955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.405596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-12T20:44:11.527994Z","caller":"traceutil/trace.go:171","msg":"trace[1203981669] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:235; }","duration":"110.498656ms","start":"2026-04-12T20:44:11.417485Z","end":"2026-04-12T20:44:11.527984Z","steps":["trace[1203981669] 'agreement among raft nodes before linearized reading' (duration: 110.396345ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:11.720852Z","caller":"traceutil/trace.go:171","msg":"trace[2023585873] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"179.805632ms","start":"2026-04-12T20:44:11.541025Z","end":"2026-04-12T20:44:11.72083Z","steps":["trace[2023585873] 'process raft request' (duration: 170.400486ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:11.751501Z","caller":"traceutil/trace.go:171","msg":"trace[699734063] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"206.306228ms","start":"2026-04-12T20:44:11.545167Z","end":"2026-04-12T20:44:11.751473Z","steps":["trace[699734063] 'process raft request' (duration: 206.033906ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:11.751807Z","caller":"traceutil/trace.go:171","msg":"trace[1988470894] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"185.543393ms","start":"2026-04-12T20:44:11.566167Z","end":"2026-04-12T20:44:11.75171Z","steps":["trace[1988470894] 'process raft request' (duration: 185.210751ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:11.751893Z","caller":"traceutil/trace.go:171","msg":"trace[991857301] transaction","detail":"{read_only:false; number_of_response:0; response_revision:243; }","duration":"184.672747ms","start":"2026-04-12T20:44:11.567185Z","end":"2026-04-12T20:44:11.751857Z","steps":["trace[991857301] 'process raft request' (duration: 184.457074ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:11.751997Z","caller":"traceutil/trace.go:171","msg":"trace[864373256] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"164.250974ms","start":"2026-04-12T20:44:11.587729Z","end":"2026-04-12T20:44:11.75198Z","steps":["trace[864373256] 'process raft request' (duration: 164.030641ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:11.752137Z","caller":"traceutil/trace.go:171","msg":"trace[1230493040] transaction","detail":"{read_only:false; number_of_response:0; response_revision:245; }","duration":"163.294017ms","start":"2026-04-12T20:44:11.588833Z","end":"2026-04-12T20:44:11.752127Z","steps":["trace[1230493040] 'process raft request' (duration: 163.164236ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:11.752136Z","caller":"traceutil/trace.go:171","msg":"trace[1590666224] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"163.360778ms","start":"2026-04-12T20:44:11.588762Z","end":"2026-04-12T20:44:11.752123Z","steps":["trace[1590666224] 'process raft request' (duration: 163.157876ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:11.752155Z","caller":"traceutil/trace.go:171","msg":"trace[945331830] linearizableReadLoop","detail":"{readStateIndex:250; appliedIndex:246; }","duration":"184.551296ms","start":"2026-04-12T20:44:11.567585Z","end":"2026-04-12T20:44:11.752136Z","steps":["trace[945331830] 'read index received' (duration: 144.326092ms)","trace[945331830] 'applied index is now lower than readState.Index' (duration: 40.223754ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:11.752233Z","caller":"traceutil/trace.go:171","msg":"trace[1940243346] transaction","detail":"{read_only:false; number_of_response:0; response_revision:244; }","duration":"163.76778ms","start":"2026-04-12T20:44:11.588441Z","end":"2026-04-12T20:44:11.752209Z","steps":["trace[1940243346] 'process raft request' (duration: 163.425088ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:11.75414Z","caller":"traceutil/trace.go:171","msg":"trace[2055272321] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"164.705707ms","start":"2026-04-12T20:44:11.589087Z","end":"2026-04-12T20:44:11.753793Z","steps":["trace[2055272321] 'process raft request' (duration: 162.971835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:11.754179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.567519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-12T20:44:11.754348Z","caller":"traceutil/trace.go:171","msg":"trace[1606323450] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:246; }","duration":"186.7271ms","start":"2026-04-12T20:44:11.56758Z","end":"2026-04-12T20:44:11.754307Z","steps":["trace[1606323450] 'agreement among raft nodes before linearized reading' (duration: 184.620296ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:11.989317Z","caller":"traceutil/trace.go:171","msg":"trace[1318194283] linearizableReadLoop","detail":"{readStateIndex:262; appliedIndex:259; }","duration":"131.827856ms","start":"2026-04-12T20:44:11.85747Z","end":"2026-04-12T20:44:11.989298Z","steps":["trace[1318194283] 'read index received' (duration: 76.023944ms)","trace[1318194283] 'applied index is now lower than readState.Index' (duration: 55.802862ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:11.989488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.999347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:11.989525Z","caller":"traceutil/trace.go:171","msg":"trace[1880236522] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:0; response_revision:254; }","duration":"132.064747ms","start":"2026-04-12T20:44:11.857449Z","end":"2026-04-12T20:44:11.989514Z","steps":["trace[1880236522] 'agreement among raft nodes before linearized reading' (duration: 131.997527ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:11.989611Z","caller":"traceutil/trace.go:171","msg":"trace[1039413530] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"128.354341ms","start":"2026-04-12T20:44:11.86125Z","end":"2026-04-12T20:44:11.989604Z","steps":["trace[1039413530] 'process raft request' (duration: 128.004288ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:11.989714Z","caller":"traceutil/trace.go:171","msg":"trace[493353843] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"132.363909ms","start":"2026-04-12T20:44:11.857339Z","end":"2026-04-12T20:44:11.989703Z","steps":["trace[493353843] 'process raft request' (duration: 124.216543ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:11.989597Z","caller":"traceutil/trace.go:171","msg":"trace[1078595744] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"131.977347ms","start":"2026-04-12T20:44:11.857356Z","end":"2026-04-12T20:44:11.989334Z","steps":["trace[1078595744] 'process raft request' (duration: 131.826836ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:14.222476Z","caller":"traceutil/trace.go:171","msg":"trace[658698038] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"104.089086ms","start":"2026-04-12T20:44:14.118348Z","end":"2026-04-12T20:44:14.222437Z","steps":["trace[658698038] 'process raft request' (duration: 58.06107ms)","trace[658698038] 'compare' (duration: 45.853145ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:16.253594Z","caller":"traceutil/trace.go:171","msg":"trace[1511864431] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"122.752463ms","start":"2026-04-12T20:44:16.130809Z","end":"2026-04-12T20:44:16.253561Z","steps":["trace[1511864431] 'process raft request' (duration: 44.563173ms)","trace[1511864431] 'compare' (duration: 78.057459ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:16.334577Z","caller":"traceutil/trace.go:171","msg":"trace[1495218795] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"200.911463ms","start":"2026-04-12T20:44:16.133639Z","end":"2026-04-12T20:44:16.33455Z","steps":["trace[1495218795] 'process raft request' (duration: 200.629721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:16.334782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.384928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-12T20:44:16.334956Z","caller":"traceutil/trace.go:171","msg":"trace[1768809665] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"199.367863ms","start":"2026-04-12T20:44:16.135182Z","end":"2026-04-12T20:44:16.33455Z","steps":["trace[1768809665] 'process raft request' (duration: 199.225632ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:16.335042Z","caller":"traceutil/trace.go:171","msg":"trace[182203076] linearizableReadLoop","detail":"{readStateIndex:332; appliedIndex:328; }","duration":"166.305698ms","start":"2026-04-12T20:44:16.168372Z","end":"2026-04-12T20:44:16.334678Z","steps":["trace[182203076] 'read index received' (duration: 7.035178ms)","trace[182203076] 'applied index is now lower than readState.Index' (duration: 159.26891ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:16.335111Z","caller":"traceutil/trace.go:171","msg":"trace[1088068129] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:320; }","duration":"166.524321ms","start":"2026-04-12T20:44:16.168327Z","end":"2026-04-12T20:44:16.334852Z","steps":["trace[1088068129] 'agreement among raft nodes before linearized reading' (duration: 166.353839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:16.335272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.31014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-12T20:44:16.335328Z","caller":"traceutil/trace.go:171","msg":"trace[8937480] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"197.963244ms","start":"2026-04-12T20:44:16.136898Z","end":"2026-04-12T20:44:16.334861Z","steps":["trace[8937480] 'process raft request' (duration: 197.54914ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:16.335386Z","caller":"traceutil/trace.go:171","msg":"trace[1764519814] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:320; }","duration":"116.476951ms","start":"2026-04-12T20:44:16.21889Z","end":"2026-04-12T20:44:16.335367Z","steps":["trace[1764519814] 'agreement among raft nodes before linearized reading' (duration: 116.30057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:16.924509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.369653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:44:16.924707Z","caller":"traceutil/trace.go:171","msg":"trace[454966086] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"496.452298ms","start":"2026-04-12T20:44:16.428237Z","end":"2026-04-12T20:44:16.924689Z","steps":["trace[454966086] 'process raft request' (duration: 175.842153ms)","trace[454966086] 'compare' (duration: 320.243662ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:16.924767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:16.428226Z","time spent":"496.518018ms","remote":"127.0.0.1:38596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:44:16.924791Z","caller":"traceutil/trace.go:171","msg":"trace[1963029528] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"495.463051ms","start":"2026-04-12T20:44:16.429291Z","end":"2026-04-12T20:44:16.924754Z","steps":["trace[1963029528] 'process raft request' (duration: 495.30563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:16.925035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:16.42928Z","time spent":"495.724773ms","remote":"127.0.0.1:38856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:44:17.535554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.363977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:44:17.535795Z","caller":"traceutil/trace.go:171","msg":"trace[1997703839] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"1.064809051s","start":"2026-04-12T20:44:16.470957Z","end":"2026-04-12T20:44:17.535766Z","steps":["trace[1997703839] 'process raft request' (duration: 639.175662ms)","trace[1997703839] 'compare' (duration: 424.667363ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:17.535898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:16.470932Z","time spent":"1.064917912s","remote":"127.0.0.1:38596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:44:17.535931Z","caller":"traceutil/trace.go:171","msg":"trace[1747698938] linearizableReadLoop","detail":"{readStateIndex:343; appliedIndex:340; }","duration":"604.77692ms","start":"2026-04-12T20:44:16.931136Z","end":"2026-04-12T20:44:17.535913Z","steps":["trace[1747698938] 'read index received' (duration: 179.071201ms)","trace[1747698938] 'applied index is now lower than readState.Index' (duration: 425.704809ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:17.535977Z","caller":"traceutil/trace.go:171","msg":"trace[849571621] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"605.633375ms","start":"2026-04-12T20:44:16.930323Z","end":"2026-04-12T20:44:17.535956Z","steps":["trace[849571621] 'process raft request' (duration: 605.495584ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:17.535989Z","caller":"traceutil/trace.go:171","msg":"trace[1695650178] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"606.805743ms","start":"2026-04-12T20:44:16.929119Z","end":"2026-04-12T20:44:17.535925Z","steps":["trace[1695650178] 'process raft request' (duration: 606.574562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:17.537315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:16.929099Z","time spent":"606.979524ms","remote":"127.0.0.1:38470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:44:17.538436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.585696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-12T20:44:17.5385Z","caller":"traceutil/trace.go:171","msg":"trace[1164642276] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:332; }","duration":"398.698327ms","start":"2026-04-12T20:44:17.139785Z","end":"2026-04-12T20:44:17.538483Z","steps":["trace[1164642276] 'agreement among raft nodes before linearized reading' (duration: 398.341454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:17.538556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:17.139766Z","time spent":"398.780707ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-12T20:44:17.538971Z","caller":"traceutil/trace.go:171","msg":"trace[1840215628] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"607.7093ms","start":"2026-04-12T20:44:16.931231Z","end":"2026-04-12T20:44:17.53894Z","steps":["trace[1840215628] 'process raft request' (duration: 604.644619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:17.53908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:16.931211Z","time spent":"607.802621ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:44:17.539225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:16.930315Z","time spent":"605.706466ms","remote":"127.0.0.1:38596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:44:17.539082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.935021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-04-12T20:44:17.539648Z","caller":"traceutil/trace.go:171","msg":"trace[860449227] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:332; }","duration":"608.532826ms","start":"2026-04-12T20:44:16.931099Z","end":"2026-04-12T20:44:17.539632Z","steps":["trace[860449227] 'agreement among raft nodes before linearized reading' (duration: 607.499998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:17.539907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:16.931087Z","time spent":"608.597596ms","remote":"127.0.0.1:38856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3729,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-04-12T20:44:17.744807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.204644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:44:17.744987Z","caller":"traceutil/trace.go:171","msg":"trace[1127846389] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"205.361315ms","start":"2026-04-12T20:44:17.539557Z","end":"2026-04-12T20:44:17.744918Z","steps":["trace[1127846389] 'process raft request' (duration: 94.96132ms)","trace[1127846389] 'compare' (duration: 109.940502ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:17.747149Z","caller":"traceutil/trace.go:171","msg":"trace[620277674] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"201.891461ms","start":"2026-04-12T20:44:17.545235Z","end":"2026-04-12T20:44:17.747127Z","steps":["trace[620277674] 'process raft request' (duration: 201.69816ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:17.747341Z","caller":"traceutil/trace.go:171","msg":"trace[752737080] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"202.016192ms","start":"2026-04-12T20:44:17.545289Z","end":"2026-04-12T20:44:17.747305Z","steps":["trace[752737080] 'process raft request' (duration: 201.760771ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:17.747498Z","caller":"traceutil/trace.go:171","msg":"trace[966202677] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"202.086272ms","start":"2026-04-12T20:44:17.545378Z","end":"2026-04-12T20:44:17.747465Z","steps":["trace[966202677] 'process raft request' (duration: 201.855991ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:17.747656Z","caller":"traceutil/trace.go:171","msg":"trace[1855319455] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:344; }","duration":"202.214823ms","start":"2026-04-12T20:44:17.545427Z","end":"2026-04-12T20:44:17.747642Z","steps":["trace[1855319455] 'read index received' (duration: 89.802566ms)","trace[1855319455] 'applied index is now lower than readState.Index' (duration: 112.410527ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:17.747682Z","caller":"traceutil/trace.go:171","msg":"trace[2048336250] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"199.843988ms","start":"2026-04-12T20:44:17.547827Z","end":"2026-04-12T20:44:17.747671Z","steps":["trace[2048336250] 'process raft request' (duration: 199.683647ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:17.747586Z","caller":"traceutil/trace.go:171","msg":"trace[424493208] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"200.841105ms","start":"2026-04-12T20:44:17.546706Z","end":"2026-04-12T20:44:17.747547Z","steps":["trace[424493208] 'process raft request' (duration: 200.581173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:17.747737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.292454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-dtl52\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-04-12T20:44:17.748838Z","caller":"traceutil/trace.go:171","msg":"trace[2134547141] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-dtl52; range_end:; response_count:1; response_revision:338; }","duration":"203.414791ms","start":"2026-04-12T20:44:17.545407Z","end":"2026-04-12T20:44:17.748821Z","steps":["trace[2134547141] 'agreement among raft nodes before linearized reading' (duration: 202.276184ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:24.801891Z","caller":"traceutil/trace.go:171","msg":"trace[281867690] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"115.201426ms","start":"2026-04-12T20:44:24.686665Z","end":"2026-04-12T20:44:24.801866Z","steps":["trace[281867690] 'process raft request' (duration: 115.039105ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:25.005978Z","caller":"traceutil/trace.go:171","msg":"trace[743290388] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"128.186951ms","start":"2026-04-12T20:44:24.877749Z","end":"2026-04-12T20:44:25.005936Z","steps":["trace[743290388] 'process raft request' (duration: 95.513109ms)","trace[743290388] 'compare' (duration: 32.34476ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:25.011585Z","caller":"traceutil/trace.go:171","msg":"trace[728727412] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"133.693216ms","start":"2026-04-12T20:44:24.877873Z","end":"2026-04-12T20:44:25.011566Z","steps":["trace[728727412] 'process raft request' (duration: 133.647676ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:25.011833Z","caller":"traceutil/trace.go:171","msg":"trace[1873410171] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"133.995038ms","start":"2026-04-12T20:44:24.877829Z","end":"2026-04-12T20:44:25.011824Z","steps":["trace[1873410171] 'process raft request' (duration: 133.607166ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:25.252367Z","caller":"traceutil/trace.go:171","msg":"trace[167381128] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"168.378416ms","start":"2026-04-12T20:44:25.083967Z","end":"2026-04-12T20:44:25.252346Z","steps":["trace[167381128] 'read index received' (duration: 117.207206ms)","trace[167381128] 'applied index is now lower than readState.Index' (duration: 51.17014ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:25.252628Z","caller":"traceutil/trace.go:171","msg":"trace[1694341257] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"168.739488ms","start":"2026-04-12T20:44:25.083876Z","end":"2026-04-12T20:44:25.252615Z","steps":["trace[1694341257] 'process raft request' (duration: 117.380367ms)","trace[1694341257] 'compare' (duration: 50.894678ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:25.252753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.767258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-04-12T20:44:25.252816Z","caller":"traceutil/trace.go:171","msg":"trace[1844447879] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:391; }","duration":"168.874579ms","start":"2026-04-12T20:44:25.08393Z","end":"2026-04-12T20:44:25.252804Z","steps":["trace[1844447879] 'agreement among raft nodes before linearized reading' (duration: 168.745958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:25.253045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.901669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-kfldn\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-04-12T20:44:25.253251Z","caller":"traceutil/trace.go:171","msg":"trace[232463571] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-kfldn; range_end:; response_count:1; response_revision:391; }","duration":"169.01792ms","start":"2026-04-12T20:44:25.084094Z","end":"2026-04-12T20:44:25.253112Z","steps":["trace[232463571] 'agreement among raft nodes before linearized reading' (duration: 168.643107ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:25.252764Z","caller":"traceutil/trace.go:171","msg":"trace[699684539] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"168.670347ms","start":"2026-04-12T20:44:25.084065Z","end":"2026-04-12T20:44:25.252736Z","steps":["trace[699684539] 'process raft request' (duration: 168.241125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:26.281893Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764676753759610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:44:26.538943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.627347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:44:26.539328Z","caller":"traceutil/trace.go:171","msg":"trace[908998965] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"1.283664488s","start":"2026-04-12T20:44:25.25561Z","end":"2026-04-12T20:44:26.539274Z","steps":["trace[908998965] 'process raft request' (duration: 522.621559ms)","trace[908998965] 'compare' (duration: 760.491486ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:26.539485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:25.255586Z","time spent":"1.283854639s","remote":"127.0.0.1:38596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4013,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:44:26.615758Z","caller":"traceutil/trace.go:171","msg":"trace[1802712676] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"834.052207ms","start":"2026-04-12T20:44:25.781687Z","end":"2026-04-12T20:44:26.615739Z","steps":["trace[1802712676] 'read index received' (duration: 833.913445ms)","trace[1802712676] 'applied index is now lower than readState.Index' (duration: 138.252µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:26.615896Z","caller":"traceutil/trace.go:171","msg":"trace[1591160466] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"1.354880965s","start":"2026-04-12T20:44:25.261005Z","end":"2026-04-12T20:44:26.615886Z","steps":["trace[1591160466] 'process raft request' (duration: 1.354625013s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:26.616026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.3207ms","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-12T20:44:26.616243Z","caller":"traceutil/trace.go:171","msg":"trace[1190874317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:393; }","duration":"834.611121ms","start":"2026-04-12T20:44:25.781616Z","end":"2026-04-12T20:44:26.616227Z","steps":["trace[1190874317] 'agreement among raft nodes before linearized reading' (duration: 834.3369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:26.616286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:25.781596Z","time spent":"834.678412ms","remote":"127.0.0.1:38654","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-12T20:44:26.616932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:25.260986Z","time spent":"1.355125867s","remote":"127.0.0.1:38888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:44:26.752292Z","caller":"traceutil/trace.go:171","msg":"trace[30724195] transaction","detail":"{read_only:false; number_of_response:1; response_revision:393; }","duration":"211.072054ms","start":"2026-04-12T20:44:26.541198Z","end":"2026-04-12T20:44:26.75227Z","steps":["trace[30724195] 'process raft request' (duration: 164.028052ms)","trace[30724195] 'compare' (duration: 46.918811ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:26.752714Z","caller":"traceutil/trace.go:171","msg":"trace[1600066117] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:408; }","duration":"136.736627ms","start":"2026-04-12T20:44:26.615967Z","end":"2026-04-12T20:44:26.752703Z","steps":["trace[1600066117] 'read index received' (duration: 89.205131ms)","trace[1600066117] 'applied index is now lower than readState.Index' (duration: 47.530566ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:26.752814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.569197ms","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-04-12T20:44:26.752844Z","caller":"traceutil/trace.go:171","msg":"trace[1843376733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:394; }","duration":"814.664747ms","start":"2026-04-12T20:44:25.938169Z","end":"2026-04-12T20:44:26.752834Z","steps":["trace[1843376733] 'agreement among raft nodes before linearized reading' (duration: 814.577847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:26.752877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:25.938139Z","time spent":"814.729778ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-12T20:44:26.753057Z","caller":"traceutil/trace.go:171","msg":"trace[2056814400] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"209.111273ms","start":"2026-04-12T20:44:26.543937Z","end":"2026-04-12T20:44:26.753048Z","steps":["trace[2056814400] 'process raft request' (duration: 208.286437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:26.753281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.843023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-12T20:44:26.753942Z","caller":"traceutil/trace.go:171","msg":"trace[1594741123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:394; }","duration":"585.537857ms","start":"2026-04-12T20:44:26.168397Z","end":"2026-04-12T20:44:26.753935Z","steps":["trace[1594741123] 'agreement among raft nodes before linearized reading' (duration: 584.675852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:26.754026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:26.16838Z","time spent":"585.638338ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-12T20:44:26.753459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.266795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-12T20:44:26.754191Z","caller":"traceutil/trace.go:171","msg":"trace[2023734283] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:394; }","duration":"452.036431ms","start":"2026-04-12T20:44:26.302148Z","end":"2026-04-12T20:44:26.754185Z","steps":["trace[2023734283] 'agreement among raft nodes before linearized reading' (duration: 451.275666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:26.754269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:26.302131Z","time spent":"452.130871ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-12T20:44:26.797183Z","caller":"traceutil/trace.go:171","msg":"trace[1727372104] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"177.226487ms","start":"2026-04-12T20:44:26.619933Z","end":"2026-04-12T20:44:26.797159Z","steps":["trace[1727372104] 'process raft request' (duration: 176.876625ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:26.797312Z","caller":"traceutil/trace.go:171","msg":"trace[1133053468] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"174.56515ms","start":"2026-04-12T20:44:26.622711Z","end":"2026-04-12T20:44:26.797276Z","steps":["trace[1133053468] 'process raft request' (duration: 174.469649ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:26.797622Z","caller":"traceutil/trace.go:171","msg":"trace[276315582] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"176.538582ms","start":"2026-04-12T20:44:26.621041Z","end":"2026-04-12T20:44:26.797579Z","steps":["trace[276315582] 'process raft request' (duration: 175.956599ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:26.79797Z","caller":"traceutil/trace.go:171","msg":"trace[1633272167] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"176.361322ms","start":"2026-04-12T20:44:26.621534Z","end":"2026-04-12T20:44:26.797895Z","steps":["trace[1633272167] 'process raft request' (duration: 175.573547ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:26.96762Z","caller":"traceutil/trace.go:171","msg":"trace[8275128] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"138.73599ms","start":"2026-04-12T20:44:26.828851Z","end":"2026-04-12T20:44:26.967587Z","steps":["trace[8275128] 'process raft request' (duration: 116.039944ms)","trace[8275128] 'compare' (duration: 22.557245ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:26.984635Z","caller":"traceutil/trace.go:171","msg":"trace[104830902] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"135.820951ms","start":"2026-04-12T20:44:26.84879Z","end":"2026-04-12T20:44:26.984611Z","steps":["trace[104830902] 'process raft request' (duration: 135.590049ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:26.985002Z","caller":"traceutil/trace.go:171","msg":"trace[752323808] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"131.969926ms","start":"2026-04-12T20:44:26.853023Z","end":"2026-04-12T20:44:26.984993Z","steps":["trace[752323808] 'process raft request' (duration: 131.517143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:33.569338Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764676753759674,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-12T20:44:33.939834Z","caller":"traceutil/trace.go:171","msg":"trace[1221657024] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:443; }","duration":"870.724055ms","start":"2026-04-12T20:44:33.069073Z","end":"2026-04-12T20:44:33.939797Z","steps":["trace[1221657024] 'read index received' (duration: 870.493604ms)","trace[1221657024] 'applied index is now lower than readState.Index' (duration: 229.011µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:33.940108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.987736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:33.940197Z","caller":"traceutil/trace.go:171","msg":"trace[536381881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"871.150627ms","start":"2026-04-12T20:44:33.069024Z","end":"2026-04-12T20:44:33.940175Z","steps":["trace[536381881] 'agreement among raft nodes before linearized reading' (duration: 870.938066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:33.940262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:33.069001Z","time spent":"871.249688ms","remote":"127.0.0.1:38404","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-04-12T20:44:33.940665Z","caller":"traceutil/trace.go:171","msg":"trace[945310061] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"963.616343ms","start":"2026-04-12T20:44:32.977034Z","end":"2026-04-12T20:44:33.94065Z","steps":["trace[945310061] 'process raft request' (duration: 962.545927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:33.940779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:32.977027Z","time spent":"963.676223ms","remote":"127.0.0.1:38654","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-04-12T20:44:33.940918Z","caller":"traceutil/trace.go:171","msg":"trace[230990133] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"963.734313ms","start":"2026-04-12T20:44:32.977174Z","end":"2026-04-12T20:44:33.940908Z","steps":["trace[230990133] 'process raft request' (duration: 962.577097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:33.94101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:32.977169Z","time spent":"963.793924ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:44:34.172365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.009995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:34.172449Z","caller":"traceutil/trace.go:171","msg":"trace[970254455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"101.147367ms","start":"2026-04-12T20:44:34.071284Z","end":"2026-04-12T20:44:34.172431Z","steps":["trace[970254455] 'range keys from in-memory index tree' (duration: 100.940205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:39.40631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.503866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-12T20:44:39.406453Z","caller":"traceutil/trace.go:171","msg":"trace[421867683] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:544; }","duration":"248.685675ms","start":"2026-04-12T20:44:39.157715Z","end":"2026-04-12T20:44:39.406401Z","steps":["trace[421867683] 'range keys from in-memory index tree' (duration: 248.170168ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:39.40649Z","caller":"traceutil/trace.go:171","msg":"trace[223866643] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"229.128525ms","start":"2026-04-12T20:44:39.177338Z","end":"2026-04-12T20:44:39.406466Z","steps":["trace[223866643] 'process raft request' (duration: 227.092579ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:39.416675Z","caller":"traceutil/trace.go:171","msg":"trace[28606659] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"203.894301ms","start":"2026-04-12T20:44:39.212722Z","end":"2026-04-12T20:44:39.416616Z","steps":["trace[28606659] 'process raft request' (duration: 203.374214ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:41.038349Z","caller":"traceutil/trace.go:171","msg":"trace[612768382] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"112.315228ms","start":"2026-04-12T20:44:40.925992Z","end":"2026-04-12T20:44:41.038307Z","steps":["trace[612768382] 'process raft request' (duration: 111.422002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:42.34594Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764676753759941,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:44:42.6741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.340981ms","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-12T20:44:42.674228Z","caller":"traceutil/trace.go:171","msg":"trace[499899875] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:584; }","duration":"168.479318ms","start":"2026-04-12T20:44:42.505727Z","end":"2026-04-12T20:44:42.674207Z","steps":["trace[499899875] 'range keys from in-memory index tree' (duration: 168.327571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:42.708755Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.433644754s","expected-duration":"1s"} {"level":"info","ts":"2026-04-12T20:44:42.709201Z","caller":"traceutil/trace.go:171","msg":"trace[1502515801] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.434215593s","start":"2026-04-12T20:44:41.274964Z","end":"2026-04-12T20:44:42.709179Z","steps":["trace[1502515801] 'process raft request' (duration: 1.434097247s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:42.709426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:41.274918Z","time spent":"1.434355679s","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:44:42.724415Z","caller":"traceutil/trace.go:171","msg":"trace[839388572] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.28246085s","start":"2026-04-12T20:44:41.44193Z","end":"2026-04-12T20:44:42.724391Z","steps":["trace[839388572] 'process raft request' (duration: 1.281411106s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:42.724555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:41.441902Z","time spent":"1.282593596s","remote":"127.0.0.1:38486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:44:42.728476Z","caller":"traceutil/trace.go:171","msg":"trace[977688390] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:606; }","duration":"882.933773ms","start":"2026-04-12T20:44:41.845513Z","end":"2026-04-12T20:44:42.728446Z","steps":["trace[977688390] 'read index received' (duration: 863.630023ms)","trace[977688390] 'applied index is now lower than readState.Index' (duration: 19.30278ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:42.728594Z","caller":"traceutil/trace.go:171","msg":"trace[1993613102] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.275865681s","start":"2026-04-12T20:44:41.452719Z","end":"2026-04-12T20:44:42.728584Z","steps":["trace[1993613102] 'process raft request' (duration: 1.27523346s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:42.728691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:41.452692Z","time spent":"1.275934484s","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:44:42.729224Z","caller":"traceutil/trace.go:171","msg":"trace[550793670] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.08056112s","start":"2026-04-12T20:44:41.648265Z","end":"2026-04-12T20:44:42.728826Z","steps":["trace[550793670] 'process raft request' (duration: 1.079923318s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:42.729276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.761785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:44:42.729315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:41.648242Z","time spent":"1.081037825s","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:44:42.729329Z","caller":"traceutil/trace.go:171","msg":"trace[1115926702] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:590; }","duration":"883.84124ms","start":"2026-04-12T20:44:41.84547Z","end":"2026-04-12T20:44:42.729311Z","steps":["trace[1115926702] 'agreement among raft nodes before linearized reading' (duration: 883.784587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:42.729367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:41.845449Z","time spent":"883.909583ms","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-12T20:44:42.729221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.619079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-12T20:44:42.729429Z","caller":"traceutil/trace.go:171","msg":"trace[1584768348] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"727.873392ms","start":"2026-04-12T20:44:42.001544Z","end":"2026-04-12T20:44:42.729418Z","steps":["trace[1584768348] 'agreement among raft nodes before linearized reading' (duration: 727.555725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:42.729473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:42.001526Z","time spent":"727.933205ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-12T20:44:42.729583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.412743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-12T20:44:42.729624Z","caller":"traceutil/trace.go:171","msg":"trace[1146096471] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:590; }","duration":"271.489427ms","start":"2026-04-12T20:44:42.458122Z","end":"2026-04-12T20:44:42.729611Z","steps":["trace[1146096471] 'agreement among raft nodes before linearized reading' (duration: 271.434334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:42.729789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.674039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-12T20:44:42.729848Z","caller":"traceutil/trace.go:171","msg":"trace[2101599169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:590; }","duration":"467.757833ms","start":"2026-04-12T20:44:42.262077Z","end":"2026-04-12T20:44:42.729835Z","steps":["trace[2101599169] 'agreement among raft nodes before linearized reading' (duration: 467.636577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:42.729889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:42.262062Z","time spent":"467.818176ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-12T20:44:42.730163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.960884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-12T20:44:42.730205Z","caller":"traceutil/trace.go:171","msg":"trace[1241938477] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:590; }","duration":"469.036448ms","start":"2026-04-12T20:44:42.261157Z","end":"2026-04-12T20:44:42.730194Z","steps":["trace[1241938477] 'agreement among raft nodes before linearized reading' (duration: 468.968034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:42.730243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:42.26114Z","time spent":"469.094861ms","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-04-12T20:44:42.730333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.055449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:42.730361Z","caller":"traceutil/trace.go:171","msg":"trace[1052660855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"660.116353ms","start":"2026-04-12T20:44:42.070237Z","end":"2026-04-12T20:44:42.730354Z","steps":["trace[1052660855] 'agreement among raft nodes before linearized reading' (duration: 660.076221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:42.730391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:42.070224Z","time spent":"660.161505ms","remote":"127.0.0.1:38404","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-12T20:44:42.730551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.98692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:42.730593Z","caller":"traceutil/trace.go:171","msg":"trace[1614440756] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:590; }","duration":"675.030152ms","start":"2026-04-12T20:44:42.055552Z","end":"2026-04-12T20:44:42.730582Z","steps":["trace[1614440756] 'agreement among raft nodes before linearized reading' (duration: 674.970779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:42.730628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:42.055508Z","time spent":"675.110966ms","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-12T20:44:42.730758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.805893ms","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-04-12T20:44:42.730789Z","caller":"traceutil/trace.go:171","msg":"trace[1275793623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"719.856545ms","start":"2026-04-12T20:44:42.010924Z","end":"2026-04-12T20:44:42.730781Z","steps":["trace[1275793623] 'agreement among raft nodes before linearized reading' (duration: 719.802192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:42.730821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:42.010912Z","time spent":"719.900638ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-12T20:44:42.731985Z","caller":"traceutil/trace.go:171","msg":"trace[515661400] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"341.774669ms","start":"2026-04-12T20:44:42.390195Z","end":"2026-04-12T20:44:42.731969Z","steps":["trace[515661400] 'process raft request' (duration: 338.0268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:42.732076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:42.390175Z","time spent":"341.854154ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:44:44.066316Z","caller":"traceutil/trace.go:171","msg":"trace[1250475656] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"591.887959ms","start":"2026-04-12T20:44:43.474403Z","end":"2026-04-12T20:44:44.066291Z","steps":["trace[1250475656] 'process raft request' (duration: 591.628066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:44.06645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:43.474378Z","time spent":"592.015756ms","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:44:44.086704Z","caller":"traceutil/trace.go:171","msg":"trace[31782279] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"411.238088ms","start":"2026-04-12T20:44:43.675451Z","end":"2026-04-12T20:44:44.086689Z","steps":["trace[31782279] 'process raft request' (duration: 411.120872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:44.086784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:43.675433Z","time spent":"411.317722ms","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:44:44.088437Z","caller":"traceutil/trace.go:171","msg":"trace[47373125] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:629; }","duration":"242.718901ms","start":"2026-04-12T20:44:43.845705Z","end":"2026-04-12T20:44:44.088424Z","steps":["trace[47373125] 'read index received' (duration: 221.067829ms)","trace[47373125] 'applied index is now lower than readState.Index' (duration: 21.650232ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:44.089209Z","caller":"traceutil/trace.go:171","msg":"trace[414592763] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"378.428977ms","start":"2026-04-12T20:44:43.710769Z","end":"2026-04-12T20:44:44.089198Z","steps":["trace[414592763] 'process raft request' (duration: 377.017248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:44.089604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.892918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-12T20:44:44.089657Z","caller":"traceutil/trace.go:171","msg":"trace[1829267738] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:609; }","duration":"243.990753ms","start":"2026-04-12T20:44:43.845653Z","end":"2026-04-12T20:44:44.089643Z","steps":["trace[1829267738] 'agreement among raft nodes before linearized reading' (duration: 243.878707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:44.090269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:43.710734Z","time spent":"378.512852ms","remote":"127.0.0.1:38486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:44:48.95384Z","caller":"traceutil/trace.go:171","msg":"trace[2022799003] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"192.124623ms","start":"2026-04-12T20:44:48.761687Z","end":"2026-04-12T20:44:48.953811Z","steps":["trace[2022799003] 'process raft request' (duration: 191.940685ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:49.033118Z","caller":"traceutil/trace.go:171","msg":"trace[1687198110] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"262.301057ms","start":"2026-04-12T20:44:48.770792Z","end":"2026-04-12T20:44:49.033093Z","steps":["trace[1687198110] 'read index received' (duration: 182.909573ms)","trace[1687198110] 'applied index is now lower than readState.Index' (duration: 79.390054ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:49.033411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.58239ms","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-12T20:44:49.033468Z","caller":"traceutil/trace.go:171","msg":"trace[76052623] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:643; }","duration":"262.672124ms","start":"2026-04-12T20:44:48.77078Z","end":"2026-04-12T20:44:49.033452Z","steps":["trace[76052623] 'agreement among raft nodes before linearized reading' (duration: 262.421312ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:49.033467Z","caller":"traceutil/trace.go:171","msg":"trace[399963770] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"268.421623ms","start":"2026-04-12T20:44:48.764986Z","end":"2026-04-12T20:44:49.033408Z","steps":["trace[399963770] 'process raft request' (duration: 266.220621ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:50.244675Z","caller":"traceutil/trace.go:171","msg":"trace[1117707452] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"174.333258ms","start":"2026-04-12T20:44:50.070318Z","end":"2026-04-12T20:44:50.244651Z","steps":["trace[1117707452] 'read index received' (duration: 174.076817ms)","trace[1117707452] 'applied index is now lower than readState.Index' (duration: 255.541µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:50.244777Z","caller":"traceutil/trace.go:171","msg":"trace[1359380757] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"614.904262ms","start":"2026-04-12T20:44:49.629851Z","end":"2026-04-12T20:44:50.244755Z","steps":["trace[1359380757] 'process raft request' (duration: 614.604188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:50.244821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.656073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:50.24488Z","caller":"traceutil/trace.go:171","msg":"trace[118970979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"174.742107ms","start":"2026-04-12T20:44:50.070123Z","end":"2026-04-12T20:44:50.244865Z","steps":["trace[118970979] 'agreement among raft nodes before linearized reading' (duration: 174.629972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:50.244937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:49.62982Z","time spent":"615.020098ms","remote":"127.0.0.1:38596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:44:51.069273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.875712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-12T20:44:51.069356Z","caller":"traceutil/trace.go:171","msg":"trace[1545402556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"111.006557ms","start":"2026-04-12T20:44:50.95833Z","end":"2026-04-12T20:44:51.069337Z","steps":["trace[1545402556] 'range keys from in-memory index tree' (duration: 110.036053ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:51.175502Z","caller":"traceutil/trace.go:171","msg":"trace[1450013493] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"102.490547ms","start":"2026-04-12T20:44:51.072985Z","end":"2026-04-12T20:44:51.175476Z","steps":["trace[1450013493] 'process raft request' (duration: 102.307038ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:51.202696Z","caller":"traceutil/trace.go:171","msg":"trace[453004075] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:675; }","duration":"124.901686ms","start":"2026-04-12T20:44:51.077748Z","end":"2026-04-12T20:44:51.202649Z","steps":["trace[453004075] 'read index received' (duration: 97.527043ms)","trace[453004075] 'applied index is now lower than readState.Index' (duration: 27.373043ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:51.202896Z","caller":"traceutil/trace.go:171","msg":"trace[1439574049] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"128.434736ms","start":"2026-04-12T20:44:51.074439Z","end":"2026-04-12T20:44:51.202873Z","steps":["trace[1439574049] 'process raft request' (duration: 128.163134ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:51.202724Z","caller":"traceutil/trace.go:171","msg":"trace[1312395066] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"128.368113ms","start":"2026-04-12T20:44:51.074322Z","end":"2026-04-12T20:44:51.20269Z","steps":["trace[1312395066] 'process raft request' (duration: 128.114741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:51.203233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.4548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-12T20:44:51.20355Z","caller":"traceutil/trace.go:171","msg":"trace[1055795936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"125.770844ms","start":"2026-04-12T20:44:51.077721Z","end":"2026-04-12T20:44:51.203491Z","steps":["trace[1055795936] 'agreement among raft nodes before linearized reading' (duration: 125.313164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:53.679674Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764676753760173,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:44:54.096318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.025279479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:54.096508Z","caller":"traceutil/trace.go:171","msg":"trace[395395305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"2.025461827s","start":"2026-04-12T20:44:52.070934Z","end":"2026-04-12T20:44:54.096396Z","steps":["trace[395395305] 'range keys from in-memory index tree' (duration: 2.025132132s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:54.096563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:52.070912Z","time spent":"2.025639495s","remote":"127.0.0.1:38392","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-12T20:44:54.096648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.590470874s","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-12T20:44:54.096789Z","caller":"traceutil/trace.go:171","msg":"trace[454997024] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:656; }","duration":"1.590629121s","start":"2026-04-12T20:44:52.506138Z","end":"2026-04-12T20:44:54.096767Z","steps":["trace[454997024] 'range keys from in-memory index tree' (duration: 1.590453404s)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:54.098264Z","caller":"traceutil/trace.go:171","msg":"trace[2140483715] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"918.89086ms","start":"2026-04-12T20:44:53.179331Z","end":"2026-04-12T20:44:54.098222Z","steps":["trace[2140483715] 'read index received' (duration: 853.99589ms)","trace[2140483715] 'applied index is now lower than readState.Index' (duration: 64.89254ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:54.098358Z","caller":"traceutil/trace.go:171","msg":"trace[1455229889] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.041295114s","start":"2026-04-12T20:44:53.05704Z","end":"2026-04-12T20:44:54.098335Z","steps":["trace[1455229889] 'process raft request' (duration: 976.297889ms)","trace[1455229889] 'compare' (duration: 63.570543ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:54.098508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.179072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-12T20:44:54.098505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:53.05701Z","time spent":"1.041416729s","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:44:54.098568Z","caller":"traceutil/trace.go:171","msg":"trace[209677725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"919.271397ms","start":"2026-04-12T20:44:53.179281Z","end":"2026-04-12T20:44:54.098552Z","steps":["trace[209677725] 'agreement among raft nodes before linearized reading' (duration: 919.080418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:54.098605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:53.179264Z","time spent":"919.333749ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-12T20:44:54.105652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.75747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-12T20:44:54.105754Z","caller":"traceutil/trace.go:171","msg":"trace[1301239700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:658; }","duration":"898.902226ms","start":"2026-04-12T20:44:53.206837Z","end":"2026-04-12T20:44:54.105739Z","steps":["trace[1301239700] 'agreement among raft nodes before linearized reading' (duration: 898.656775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:54.105753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.027865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-12T20:44:54.105835Z","caller":"traceutil/trace.go:171","msg":"trace[662191345] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:658; }","duration":"872.150451ms","start":"2026-04-12T20:44:53.233667Z","end":"2026-04-12T20:44:54.105817Z","steps":["trace[662191345] 'agreement among raft nodes before linearized reading' (duration: 871.848477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:54.10588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:53.20682Z","time spent":"899.033293ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-12T20:44:54.106067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.643525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-12T20:44:54.106079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.876392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-12T20:44:54.106125Z","caller":"traceutil/trace.go:171","msg":"trace[1557069261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:658; }","duration":"897.934365ms","start":"2026-04-12T20:44:53.208171Z","end":"2026-04-12T20:44:54.106106Z","steps":["trace[1557069261] 'agreement among raft nodes before linearized reading' (duration: 897.751697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:54.106155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:53.20816Z","time spent":"897.989217ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-12T20:44:54.106775Z","caller":"traceutil/trace.go:171","msg":"trace[700098159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:658; }","duration":"856.389877ms","start":"2026-04-12T20:44:53.250355Z","end":"2026-04-12T20:44:54.106745Z","steps":["trace[700098159] 'agreement among raft nodes before linearized reading' (duration: 855.576992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:54.106853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:53.250324Z","time spent":"856.510302ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-12T20:44:54.105886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:53.23365Z","time spent":"872.227045ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-12T20:45:10.683873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764676753760385,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:45:11.184312Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764676753760385,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:45:11.648011Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.91545495s","expected-duration":"1s"} {"level":"info","ts":"2026-04-12T20:45:11.648331Z","caller":"traceutil/trace.go:171","msg":"trace[751822040] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"1.46492221s","start":"2026-04-12T20:45:10.183391Z","end":"2026-04-12T20:45:11.648313Z","steps":["trace[751822040] 'read index received' (duration: 1.464739972s)","trace[751822040] 'applied index is now lower than readState.Index' (duration: 181.588µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:45:11.64839Z","caller":"traceutil/trace.go:171","msg":"trace[1273944574] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"1.915938378s","start":"2026-04-12T20:45:09.732444Z","end":"2026-04-12T20:45:11.648383Z","steps":["trace[1273944574] 'process raft request' (duration: 1.915759641s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:11.648481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:09.73243Z","time spent":"1.915981919s","remote":"127.0.0.1:38596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:45:11.648667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458234408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-12T20:45:11.648702Z","caller":"traceutil/trace.go:171","msg":"trace[1338465889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:768; }","duration":"1.458282729s","start":"2026-04-12T20:45:10.19041Z","end":"2026-04-12T20:45:11.648692Z","steps":["trace[1338465889] 'agreement among raft nodes before linearized reading' (duration: 1.458145044s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:11.648693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459486002s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:45:11.648742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.465317013s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-12T20:45:11.648735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282267561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T20:45:11.648878Z","caller":"traceutil/trace.go:171","msg":"trace[1148367098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:768; }","duration":"1.465486749s","start":"2026-04-12T20:45:10.183349Z","end":"2026-04-12T20:45:11.648836Z","steps":["trace[1148367098] 'agreement among raft nodes before linearized reading' (duration: 1.465177778s)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:11.648766Z","caller":"traceutil/trace.go:171","msg":"trace[1896921682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"1.459567835s","start":"2026-04-12T20:45:10.189181Z","end":"2026-04-12T20:45:11.648749Z","steps":["trace[1896921682] 'agreement among raft nodes before linearized reading' (duration: 1.459456761s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:11.648926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:10.189134Z","time spent":"1.459786453s","remote":"127.0.0.1:38404","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-12T20:45:11.648724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:10.190403Z","time spent":"1.458316261s","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-12T20:45:11.648952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:10.183335Z","time spent":"1.465582163s","remote":"127.0.0.1:38654","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":"info","ts":"2026-04-12T20:45:11.649095Z","caller":"traceutil/trace.go:171","msg":"trace[1825463405] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:768; }","duration":"1.282496719s","start":"2026-04-12T20:45:10.366407Z","end":"2026-04-12T20:45:11.648903Z","steps":["trace[1825463405] 'agreement among raft nodes before linearized reading' (duration: 1.282172447s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:11.648779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458166495s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-12T20:45:11.649161Z","caller":"traceutil/trace.go:171","msg":"trace[1949893142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:768; }","duration":"1.45855727s","start":"2026-04-12T20:45:10.19057Z","end":"2026-04-12T20:45:11.649128Z","steps":["trace[1949893142] 'agreement among raft nodes before linearized reading' (duration: 1.458167456s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:11.649209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:10.190553Z","time spent":"1.458645392s","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-12T20:45:11.649214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:10.366391Z","time spent":"1.282733757s","remote":"127.0.0.1:38938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-12T20:45:11.649603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.461665332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-12T20:45:11.649638Z","caller":"traceutil/trace.go:171","msg":"trace[1363492336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"1.461737003s","start":"2026-04-12T20:45:10.187893Z","end":"2026-04-12T20:45:11.64963Z","steps":["trace[1363492336] 'agreement among raft nodes before linearized reading' (duration: 1.460560402s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:11.64966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:10.187883Z","time spent":"1.461772075s","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-12T20:45:11.649857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.964423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:11.649925Z","caller":"traceutil/trace.go:171","msg":"trace[1711165205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"578.095227ms","start":"2026-04-12T20:45:11.071814Z","end":"2026-04-12T20:45:11.649909Z","steps":["trace[1711165205] 'agreement among raft nodes before linearized reading' (duration: 577.725524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:11.649976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:11.071772Z","time spent":"578.19279ms","remote":"127.0.0.1:38392","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-12T20:45:11.650469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452248409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-12T20:45:11.676031Z","caller":"traceutil/trace.go:171","msg":"trace[1534257912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:768; }","duration":"1.477840648s","start":"2026-04-12T20:45:10.198182Z","end":"2026-04-12T20:45:11.676023Z","steps":["trace[1534257912] 'agreement among raft nodes before linearized reading' (duration: 1.450291151s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:11.676058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:10.198174Z","time spent":"1.477877039s","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}