{"level":"warn","ts":"2026-04-27T16:06:12.551041Z","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-27T16:06:12.551122Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.52: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.19.213.52:2380","--initial-cluster=instance=https://199.19.213.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.52: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-27T16:06:12.551184Z","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-27T16:06:12.551196Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.52:2380"]} {"level":"info","ts":"2026-04-27T16:06:12.551214Z","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-27T16:06:12.552421Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.52:2379"]} {"level":"info","ts":"2026-04-27T16:06:12.552521Z","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.19.213.52:2380"],"listen-peer-urls":["https://199.19.213.52:2380"],"advertise-client-urls":["https://199.19.213.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.52: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-27T16:06:12.558052Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.026865ms"} {"level":"info","ts":"2026-04-27T16:06:12.570704Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e1254347e028571c","cluster-id":"fc1f90ba5bf7591c"} {"level":"info","ts":"2026-04-27T16:06:12.57078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1254347e028571c switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T16:06:12.570799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1254347e028571c became follower at term 0"} {"level":"info","ts":"2026-04-27T16:06:12.570807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e1254347e028571c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T16:06:12.570819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1254347e028571c became follower at term 1"} {"level":"info","ts":"2026-04-27T16:06:12.570858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1254347e028571c switched to configuration voters=(16223447208654559004)"} {"level":"warn","ts":"2026-04-27T16:06:12.576404Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T16:06:12.577799Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T16:06:12.57903Z","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-27T16:06:12.581361Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e1254347e028571c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T16:06:12.581977Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e1254347e028571c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T16:06:12.582554Z","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-27T16:06:12.582662Z","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-27T16:06:12.582682Z","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-27T16:06:12.587853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1254347e028571c switched to configuration voters=(16223447208654559004)"} {"level":"info","ts":"2026-04-27T16:06:12.589367Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fc1f90ba5bf7591c","local-member-id":"e1254347e028571c","added-peer-id":"e1254347e028571c","added-peer-peer-urls":["https://199.19.213.52:2380"]} {"level":"info","ts":"2026-04-27T16:06:12.590318Z","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-27T16:06:12.590584Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e1254347e028571c","initial-advertise-peer-urls":["https://199.19.213.52:2380"],"listen-peer-urls":["https://199.19.213.52:2380"],"advertise-client-urls":["https://199.19.213.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T16:06:12.590616Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T16:06:12.590692Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.52:2380"} {"level":"info","ts":"2026-04-27T16:06:12.59071Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.52:2380"} {"level":"info","ts":"2026-04-27T16:06:12.971709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1254347e028571c is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T16:06:12.971777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1254347e028571c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T16:06:12.971845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1254347e028571c received MsgPreVoteResp from e1254347e028571c at term 1"} {"level":"info","ts":"2026-04-27T16:06:12.971888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1254347e028571c became candidate at term 2"} {"level":"info","ts":"2026-04-27T16:06:12.971963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1254347e028571c received MsgVoteResp from e1254347e028571c at term 2"} {"level":"info","ts":"2026-04-27T16:06:12.97199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1254347e028571c became leader at term 2"} {"level":"info","ts":"2026-04-27T16:06:12.97214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e1254347e028571c elected leader e1254347e028571c at term 2"} {"level":"info","ts":"2026-04-27T16:06:12.973451Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:06:12.973697Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e1254347e028571c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.52:2379]}","request-path":"/0/members/e1254347e028571c/attributes","cluster-id":"fc1f90ba5bf7591c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T16:06:12.973748Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T16:06:12.973705Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T16:06:12.974285Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T16:06:12.974368Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T16:06:12.975571Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fc1f90ba5bf7591c","local-member-id":"e1254347e028571c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:06:12.97571Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:06:12.975746Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:06:12.97763Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.52:2379"} {"level":"info","ts":"2026-04-27T16:06:12.978963Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T16:06:14.574956Z","caller":"traceutil/trace.go:171","msg":"trace[959468990] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"105.863568ms","start":"2026-04-27T16:06:14.469072Z","end":"2026-04-27T16:06:14.574936Z","steps":["trace[959468990] 'process raft request' (duration: 105.840857ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:14.57507Z","caller":"traceutil/trace.go:171","msg":"trace[1632219279] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"106.396623ms","start":"2026-04-27T16:06:14.468652Z","end":"2026-04-27T16:06:14.575049Z","steps":["trace[1632219279] 'process raft request' (duration: 106.221398ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:14.575099Z","caller":"traceutil/trace.go:171","msg":"trace[504230156] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"106.044172ms","start":"2026-04-27T16:06:14.469049Z","end":"2026-04-27T16:06:14.575093Z","steps":["trace[504230156] 'process raft request' (duration: 105.846036ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:14.574975Z","caller":"traceutil/trace.go:171","msg":"trace[1542019291] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"106.707642ms","start":"2026-04-27T16:06:14.468226Z","end":"2026-04-27T16:06:14.574933Z","steps":["trace[1542019291] 'process raft request' (duration: 93.169172ms)","trace[1542019291] 'compare' (duration: 13.325563ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:14.57518Z","caller":"traceutil/trace.go:171","msg":"trace[486267604] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"106.542857ms","start":"2026-04-27T16:06:14.46863Z","end":"2026-04-27T16:06:14.575173Z","steps":["trace[486267604] 'process raft request' (duration: 106.217808ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:32.671916Z","caller":"traceutil/trace.go:171","msg":"trace[266592105] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"181.340028ms","start":"2026-04-27T16:06:32.490556Z","end":"2026-04-27T16:06:32.671896Z","steps":["trace[266592105] 'process raft request' (duration: 181.220235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:33.979483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175434854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-27T16:06:33.979524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23644461s","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-27T16:06:33.979584Z","caller":"traceutil/trace.go:171","msg":"trace[881548176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:448; }","duration":"1.175584698s","start":"2026-04-27T16:06:32.803981Z","end":"2026-04-27T16:06:33.979566Z","steps":["trace[881548176] 'range keys from in-memory index tree' (duration: 1.175310121s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:33.979597Z","caller":"traceutil/trace.go:171","msg":"trace[138192892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:448; }","duration":"1.236561324s","start":"2026-04-27T16:06:32.743022Z","end":"2026-04-27T16:06:33.979583Z","steps":["trace[138192892] 'range keys from in-memory index tree' (duration: 1.236321607s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:33.979617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:32.803963Z","time spent":"1.175647021s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T16:06:33.979636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:32.743005Z","time spent":"1.236623406s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T16:06:33.979616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202083062s","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-27T16:06:33.979728Z","caller":"traceutil/trace.go:171","msg":"trace[491609835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:448; }","duration":"1.202242496s","start":"2026-04-27T16:06:32.777458Z","end":"2026-04-27T16:06:33.9797Z","steps":["trace[491609835] 'range keys from in-memory index tree' (duration: 1.201984449s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:33.979784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:32.777437Z","time spent":"1.202331409s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T16:06:33.979859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.017735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:06:33.980103Z","caller":"traceutil/trace.go:171","msg":"trace[468286486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"802.294723ms","start":"2026-04-27T16:06:33.177797Z","end":"2026-04-27T16:06:33.980091Z","steps":["trace[468286486] 'range keys from in-memory index tree' (duration: 801.919123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:33.980137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:33.177775Z","time spent":"802.352765ms","remote":"127.0.0.1:33022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T16:06:34.092932Z","caller":"traceutil/trace.go:171","msg":"trace[874508224] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"109.387119ms","start":"2026-04-27T16:06:33.98352Z","end":"2026-04-27T16:06:34.092907Z","steps":["trace[874508224] 'process raft request' (duration: 108.334178ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:34.093058Z","caller":"traceutil/trace.go:171","msg":"trace[1062108548] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"109.06871ms","start":"2026-04-27T16:06:33.983969Z","end":"2026-04-27T16:06:34.093038Z","steps":["trace[1062108548] 'process raft request' (duration: 108.902225ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:38.915264Z","caller":"traceutil/trace.go:171","msg":"trace[2120062288] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"216.652457ms","start":"2026-04-27T16:06:38.69856Z","end":"2026-04-27T16:06:38.915212Z","steps":["trace[2120062288] 'process raft request' (duration: 216.478372ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:42.330648Z","caller":"traceutil/trace.go:171","msg":"trace[1837149564] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"205.491125ms","start":"2026-04-27T16:06:42.125137Z","end":"2026-04-27T16:06:42.330628Z","steps":["trace[1837149564] 'read index received' (duration: 205.389432ms)","trace[1837149564] 'applied index is now lower than readState.Index' (duration: 100.453µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:42.330795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.46441ms","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-27T16:06:42.33076Z","caller":"traceutil/trace.go:171","msg":"trace[2124588625] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"245.415854ms","start":"2026-04-27T16:06:42.085312Z","end":"2026-04-27T16:06:42.330728Z","steps":["trace[2124588625] 'process raft request' (duration: 245.193598ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:42.330825Z","caller":"traceutil/trace.go:171","msg":"trace[877614026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"200.556613ms","start":"2026-04-27T16:06:42.13026Z","end":"2026-04-27T16:06:42.330816Z","steps":["trace[877614026] 'agreement among raft nodes before linearized reading' (duration: 200.487321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:42.33083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.680778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:06:42.330867Z","caller":"traceutil/trace.go:171","msg":"trace[794908425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"150.76711ms","start":"2026-04-27T16:06:42.180088Z","end":"2026-04-27T16:06:42.330856Z","steps":["trace[794908425] 'agreement among raft nodes before linearized reading' (duration: 150.706478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:42.330855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.686951ms","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-27T16:06:42.330944Z","caller":"traceutil/trace.go:171","msg":"trace[802334299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"205.810104ms","start":"2026-04-27T16:06:42.12511Z","end":"2026-04-27T16:06:42.33092Z","steps":["trace[802334299] 'agreement among raft nodes before linearized reading' (duration: 205.627718ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:45.855082Z","caller":"traceutil/trace.go:171","msg":"trace[397652889] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"141.294817ms","start":"2026-04-27T16:06:45.713756Z","end":"2026-04-27T16:06:45.855051Z","steps":["trace[397652889] 'process raft request' (duration: 138.720873ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:45.860792Z","caller":"traceutil/trace.go:171","msg":"trace[970154216] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"143.941914ms","start":"2026-04-27T16:06:45.716819Z","end":"2026-04-27T16:06:45.860761Z","steps":["trace[970154216] 'process raft request' (duration: 143.628845ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:45.860968Z","caller":"traceutil/trace.go:171","msg":"trace[1809085835] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"139.488905ms","start":"2026-04-27T16:06:45.721456Z","end":"2026-04-27T16:06:45.860945Z","steps":["trace[1809085835] 'process raft request' (duration: 139.206217ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:47.383803Z","caller":"traceutil/trace.go:171","msg":"trace[219403600] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"223.630697ms","start":"2026-04-27T16:06:47.160146Z","end":"2026-04-27T16:06:47.383777Z","steps":["trace[219403600] 'read index received' (duration: 216.932214ms)","trace[219403600] 'applied index is now lower than readState.Index' (duration: 6.697793ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:47.383884Z","caller":"traceutil/trace.go:171","msg":"trace[276426387] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"257.549874ms","start":"2026-04-27T16:06:47.126328Z","end":"2026-04-27T16:06:47.383878Z","steps":["trace[276426387] 'process raft request' (duration: 250.664356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:47.384266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.07816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-04-27T16:06:47.384335Z","caller":"traceutil/trace.go:171","msg":"trace[1885924837] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:604; }","duration":"224.179412ms","start":"2026-04-27T16:06:47.160141Z","end":"2026-04-27T16:06:47.38432Z","steps":["trace[1885924837] 'agreement among raft nodes before linearized reading' (duration: 223.820352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:47.384681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.021547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:06:47.384714Z","caller":"traceutil/trace.go:171","msg":"trace[1664102825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"208.09398ms","start":"2026-04-27T16:06:47.176613Z","end":"2026-04-27T16:06:47.384707Z","steps":["trace[1664102825] 'agreement among raft nodes before linearized reading' (duration: 208.017717ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:47.384672Z","caller":"traceutil/trace.go:171","msg":"trace[1775143119] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"182.996648ms","start":"2026-04-27T16:06:47.201645Z","end":"2026-04-27T16:06:47.384642Z","steps":["trace[1775143119] 'process raft request' (duration: 182.847273ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:47.384845Z","caller":"traceutil/trace.go:171","msg":"trace[1184572158] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"144.204771ms","start":"2026-04-27T16:06:47.240631Z","end":"2026-04-27T16:06:47.384836Z","steps":["trace[1184572158] 'process raft request' (duration: 143.972845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.342298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.226427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:06:48.342422Z","caller":"traceutil/trace.go:171","msg":"trace[1545848109] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"934.823858ms","start":"2026-04-27T16:06:47.40758Z","end":"2026-04-27T16:06:48.342404Z","steps":["trace[1545848109] 'process raft request' (duration: 473.742446ms)","trace[1545848109] 'compare' (duration: 460.109764ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:48.34249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:47.407547Z","time spent":"934.909231ms","remote":"127.0.0.1:33062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:06:48.345211Z","caller":"traceutil/trace.go:171","msg":"trace[365947459] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"937.29264ms","start":"2026-04-27T16:06:47.407902Z","end":"2026-04-27T16:06:48.345195Z","steps":["trace[365947459] 'process raft request' (duration: 937.172426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.34531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:47.407893Z","time spent":"937.372972ms","remote":"127.0.0.1:33062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:06:48.345501Z","caller":"traceutil/trace.go:171","msg":"trace[319659035] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"705.360223ms","start":"2026-04-27T16:06:47.640127Z","end":"2026-04-27T16:06:48.345488Z","steps":["trace[319659035] 'process raft request' (duration: 705.239239ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:48.345496Z","caller":"traceutil/trace.go:171","msg":"trace[565416040] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:626; }","duration":"879.029202ms","start":"2026-04-27T16:06:47.466448Z","end":"2026-04-27T16:06:48.345477Z","steps":["trace[565416040] 'read index received' (duration: 414.882982ms)","trace[565416040] 'applied index is now lower than readState.Index' (duration: 464.14484ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:48.345574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:47.640106Z","time spent":"705.429695ms","remote":"127.0.0.1:33062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:06:48.345686Z","caller":"traceutil/trace.go:171","msg":"trace[1347809993] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"904.818915ms","start":"2026-04-27T16:06:47.440825Z","end":"2026-04-27T16:06:48.345644Z","steps":["trace[1347809993] 'process raft request' (duration: 904.341091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.345726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.299489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-04-27T16:06:48.345849Z","caller":"traceutil/trace.go:171","msg":"trace[905209673] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:612; }","duration":"879.451974ms","start":"2026-04-27T16:06:47.466383Z","end":"2026-04-27T16:06:48.345835Z","steps":["trace[905209673] 'agreement among raft nodes before linearized reading' (duration: 879.201077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.34588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:47.440801Z","time spent":"904.963259ms","remote":"127.0.0.1:33062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:06:48.345904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:47.466368Z","time spent":"879.526807ms","remote":"127.0.0.1:33062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":15712,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-04-27T16:06:48.346474Z","caller":"traceutil/trace.go:171","msg":"trace[958360539] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"503.609276ms","start":"2026-04-27T16:06:47.841929Z","end":"2026-04-27T16:06:48.345539Z","steps":["trace[958360539] 'process raft request' (duration: 503.501293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.346555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:47.841885Z","time spent":"504.627676ms","remote":"127.0.0.1:33062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:06:48.35086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.630858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:06:48.350933Z","caller":"traceutil/trace.go:171","msg":"trace[33329540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"173.747161ms","start":"2026-04-27T16:06:48.177168Z","end":"2026-04-27T16:06:48.350915Z","steps":["trace[33329540] 'agreement among raft nodes before linearized reading' (duration: 173.622178ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:48.351404Z","caller":"traceutil/trace.go:171","msg":"trace[430393665] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"305.695139ms","start":"2026-04-27T16:06:48.045691Z","end":"2026-04-27T16:06:48.351386Z","steps":["trace[430393665] 'process raft request' (duration: 304.960248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.351499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:48.045647Z","time spent":"305.807383ms","remote":"127.0.0.1:33062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:06:48.351564Z","caller":"traceutil/trace.go:171","msg":"trace[96464374] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"108.929645ms","start":"2026-04-27T16:06:48.242604Z","end":"2026-04-27T16:06:48.351534Z","steps":["trace[96464374] 'process raft request' (duration: 108.145382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:55.479224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6277065495999897552,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:06:55.897946Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.004826544s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T16:06:55.89838Z","caller":"traceutil/trace.go:171","msg":"trace[1185392951] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"1.005333198s","start":"2026-04-27T16:06:54.893022Z","end":"2026-04-27T16:06:55.898355Z","steps":["trace[1185392951] 'process raft request' (duration: 1.005165983s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:55.898549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:54.892997Z","time spent":"1.005468212s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:06:55.969275Z","caller":"traceutil/trace.go:171","msg":"trace[538080483] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:702; }","duration":"990.441899ms","start":"2026-04-27T16:06:54.9788Z","end":"2026-04-27T16:06:55.969242Z","steps":["trace[538080483] 'read index received' (duration: 919.374204ms)","trace[538080483] 'applied index is now lower than readState.Index' (duration: 71.066285ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:55.969289Z","caller":"traceutil/trace.go:171","msg":"trace[633820102] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"583.786285ms","start":"2026-04-27T16:06:55.385469Z","end":"2026-04-27T16:06:55.969255Z","steps":["trace[633820102] 'process raft request' (duration: 549.829657ms)","trace[633820102] 'compare' (duration: 33.806033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:55.969456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:55.385445Z","time spent":"583.924438ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:06:55.969472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.757221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:06:55.969526Z","caller":"traceutil/trace.go:171","msg":"trace[1707943936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:684; }","duration":"790.840634ms","start":"2026-04-27T16:06:55.178671Z","end":"2026-04-27T16:06:55.969511Z","steps":["trace[1707943936] 'agreement among raft nodes before linearized reading' (duration: 790.752151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:55.96955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.739639ms","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-27T16:06:55.969559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:55.178654Z","time spent":"790.897966ms","remote":"127.0.0.1:33022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T16:06:55.969608Z","caller":"traceutil/trace.go:171","msg":"trace[1327743640] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:684; }","duration":"990.82884ms","start":"2026-04-27T16:06:54.978763Z","end":"2026-04-27T16:06:55.969592Z","steps":["trace[1327743640] 'agreement among raft nodes before linearized reading' (duration: 990.612794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:55.969585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.450185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jmpgx\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-27T16:06:55.969803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:54.978673Z","time spent":"991.111689ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T16:06:55.970176Z","caller":"traceutil/trace.go:171","msg":"trace[1000418698] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jmpgx; range_end:; response_count:1; response_revision:684; }","duration":"953.827535ms","start":"2026-04-27T16:06:55.016043Z","end":"2026-04-27T16:06:55.96987Z","steps":["trace[1000418698] 'agreement among raft nodes before linearized reading' (duration: 953.368452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:55.971124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:55.016022Z","time spent":"955.031181ms","remote":"127.0.0.1:33192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-jmpgx\" "} {"level":"info","ts":"2026-04-27T16:06:56.11427Z","caller":"traceutil/trace.go:171","msg":"trace[1346560279] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"129.724054ms","start":"2026-04-27T16:06:55.984509Z","end":"2026-04-27T16:06:56.114233Z","steps":["trace[1346560279] 'process raft request' (duration: 121.93866ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:56.661673Z","caller":"traceutil/trace.go:171","msg":"trace[280761451] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"208.07406ms","start":"2026-04-27T16:06:56.45357Z","end":"2026-04-27T16:06:56.661644Z","steps":["trace[280761451] 'process raft request' (duration: 207.824572ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:56.864771Z","caller":"traceutil/trace.go:171","msg":"trace[661164944] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:708; }","duration":"349.272114ms","start":"2026-04-27T16:06:56.515476Z","end":"2026-04-27T16:06:56.864748Z","steps":["trace[661164944] 'read index received' (duration: 145.868479ms)","trace[661164944] 'applied index is now lower than readState.Index' (duration: 203.402515ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:56.864861Z","caller":"traceutil/trace.go:171","msg":"trace[1649856776] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"408.471908ms","start":"2026-04-27T16:06:56.456353Z","end":"2026-04-27T16:06:56.864825Z","steps":["trace[1649856776] 'process raft request' (duration: 393.24517ms)","trace[1649856776] 'compare' (duration: 14.916089ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:56.86495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.47489ms","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-27T16:06:56.865002Z","caller":"traceutil/trace.go:171","msg":"trace[754046984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:689; }","duration":"349.584493ms","start":"2026-04-27T16:06:56.515401Z","end":"2026-04-27T16:06:56.864986Z","steps":["trace[754046984] 'agreement among raft nodes before linearized reading' (duration: 349.433378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:56.865019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:56.456313Z","time spent":"408.623272ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:06:56.865058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:56.515367Z","time spent":"349.681255ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T16:06:59.166798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6277065495999897576,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:06:59.292065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.112400632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:06:59.292116Z","caller":"traceutil/trace.go:171","msg":"trace[1411751156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"2.112489015s","start":"2026-04-27T16:06:57.179612Z","end":"2026-04-27T16:06:59.292102Z","steps":["trace[1411751156] 'range keys from in-memory index tree' (duration: 2.112334931s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:59.292138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:57.179591Z","time spent":"2.112542107s","remote":"127.0.0.1:33022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:06:59.292321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259963947s","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-27T16:06:59.29239Z","caller":"traceutil/trace.go:171","msg":"trace[493818098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:690; }","duration":"1.26006999s","start":"2026-04-27T16:06:58.032302Z","end":"2026-04-27T16:06:59.292372Z","steps":["trace[493818098] 'range keys from in-memory index tree' (duration: 1.259553386s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:59.292436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:58.03228Z","time spent":"1.260143613s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:06:59.291609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:58.07544Z","time spent":"1.216153256s","remote":"127.0.0.1:42134","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-27T16:06:59.292572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.389553507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T16:06:59.292638Z","caller":"traceutil/trace.go:171","msg":"trace[795433713] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:690; }","duration":"1.389652841s","start":"2026-04-27T16:06:57.902966Z","end":"2026-04-27T16:06:59.292618Z","steps":["trace[795433713] 'range keys from in-memory index tree' (duration: 1.389272859s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:59.292679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:57.902949Z","time spent":"1.389720752s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-27T16:06:59.293734Z","caller":"traceutil/trace.go:171","msg":"trace[193284719] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"880.025641ms","start":"2026-04-27T16:06:58.413689Z","end":"2026-04-27T16:06:59.293715Z","steps":["trace[193284719] 'process raft request' (duration: 879.930438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:59.293836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:58.413656Z","time spent":"880.130004ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:06:59.294316Z","caller":"traceutil/trace.go:171","msg":"trace[680691234] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:712; }","duration":"628.515422ms","start":"2026-04-27T16:06:58.665781Z","end":"2026-04-27T16:06:59.294296Z","steps":["trace[680691234] 'read index received' (duration: 628.509382ms)","trace[680691234] 'applied index is now lower than readState.Index' (duration: 4.89µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:59.294587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.80038ms","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-27T16:06:59.294658Z","caller":"traceutil/trace.go:171","msg":"trace[913290806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:691; }","duration":"628.906183ms","start":"2026-04-27T16:06:58.665733Z","end":"2026-04-27T16:06:59.294639Z","steps":["trace[913290806] 'agreement among raft nodes before linearized reading' (duration: 628.718197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:59.294551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.334391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:06:59.294701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:58.665705Z","time spent":"628.986085ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T16:06:59.294713Z","caller":"traceutil/trace.go:171","msg":"trace[1825565050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"114.525406ms","start":"2026-04-27T16:06:59.180169Z","end":"2026-04-27T16:06:59.294695Z","steps":["trace[1825565050] 'agreement among raft nodes before linearized reading' (duration: 114.334941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:59.294806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.148405ms","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-27T16:06:59.294843Z","caller":"traceutil/trace.go:171","msg":"trace[434621659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:691; }","duration":"411.243077ms","start":"2026-04-27T16:06:58.883589Z","end":"2026-04-27T16:06:59.294832Z","steps":["trace[434621659] 'agreement among raft nodes before linearized reading' (duration: 411.110133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:59.294865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:58.883566Z","time spent":"411.294449ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T16:06:59.295675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.17147ms","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-27T16:06:59.295737Z","caller":"traceutil/trace.go:171","msg":"trace[269375639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:691; }","duration":"424.274313ms","start":"2026-04-27T16:06:58.871447Z","end":"2026-04-27T16:06:59.295722Z","steps":["trace[269375639] 'agreement among raft nodes before linearized reading' (duration: 422.940104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:59.295869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:58.871425Z","time spent":"424.430457ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T16:07:09.984276Z","caller":"traceutil/trace.go:171","msg":"trace[1317882997] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"115.709151ms","start":"2026-04-27T16:07:09.868547Z","end":"2026-04-27T16:07:09.984257Z","steps":["trace[1317882997] 'process raft request' (duration: 115.550385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:16.60786Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6277065495999897798,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:07:17.108559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6277065495999897798,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:07:17.430869Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.403099936s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-27T16:07:17.431178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:16.027591Z","time spent":"1.403582822s","remote":"127.0.0.1:33038","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-27T16:07:17.450241Z","caller":"traceutil/trace.go:171","msg":"trace[1928177355] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:835; }","duration":"1.342970726s","start":"2026-04-27T16:07:16.107244Z","end":"2026-04-27T16:07:17.450214Z","steps":["trace[1928177355] 'read index received' (duration: 1.323892011s)","trace[1928177355] 'applied index is now lower than readState.Index' (duration: 19.077615ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:07:17.450278Z","caller":"traceutil/trace.go:171","msg":"trace[152703380] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"1.312341262s","start":"2026-04-27T16:07:16.137908Z","end":"2026-04-27T16:07:17.45025Z","steps":["trace[152703380] 'process raft request' (duration: 1.312067173s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:17.450381Z","caller":"traceutil/trace.go:171","msg":"trace[2002233027] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"1.220481163s","start":"2026-04-27T16:07:16.229874Z","end":"2026-04-27T16:07:17.450355Z","steps":["trace[2002233027] 'process raft request' (duration: 1.220294307s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:17.450415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:16.137885Z","time spent":"1.312453836s","remote":"127.0.0.1:33244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:07:17.450469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343204553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:07:17.450537Z","caller":"traceutil/trace.go:171","msg":"trace[505119200] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:812; }","duration":"1.343324377s","start":"2026-04-27T16:07:16.107198Z","end":"2026-04-27T16:07:17.450523Z","steps":["trace[505119200] 'agreement among raft nodes before linearized reading' (duration: 1.343181403s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:17.45055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:16.229846Z","time spent":"1.220604937s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:07:17.450573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:16.107181Z","time spent":"1.3433849s","remote":"127.0.0.1:33366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:07:17.453977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.939281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:07:17.453989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273950555s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:07:17.454028Z","caller":"traceutil/trace.go:171","msg":"trace[801829330] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:813; }","duration":"403.031734ms","start":"2026-04-27T16:07:17.050983Z","end":"2026-04-27T16:07:17.454015Z","steps":["trace[801829330] 'agreement among raft nodes before linearized reading' (duration: 402.92592ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:17.454105Z","caller":"traceutil/trace.go:171","msg":"trace[1530300194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:813; }","duration":"1.2741158s","start":"2026-04-27T16:07:16.17997Z","end":"2026-04-27T16:07:17.454086Z","steps":["trace[1530300194] 'agreement among raft nodes before linearized reading' (duration: 1.273906403s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:17.454187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:16.179951Z","time spent":"1.274224834s","remote":"127.0.0.1:33022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:07:17.454223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:17.050964Z","time spent":"403.23809ms","remote":"127.0.0.1:33310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:07:52.026922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.032391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:07:52.027048Z","caller":"traceutil/trace.go:171","msg":"trace[945129443] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"202.042959ms","start":"2026-04-27T16:07:51.824985Z","end":"2026-04-27T16:07:52.027028Z","steps":["trace[945129443] 'process raft request' (duration: 27.804671ms)","trace[945129443] 'compare' (duration: 173.843355ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:07:52.198108Z","caller":"traceutil/trace.go:171","msg":"trace[2042010526] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"373.060141ms","start":"2026-04-27T16:07:51.825026Z","end":"2026-04-27T16:07:52.198087Z","steps":["trace[2042010526] 'process raft request' (duration: 323.089034ms)","trace[2042010526] 'compare' (duration: 49.866034ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:07:52.198233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:51.825007Z","time spent":"373.162535ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:07:53.430821Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.054500985s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-27T16:07:53.874515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.385555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:07:53.874624Z","caller":"traceutil/trace.go:171","msg":"trace[1721720938] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"696.038162ms","start":"2026-04-27T16:07:53.178568Z","end":"2026-04-27T16:07:53.874606Z","steps":["trace[1721720938] 'read index received' (duration: 252.361417ms)","trace[1721720938] 'applied index is now lower than readState.Index' (duration: 443.675475ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:07:53.87464Z","caller":"traceutil/trace.go:171","msg":"trace[1035521439] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"1.498392107s","start":"2026-04-27T16:07:52.376221Z","end":"2026-04-27T16:07:53.874613Z","steps":["trace[1035521439] 'process raft request' (duration: 1.054811695s)","trace[1035521439] 'compare' (duration: 443.22158ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:07:53.874731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.170556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:07:53.874759Z","caller":"traceutil/trace.go:171","msg":"trace[1974669621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1031; }","duration":"696.237988ms","start":"2026-04-27T16:07:53.178512Z","end":"2026-04-27T16:07:53.87475Z","steps":["trace[1974669621] 'agreement among raft nodes before linearized reading' (duration: 696.184267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:53.87476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:52.376201Z","time spent":"1.49849155s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:07:53.87479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:53.178489Z","time spent":"696.29348ms","remote":"127.0.0.1:33022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:07:53.875089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.472845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:07:53.875134Z","caller":"traceutil/trace.go:171","msg":"trace[411172851] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1031; }","duration":"507.555227ms","start":"2026-04-27T16:07:53.367566Z","end":"2026-04-27T16:07:53.875121Z","steps":["trace[411172851] 'agreement among raft nodes before linearized reading' (duration: 507.476295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:53.875185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:53.367549Z","time spent":"507.61555ms","remote":"127.0.0.1:33462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:07:53.875512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.138749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:07:53.875538Z","caller":"traceutil/trace.go:171","msg":"trace[1197983655] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1031; }","duration":"498.16609ms","start":"2026-04-27T16:07:53.377363Z","end":"2026-04-27T16:07:53.87553Z","steps":["trace[1197983655] 'agreement among raft nodes before linearized reading' (duration: 498.119568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:53.87556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:53.377303Z","time spent":"498.250323ms","remote":"127.0.0.1:33226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:07:54.531909Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6277065495999898267,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T16:07:54.696509Z","caller":"traceutil/trace.go:171","msg":"trace[260338507] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1066; }","duration":"665.42736ms","start":"2026-04-27T16:07:54.031058Z","end":"2026-04-27T16:07:54.696486Z","steps":["trace[260338507] 'read index received' (duration: 663.860528ms)","trace[260338507] 'applied index is now lower than readState.Index' (duration: 1.566072ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:07:54.696881Z","caller":"traceutil/trace.go:171","msg":"trace[1305286960] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"818.503474ms","start":"2026-04-27T16:07:53.878363Z","end":"2026-04-27T16:07:54.696867Z","steps":["trace[1305286960] 'process raft request' (duration: 816.53901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:54.696994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:53.878338Z","time spent":"818.585587ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:07:54.697062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.990628ms","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-27T16:07:54.697121Z","caller":"traceutil/trace.go:171","msg":"trace[305420460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1034; }","duration":"666.082811ms","start":"2026-04-27T16:07:54.031022Z","end":"2026-04-27T16:07:54.697105Z","steps":["trace[305420460] 'agreement among raft nodes before linearized reading' (duration: 665.900045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:54.697166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:54.031005Z","time spent":"666.150294ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T16:07:54.697175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.240634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T16:07:54.697202Z","caller":"traceutil/trace.go:171","msg":"trace[1260428848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1034; }","duration":"495.295626ms","start":"2026-04-27T16:07:54.201898Z","end":"2026-04-27T16:07:54.697194Z","steps":["trace[1260428848] 'agreement among raft nodes before linearized reading' (duration: 495.223803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:54.697222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:54.201879Z","time spent":"495.337358ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T16:07:54.697296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.538663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:07:54.697317Z","caller":"traceutil/trace.go:171","msg":"trace[607282095] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1034; }","duration":"491.594414ms","start":"2026-04-27T16:07:54.205712Z","end":"2026-04-27T16:07:54.697307Z","steps":["trace[607282095] 'agreement among raft nodes before linearized reading' (duration: 491.559953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:54.697411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:54.205691Z","time spent":"491.703508ms","remote":"127.0.0.1:42098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:07:54.697444Z","caller":"traceutil/trace.go:171","msg":"trace[316899852] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"818.930508ms","start":"2026-04-27T16:07:53.878499Z","end":"2026-04-27T16:07:54.69743Z","steps":["trace[316899852] 'process raft request' (duration: 817.862774ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:54.697486Z","caller":"traceutil/trace.go:171","msg":"trace[220014653] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"818.206155ms","start":"2026-04-27T16:07:53.879259Z","end":"2026-04-27T16:07:54.697465Z","steps":["trace[220014653] 'process raft request' (duration: 817.177801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:54.697625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:53.879252Z","time spent":"818.309898ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:07:54.697514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:53.878491Z","time spent":"818.99006ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:18.710316Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.800106582s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T16:08:18.728768Z","caller":"traceutil/trace.go:171","msg":"trace[2087660803] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1144; }","duration":"1.882304394s","start":"2026-04-27T16:08:16.846444Z","end":"2026-04-27T16:08:18.728748Z","steps":["trace[2087660803] 'read index received' (duration: 45.339715ms)","trace[2087660803] 'applied index is now lower than readState.Index' (duration: 1.836963789s)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:18.729029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.882585363s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-27T16:08:18.729013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.878189609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-27T16:08:18.729051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.214252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:18.729089Z","caller":"traceutil/trace.go:171","msg":"trace[2106739039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1107; }","duration":"1.882702276s","start":"2026-04-27T16:08:16.846373Z","end":"2026-04-27T16:08:18.729075Z","steps":["trace[2106739039] 'agreement among raft nodes before linearized reading' (duration: 1.882467939s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:18.729101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.864831002s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T16:08:18.729119Z","caller":"traceutil/trace.go:171","msg":"trace[815959855] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1107; }","duration":"1.878318713s","start":"2026-04-27T16:08:16.850779Z","end":"2026-04-27T16:08:18.729098Z","steps":["trace[815959855] 'agreement among raft nodes before linearized reading' (duration: 1.878143167s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:18.729135Z","caller":"traceutil/trace.go:171","msg":"trace[1788234632] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1107; }","duration":"853.296574ms","start":"2026-04-27T16:08:17.875808Z","end":"2026-04-27T16:08:18.729104Z","steps":["trace[1788234632] 'agreement among raft nodes before linearized reading' (duration: 853.181711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:18.729158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.550850636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:08:18.729172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:17.875653Z","time spent":"853.511581ms","remote":"127.0.0.1:58992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:08:18.729168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:16.850744Z","time spent":"1.878413187s","remote":"127.0.0.1:33444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:08:18.729177Z","caller":"traceutil/trace.go:171","msg":"trace[658383551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1107; }","duration":"1.550903728s","start":"2026-04-27T16:08:17.178267Z","end":"2026-04-27T16:08:18.729171Z","steps":["trace[658383551] 'agreement among raft nodes before linearized reading' (duration: 1.550877367s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:18.729217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:17.178247Z","time spent":"1.550963699s","remote":"127.0.0.1:33008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:08:18.729259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.868188351s","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-27T16:08:18.729281Z","caller":"traceutil/trace.go:171","msg":"trace[841792777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1107; }","duration":"1.868238243s","start":"2026-04-27T16:08:16.861037Z","end":"2026-04-27T16:08:18.729275Z","steps":["trace[841792777] 'agreement among raft nodes before linearized reading' (duration: 1.868171131s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:18.729302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:16.861022Z","time spent":"1.868272375s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T16:08:18.729322Z","caller":"traceutil/trace.go:171","msg":"trace[1913862454] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"705.293745ms","start":"2026-04-27T16:08:18.024019Z","end":"2026-04-27T16:08:18.729313Z","steps":["trace[1913862454] 'process raft request' (duration: 704.693726ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:18.729133Z","caller":"traceutil/trace.go:171","msg":"trace[2027954328] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1107; }","duration":"1.864897684s","start":"2026-04-27T16:08:16.864226Z","end":"2026-04-27T16:08:18.729124Z","steps":["trace[2027954328] 'agreement among raft nodes before linearized reading' (duration: 1.86477971s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:18.729409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:18.023992Z","time spent":"705.349537ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:18.729405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:16.864209Z","time spent":"1.865181973s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T16:08:18.729419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.566509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:18.729446Z","caller":"traceutil/trace.go:171","msg":"trace[349258774] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1107; }","duration":"772.633491ms","start":"2026-04-27T16:08:17.956805Z","end":"2026-04-27T16:08:18.729439Z","steps":["trace[349258774] 'agreement among raft nodes before linearized reading' (duration: 772.59021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:18.729466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:17.956768Z","time spent":"772.692793ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:08:18.729127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:16.846351Z","time spent":"1.882770349s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T16:08:18.728862Z","caller":"traceutil/trace.go:171","msg":"trace[1007061942] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"1.898063191s","start":"2026-04-27T16:08:16.830781Z","end":"2026-04-27T16:08:18.728844Z","steps":["trace[1007061942] 'process raft request' (duration: 1.879837304s)","trace[1007061942] 'compare' (duration: 17.7069ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:18.730424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:16.830766Z","time spent":"1.899561219s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:20.403049Z","caller":"traceutil/trace.go:171","msg":"trace[957231409] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"215.599803ms","start":"2026-04-27T16:08:20.187425Z","end":"2026-04-27T16:08:20.403025Z","steps":["trace[957231409] 'process raft request' (duration: 215.307464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:20.836479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.042281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:20.836549Z","caller":"traceutil/trace.go:171","msg":"trace[1799106134] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1113; }","duration":"152.172845ms","start":"2026-04-27T16:08:20.684362Z","end":"2026-04-27T16:08:20.836534Z","steps":["trace[1799106134] 'count revisions from in-memory index tree' (duration: 151.967258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:20.836737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.068717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:20.836818Z","caller":"traceutil/trace.go:171","msg":"trace[15900510] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1113; }","duration":"103.354536ms","start":"2026-04-27T16:08:20.733443Z","end":"2026-04-27T16:08:20.836797Z","steps":["trace[15900510] 'count revisions from in-memory index tree' (duration: 102.974074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:20.836794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.820266ms","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-27T16:08:20.836901Z","caller":"traceutil/trace.go:171","msg":"trace[774858987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1113; }","duration":"102.002622ms","start":"2026-04-27T16:08:20.734879Z","end":"2026-04-27T16:08:20.836882Z","steps":["trace[774858987] 'range keys from in-memory index tree' (duration: 101.587128ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:20.836951Z","caller":"traceutil/trace.go:171","msg":"trace[1768338977] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"100.808972ms","start":"2026-04-27T16:08:20.736132Z","end":"2026-04-27T16:08:20.836941Z","steps":["trace[1768338977] 'process raft request' (duration: 45.081007ms)","trace[1768338977] 'compare' (duration: 55.218499ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:21.029156Z","caller":"traceutil/trace.go:171","msg":"trace[433426272] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"185.750186ms","start":"2026-04-27T16:08:20.843381Z","end":"2026-04-27T16:08:21.029131Z","steps":["trace[433426272] 'process raft request' (duration: 144.876417ms)","trace[433426272] 'compare' (duration: 40.718563ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:21.02949Z","caller":"traceutil/trace.go:171","msg":"trace[449317334] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"184.447592ms","start":"2026-04-27T16:08:20.845006Z","end":"2026-04-27T16:08:21.029453Z","steps":["trace[449317334] 'process raft request' (duration: 184.08077ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:21.029538Z","caller":"traceutil/trace.go:171","msg":"trace[1887362183] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1155; }","duration":"104.248345ms","start":"2026-04-27T16:08:20.925255Z","end":"2026-04-27T16:08:21.029503Z","steps":["trace[1887362183] 'read index received' (duration: 62.960712ms)","trace[1887362183] 'applied index is now lower than readState.Index' (duration: 41.282183ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:21.029562Z","caller":"traceutil/trace.go:171","msg":"trace[1570922302] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"184.023369ms","start":"2026-04-27T16:08:20.845512Z","end":"2026-04-27T16:08:21.029536Z","steps":["trace[1570922302] 'process raft request' (duration: 183.830212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:21.029849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.576306ms","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-27T16:08:21.029915Z","caller":"traceutil/trace.go:171","msg":"trace[921579859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1118; }","duration":"104.6852ms","start":"2026-04-27T16:08:20.925215Z","end":"2026-04-27T16:08:21.0299Z","steps":["trace[921579859] 'agreement among raft nodes before linearized reading' (duration: 104.37423ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:22.990947Z","caller":"traceutil/trace.go:171","msg":"trace[1299032429] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1160; }","duration":"108.193774ms","start":"2026-04-27T16:08:22.88273Z","end":"2026-04-27T16:08:22.990923Z","steps":["trace[1299032429] 'read index received' (duration: 108.06831ms)","trace[1299032429] 'applied index is now lower than readState.Index' (duration: 124.594µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:22.991017Z","caller":"traceutil/trace.go:171","msg":"trace[428800664] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"135.860051ms","start":"2026-04-27T16:08:22.855123Z","end":"2026-04-27T16:08:22.990983Z","steps":["trace[428800664] 'process raft request' (duration: 135.655694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:22.991141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.400931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T16:08:22.99118Z","caller":"traceutil/trace.go:171","msg":"trace[1495971376] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1120; }","duration":"108.496394ms","start":"2026-04-27T16:08:22.882673Z","end":"2026-04-27T16:08:22.991169Z","steps":["trace[1495971376] 'agreement among raft nodes before linearized reading' (duration: 108.346489ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:23.282016Z","caller":"traceutil/trace.go:171","msg":"trace[815533608] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"207.680153ms","start":"2026-04-27T16:08:23.074306Z","end":"2026-04-27T16:08:23.281987Z","steps":["trace[815533608] 'process raft request' (duration: 131.685674ms)","trace[815533608] 'compare' (duration: 75.882046ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:23.28638Z","caller":"traceutil/trace.go:171","msg":"trace[1966804674] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"212.031486ms","start":"2026-04-27T16:08:23.074338Z","end":"2026-04-27T16:08:23.286369Z","steps":["trace[1966804674] 'process raft request' (duration: 211.935142ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:23.286741Z","caller":"traceutil/trace.go:171","msg":"trace[1898165648] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"211.959744ms","start":"2026-04-27T16:08:23.074758Z","end":"2026-04-27T16:08:23.286718Z","steps":["trace[1898165648] 'process raft request' (duration: 211.583712ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:23.287252Z","caller":"traceutil/trace.go:171","msg":"trace[2059863734] linearizableReadLoop","detail":"{readStateIndex:1166; appliedIndex:1162; }","duration":"108.144893ms","start":"2026-04-27T16:08:23.179061Z","end":"2026-04-27T16:08:23.287206Z","steps":["trace[2059863734] 'read index received' (duration: 27.078437ms)","trace[2059863734] 'applied index is now lower than readState.Index' (duration: 81.065776ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:23.287481Z","caller":"traceutil/trace.go:171","msg":"trace[397506520] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"212.503082ms","start":"2026-04-27T16:08:23.074965Z","end":"2026-04-27T16:08:23.287468Z","steps":["trace[397506520] 'process raft request' (duration: 211.672004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:23.288109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.041522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:23.288157Z","caller":"traceutil/trace.go:171","msg":"trace[73283987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1125; }","duration":"109.140506ms","start":"2026-04-27T16:08:23.179008Z","end":"2026-04-27T16:08:23.288148Z","steps":["trace[73283987] 'agreement among raft nodes before linearized reading' (duration: 108.279478ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:33.450102Z","caller":"traceutil/trace.go:171","msg":"trace[1266582368] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1250; }","duration":"116.821888ms","start":"2026-04-27T16:08:33.333253Z","end":"2026-04-27T16:08:33.450075Z","steps":["trace[1266582368] 'read index received' (duration: 116.747615ms)","trace[1266582368] 'applied index is now lower than readState.Index' (duration: 62.222µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:33.450256Z","caller":"traceutil/trace.go:171","msg":"trace[1532516209] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"119.595668ms","start":"2026-04-27T16:08:33.330646Z","end":"2026-04-27T16:08:33.450241Z","steps":["trace[1532516209] 'process raft request' (duration: 119.175864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:33.450519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.240131ms","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-27T16:08:33.450568Z","caller":"traceutil/trace.go:171","msg":"trace[172370976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1206; }","duration":"117.317393ms","start":"2026-04-27T16:08:33.333237Z","end":"2026-04-27T16:08:33.450554Z","steps":["trace[172370976] 'agreement among raft nodes before linearized reading' (duration: 117.141537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:33.453357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.009231ms","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-27T16:08:33.453436Z","caller":"traceutil/trace.go:171","msg":"trace[300924052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1207; }","duration":"120.118745ms","start":"2026-04-27T16:08:33.333297Z","end":"2026-04-27T16:08:33.453416Z","steps":["trace[300924052] 'agreement among raft nodes before linearized reading' (duration: 119.924129ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:33.453371Z","caller":"traceutil/trace.go:171","msg":"trace[1484701483] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"118.069278ms","start":"2026-04-27T16:08:33.335259Z","end":"2026-04-27T16:08:33.453328Z","steps":["trace[1484701483] 'process raft request' (duration: 117.82393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:35.630238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6277065495999898693,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:08:36.130882Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6277065495999898693,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:08:36.627965Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.601004239s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T16:08:36.628363Z","caller":"traceutil/trace.go:171","msg":"trace[555786171] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1254; }","duration":"1.498560172s","start":"2026-04-27T16:08:35.129783Z","end":"2026-04-27T16:08:36.628343Z","steps":["trace[555786171] 'read index received' (duration: 1.498314464s)","trace[555786171] 'applied index is now lower than readState.Index' (duration: 244.818µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:36.628439Z","caller":"traceutil/trace.go:171","msg":"trace[1196325355] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"1.601548436s","start":"2026-04-27T16:08:35.026881Z","end":"2026-04-27T16:08:36.628429Z","steps":["trace[1196325355] 'process raft request' (duration: 1.601299198s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:36.62854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:35.026861Z","time spent":"1.601607679s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:36.628628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170074501s","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-27T16:08:36.628664Z","caller":"traceutil/trace.go:171","msg":"trace[1586591514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1210; }","duration":"1.170126403s","start":"2026-04-27T16:08:35.45853Z","end":"2026-04-27T16:08:36.628656Z","steps":["trace[1586591514] 'agreement among raft nodes before linearized reading' (duration: 1.170004829s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:36.628651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167801217s","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-27T16:08:36.628684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:35.458525Z","time spent":"1.170156154s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T16:08:36.62871Z","caller":"traceutil/trace.go:171","msg":"trace[112678196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1210; }","duration":"1.167882369s","start":"2026-04-27T16:08:35.460815Z","end":"2026-04-27T16:08:36.628697Z","steps":["trace[112678196] 'agreement among raft nodes before linearized reading' (duration: 1.167714293s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:36.628742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.517901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:36.628762Z","caller":"traceutil/trace.go:171","msg":"trace[643381348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1210; }","duration":"451.583344ms","start":"2026-04-27T16:08:36.177174Z","end":"2026-04-27T16:08:36.628757Z","steps":["trace[643381348] 'agreement among raft nodes before linearized reading' (duration: 451.555453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:36.628747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.165476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.52\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-27T16:08:36.628731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16850462s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:08:36.628814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314788063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:08:36.628821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173778973s","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-27T16:08:36.628827Z","caller":"traceutil/trace.go:171","msg":"trace[781098096] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1210; }","duration":"1.314837824s","start":"2026-04-27T16:08:35.313985Z","end":"2026-04-27T16:08:36.628823Z","steps":["trace[781098096] 'agreement among raft nodes before linearized reading' (duration: 1.314814403s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:36.628839Z","caller":"traceutil/trace.go:171","msg":"trace[241322727] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1210; }","duration":"1.173828905s","start":"2026-04-27T16:08:35.455006Z","end":"2026-04-27T16:08:36.628835Z","steps":["trace[241322727] 'agreement among raft nodes before linearized reading' (duration: 1.173782593s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:36.628823Z","caller":"traceutil/trace.go:171","msg":"trace[893250986] range","detail":"{range_begin:/registry/masterleases/199.19.213.52; range_end:; response_count:1; response_revision:1210; }","duration":"598.278529ms","start":"2026-04-27T16:08:36.030524Z","end":"2026-04-27T16:08:36.628803Z","steps":["trace[893250986] 'agreement among raft nodes before linearized reading' (duration: 598.083683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:36.628844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:35.313934Z","time spent":"1.314905767s","remote":"127.0.0.1:33284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:08:36.628843Z","caller":"traceutil/trace.go:171","msg":"trace[1096338982] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1210; }","duration":"1.168661065s","start":"2026-04-27T16:08:35.460163Z","end":"2026-04-27T16:08:36.628824Z","steps":["trace[1096338982] 'agreement among raft nodes before linearized reading' (duration: 1.168411437s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:36.628853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:35.454988Z","time spent":"1.173862546s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T16:08:36.628874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:36.030503Z","time spent":"598.358182ms","remote":"127.0.0.1:33038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.52\" "} {"level":"warn","ts":"2026-04-27T16:08:36.628777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:36.177149Z","time spent":"451.626006ms","remote":"127.0.0.1:33022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:08:36.628948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.499185913s","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-27T16:08:36.628999Z","caller":"traceutil/trace.go:171","msg":"trace[1324750450] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1210; }","duration":"1.499280367s","start":"2026-04-27T16:08:35.129714Z","end":"2026-04-27T16:08:36.628994Z","steps":["trace[1324750450] 'agreement among raft nodes before linearized reading' (duration: 1.499201104s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:36.629016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:35.129689Z","time spent":"1.499323327s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T16:08:36.628885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:35.460159Z","time spent":"1.168716777s","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:08:36.628737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:35.46081Z","time spent":"1.167922401s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:08:36.628784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158147391s","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-27T16:08:36.629162Z","caller":"traceutil/trace.go:171","msg":"trace[830784104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1210; }","duration":"1.158558794s","start":"2026-04-27T16:08:35.470598Z","end":"2026-04-27T16:08:36.629157Z","steps":["trace[830784104] 'agreement among raft nodes before linearized reading' (duration: 1.158156061s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:36.629179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:35.470573Z","time spent":"1.158601296s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T16:08:36.629288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455397289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:36.631149Z","caller":"traceutil/trace.go:171","msg":"trace[1309766918] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1210; }","duration":"1.457295061s","start":"2026-04-27T16:08:35.173834Z","end":"2026-04-27T16:08:36.631129Z","steps":["trace[1309766918] 'agreement among raft nodes before linearized reading' (duration: 1.454710536s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:36.63121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:35.173814Z","time spent":"1.457375104s","remote":"127.0.0.1:33310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:08:37.430071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:36.631236Z","time spent":"798.8314ms","remote":"127.0.0.1:33038","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-27T16:08:37.453591Z","caller":"traceutil/trace.go:171","msg":"trace[1922788578] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"820.624364ms","start":"2026-04-27T16:08:36.632948Z","end":"2026-04-27T16:08:37.453572Z","steps":["trace[1922788578] 'process raft request' (duration: 820.573862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:37.453712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:36.632941Z","time spent":"820.705816ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:37.453871Z","caller":"traceutil/trace.go:171","msg":"trace[863379726] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"819.960671ms","start":"2026-04-27T16:08:36.633899Z","end":"2026-04-27T16:08:37.45386Z","steps":["trace[863379726] 'process raft request' (duration: 819.831117ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:37.453991Z","caller":"traceutil/trace.go:171","msg":"trace[1275846052] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1255; }","duration":"274.331187ms","start":"2026-04-27T16:08:37.179633Z","end":"2026-04-27T16:08:37.453964Z","steps":["trace[1275846052] 'read index received' (duration: 250.337951ms)","trace[1275846052] 'applied index is now lower than readState.Index' (duration: 23.991766ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:37.454027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:36.633891Z","time spent":"820.043355ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:37.454027Z","caller":"traceutil/trace.go:171","msg":"trace[364254809] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"820.036844ms","start":"2026-04-27T16:08:36.633976Z","end":"2026-04-27T16:08:37.454013Z","steps":["trace[364254809] 'process raft request' (duration: 819.851968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:37.454121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.474112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:37.454151Z","caller":"traceutil/trace.go:171","msg":"trace[1682276090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"274.544145ms","start":"2026-04-27T16:08:37.179598Z","end":"2026-04-27T16:08:37.454142Z","steps":["trace[1682276090] 'agreement among raft nodes before linearized reading' (duration: 274.471812ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:37.45365Z","caller":"traceutil/trace.go:171","msg":"trace[1002904951] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"820.748928ms","start":"2026-04-27T16:08:36.632842Z","end":"2026-04-27T16:08:37.453591Z","steps":["trace[1002904951] 'process raft request' (duration: 820.559031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:37.454168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:36.633969Z","time spent":"820.138858ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:37.454228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:36.632825Z","time spent":"821.368028ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:37.454509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.399236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:37.454547Z","caller":"traceutil/trace.go:171","msg":"trace[1584263439] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1216; }","duration":"175.442378ms","start":"2026-04-27T16:08:37.279094Z","end":"2026-04-27T16:08:37.454537Z","steps":["trace[1584263439] 'agreement among raft nodes before linearized reading' (duration: 175.357355ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:37.455618Z","caller":"traceutil/trace.go:171","msg":"trace[1293380640] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"820.317064ms","start":"2026-04-27T16:08:36.633562Z","end":"2026-04-27T16:08:37.453879Z","steps":["trace[1293380640] 'process raft request' (duration: 819.985883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:37.4557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:36.633526Z","time spent":"822.123243ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:53.737674Z","caller":"traceutil/trace.go:171","msg":"trace[2053449969] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"187.471802ms","start":"2026-04-27T16:08:53.550178Z","end":"2026-04-27T16:08:53.73765Z","steps":["trace[2053449969] 'process raft request' (duration: 187.262605ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:53.738112Z","caller":"traceutil/trace.go:171","msg":"trace[1962609876] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"180.08238ms","start":"2026-04-27T16:08:53.558016Z","end":"2026-04-27T16:08:53.738099Z","steps":["trace[1962609876] 'process raft request' (duration: 179.576973ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:53.738087Z","caller":"traceutil/trace.go:171","msg":"trace[1117899466] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1329; }","duration":"187.521324ms","start":"2026-04-27T16:08:53.550458Z","end":"2026-04-27T16:08:53.737979Z","steps":["trace[1117899466] 'read index received' (duration: 69.388873ms)","trace[1117899466] 'applied index is now lower than readState.Index' (duration: 118.12079ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:53.738335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.054636ms","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-27T16:08:53.738376Z","caller":"traceutil/trace.go:171","msg":"trace[1482103515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1283; }","duration":"186.118367ms","start":"2026-04-27T16:08:53.55225Z","end":"2026-04-27T16:08:53.738368Z","steps":["trace[1482103515] 'agreement among raft nodes before linearized reading' (duration: 186.013944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:53.738341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.097417ms","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-27T16:08:53.738417Z","caller":"traceutil/trace.go:171","msg":"trace[1815247880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1283; }","duration":"186.21214ms","start":"2026-04-27T16:08:53.552189Z","end":"2026-04-27T16:08:53.738401Z","steps":["trace[1815247880] 'agreement among raft nodes before linearized reading' (duration: 186.042015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:53.73845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.987059ms","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-27T16:08:53.738544Z","caller":"traceutil/trace.go:171","msg":"trace[1825747684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1283; }","duration":"188.098233ms","start":"2026-04-27T16:08:53.550429Z","end":"2026-04-27T16:08:53.738527Z","steps":["trace[1825747684] 'agreement among raft nodes before linearized reading' (duration: 187.71738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:55.893187Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.205697938s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-27T16:08:56.178805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00000259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-27T16:08:56.178927Z","caller":"traceutil/trace.go:171","msg":"trace[1048160194] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000171034s","start":"2026-04-27T16:08:54.178731Z","end":"2026-04-27T16:08:56.178902Z","steps":["trace[1048160194] 'agreement among raft nodes before linearized reading' (duration: 2.000019399s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:56.179001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:54.178718Z","time spent":"2.000265908s","remote":"127.0.0.1:33022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:08:56.381399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.031723569s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:08:56.381653Z","caller":"traceutil/trace.go:171","msg":"trace[493312314] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"2.637215144s","start":"2026-04-27T16:08:53.744395Z","end":"2026-04-27T16:08:56.38161Z","steps":["trace[493312314] 'process raft request' (duration: 605.186995ms)","trace[493312314] 'compare' (duration: 2.030288482s)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:56.381811Z","caller":"traceutil/trace.go:171","msg":"trace[367812347] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"2.636252603s","start":"2026-04-27T16:08:53.74554Z","end":"2026-04-27T16:08:56.381793Z","steps":["trace[367812347] 'process raft request' (duration: 2.636132489s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:56.381859Z","caller":"traceutil/trace.go:171","msg":"trace[1203813096] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"2.637452592s","start":"2026-04-27T16:08:53.744401Z","end":"2026-04-27T16:08:56.381853Z","steps":["trace[1203813096] 'process raft request' (duration: 2.637108111s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:56.381897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:53.745521Z","time spent":"2.636342065s","remote":"127.0.0.1:33162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:56.381888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:53.744377Z","time spent":"2.63737725s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:56.381939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:53.744384Z","time spent":"2.637495574s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:56.381788Z","caller":"traceutil/trace.go:171","msg":"trace[291376387] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"2.636630355s","start":"2026-04-27T16:08:53.745136Z","end":"2026-04-27T16:08:56.381766Z","steps":["trace[291376387] 'process raft request' (duration: 2.63648197s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:56.382126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:53.745122Z","time spent":"2.636986806s","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:56.38217Z","caller":"traceutil/trace.go:171","msg":"trace[1604174277] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"2.634107903s","start":"2026-04-27T16:08:53.748042Z","end":"2026-04-27T16:08:56.38215Z","steps":["trace[1604174277] 'process raft request' (duration: 2.633679559s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:56.382239Z","caller":"traceutil/trace.go:171","msg":"trace[1565530320] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1333; }","duration":"2.492322387s","start":"2026-04-27T16:08:53.889908Z","end":"2026-04-27T16:08:56.382231Z","steps":["trace[1565530320] 'read index received' (duration: 459.71452ms)","trace[1565530320] 'applied index is now lower than readState.Index' (duration: 2.032607277s)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:56.382273Z","caller":"traceutil/trace.go:171","msg":"trace[1234439147] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"1.694934935s","start":"2026-04-27T16:08:54.687322Z","end":"2026-04-27T16:08:56.382256Z","steps":["trace[1234439147] 'process raft request' (duration: 1.694667427s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:56.38232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.492415581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-27T16:08:56.382298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:53.748031Z","time spent":"2.634197775s","remote":"127.0.0.1:33460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:56.382342Z","caller":"traceutil/trace.go:171","msg":"trace[1204621010] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1290; }","duration":"2.492460662s","start":"2026-04-27T16:08:53.889874Z","end":"2026-04-27T16:08:56.382334Z","steps":["trace[1204621010] 'agreement among raft nodes before linearized reading' (duration: 2.49241232s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:56.382357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:53.889856Z","time spent":"2.492497613s","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:08:56.382358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:54.687288Z","time spent":"1.695025938s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:56.382495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49971656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:08:56.382522Z","caller":"traceutil/trace.go:171","msg":"trace[1898182252] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1290; }","duration":"1.499778122s","start":"2026-04-27T16:08:54.882735Z","end":"2026-04-27T16:08:56.382513Z","steps":["trace[1898182252] 'agreement among raft nodes before linearized reading' (duration: 1.49972858s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:56.38254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:54.882713Z","time spent":"1.499821463s","remote":"127.0.0.1:42098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:08:56.382596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.18872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-27T16:08:56.382639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.810323095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:08:56.382639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.28634217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:56.382715Z","caller":"traceutil/trace.go:171","msg":"trace[804926904] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1290; }","duration":"1.810423319s","start":"2026-04-27T16:08:54.572272Z","end":"2026-04-27T16:08:56.382695Z","steps":["trace[804926904] 'agreement among raft nodes before linearized reading' (duration: 1.810322045s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:56.382734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.784034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:08:56.382761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:54.572253Z","time spent":"1.810495501s","remote":"127.0.0.1:58886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:08:56.38277Z","caller":"traceutil/trace.go:171","msg":"trace[1973481219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"202.845176ms","start":"2026-04-27T16:08:56.179914Z","end":"2026-04-27T16:08:56.38276Z","steps":["trace[1973481219] 'agreement among raft nodes before linearized reading' (duration: 202.786294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:56.382788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.022686113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:56.382804Z","caller":"traceutil/trace.go:171","msg":"trace[1810231353] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1290; }","duration":"2.022730304s","start":"2026-04-27T16:08:54.360069Z","end":"2026-04-27T16:08:56.382799Z","steps":["trace[1810231353] 'agreement among raft nodes before linearized reading' (duration: 2.022703393s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:56.382819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:54.360048Z","time spent":"2.022766475s","remote":"127.0.0.1:42056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:08:56.382848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.672746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-27T16:08:56.382875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.287078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-27T16:08:56.382889Z","caller":"traceutil/trace.go:171","msg":"trace[1950786084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1290; }","duration":"618.77413ms","start":"2026-04-27T16:08:55.764102Z","end":"2026-04-27T16:08:56.382876Z","steps":["trace[1950786084] 'agreement among raft nodes before linearized reading' (duration: 618.657416ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:56.382727Z","caller":"traceutil/trace.go:171","msg":"trace[1773425618] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1290; }","duration":"1.286466224s","start":"2026-04-27T16:08:55.096241Z","end":"2026-04-27T16:08:56.382708Z","steps":["trace[1773425618] 'agreement among raft nodes before linearized reading' (duration: 1.286321339s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:56.382912Z","caller":"traceutil/trace.go:171","msg":"trace[914794639] range","detail":"{range_begin:/registry/masterleases/199.19.213.52; range_end:; response_count:1; response_revision:1290; }","duration":"351.35293ms","start":"2026-04-27T16:08:56.031548Z","end":"2026-04-27T16:08:56.382901Z","steps":["trace[914794639] 'agreement among raft nodes before linearized reading' (duration: 351.222776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:56.38293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:55.764074Z","time spent":"618.845722ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T16:08:56.382945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:56.031526Z","time spent":"351.412023ms","remote":"127.0.0.1:33038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.52\" "} {"level":"warn","ts":"2026-04-27T16:08:56.382963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.083865ms","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-27T16:08:56.383015Z","caller":"traceutil/trace.go:171","msg":"trace[653746467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1290; }","duration":"639.186589ms","start":"2026-04-27T16:08:55.743813Z","end":"2026-04-27T16:08:56.383Z","steps":["trace[653746467] 'agreement among raft nodes before linearized reading' (duration: 638.975472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:56.383065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:55.743784Z","time spent":"639.273382ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:08:56.382534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.260676729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:08:56.382984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:55.096223Z","time spent":"1.286719802s","remote":"127.0.0.1:58932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:08:56.383139Z","caller":"traceutil/trace.go:171","msg":"trace[1896046473] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1290; }","duration":"2.2613152s","start":"2026-04-27T16:08:54.121782Z","end":"2026-04-27T16:08:56.383097Z","steps":["trace[1896046473] 'agreement among raft nodes before linearized reading' (duration: 2.260641118s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:56.38267Z","caller":"traceutil/trace.go:171","msg":"trace[200817532] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1290; }","duration":"759.327405ms","start":"2026-04-27T16:08:55.623314Z","end":"2026-04-27T16:08:56.382641Z","steps":["trace[200817532] 'agreement among raft nodes before linearized reading' (duration: 759.130688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:56.383221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:54.121769Z","time spent":"2.261404423s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:08:56.383229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:55.623284Z","time spent":"759.934005ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T16:08:56.382694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385155377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:08:56.383333Z","caller":"traceutil/trace.go:171","msg":"trace[1008655669] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1290; }","duration":"1.38586421s","start":"2026-04-27T16:08:54.997455Z","end":"2026-04-27T16:08:56.383319Z","steps":["trace[1008655669] 'agreement among raft nodes before linearized reading' (duration: 1.385184668s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:56.383407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:54.997432Z","time spent":"1.385963634s","remote":"127.0.0.1:33344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:08:57.146153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.362981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:08:57.146322Z","caller":"traceutil/trace.go:171","msg":"trace[228237859] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1340; }","duration":"671.731096ms","start":"2026-04-27T16:08:56.474579Z","end":"2026-04-27T16:08:57.14631Z","steps":["trace[228237859] 'read index received' (duration: 465.168869ms)","trace[228237859] 'applied index is now lower than readState.Index' (duration: 206.561617ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:57.146473Z","caller":"traceutil/trace.go:171","msg":"trace[106857525] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"756.127671ms","start":"2026-04-27T16:08:56.390337Z","end":"2026-04-27T16:08:57.146464Z","steps":["trace[106857525] 'process raft request' (duration: 549.394938ms)","trace[106857525] 'compare' (duration: 206.276358ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:57.146512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:56.390318Z","time spent":"756.173412ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:57.146628Z","caller":"traceutil/trace.go:171","msg":"trace[326228927] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"756.265145ms","start":"2026-04-27T16:08:56.390355Z","end":"2026-04-27T16:08:57.14662Z","steps":["trace[326228927] 'process raft request' (duration: 755.871532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:57.146662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:56.390347Z","time spent":"756.297006ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:57.146739Z","caller":"traceutil/trace.go:171","msg":"trace[745615389] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"754.998454ms","start":"2026-04-27T16:08:56.391735Z","end":"2026-04-27T16:08:57.146734Z","steps":["trace[745615389] 'process raft request' (duration: 754.547879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:57.146771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:56.391726Z","time spent":"755.026164ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:57.14686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.518228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:57.146877Z","caller":"traceutil/trace.go:171","msg":"trace[272564547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1293; }","duration":"754.552949ms","start":"2026-04-27T16:08:56.392319Z","end":"2026-04-27T16:08:57.146872Z","steps":["trace[272564547] 'agreement among raft nodes before linearized reading' (duration: 754.523268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:57.146898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:56.392311Z","time spent":"754.58267ms","remote":"127.0.0.1:33022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:08:57.168329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.609406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:57.168353Z","caller":"traceutil/trace.go:171","msg":"trace[509270261] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"690.815001ms","start":"2026-04-27T16:08:56.477509Z","end":"2026-04-27T16:08:57.168324Z","steps":["trace[509270261] 'process raft request' (duration: 690.689217ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:57.168361Z","caller":"traceutil/trace.go:171","msg":"trace[1482003520] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"691.745672ms","start":"2026-04-27T16:08:56.476569Z","end":"2026-04-27T16:08:57.168315Z","steps":["trace[1482003520] 'process raft request' (duration: 691.485183ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:57.168412Z","caller":"traceutil/trace.go:171","msg":"trace[907602765] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1295; }","duration":"379.7453ms","start":"2026-04-27T16:08:56.788649Z","end":"2026-04-27T16:08:57.168394Z","steps":["trace[907602765] 'agreement among raft nodes before linearized reading' (duration: 379.602206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:57.168483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:56.788628Z","time spent":"379.839374ms","remote":"127.0.0.1:42114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:08:57.168488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:56.477497Z","time spent":"690.917884ms","remote":"127.0.0.1:33088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:57.168519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:56.476545Z","time spent":"691.888877ms","remote":"127.0.0.1:33038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:58.595333Z","caller":"traceutil/trace.go:171","msg":"trace[2134368952] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"204.393566ms","start":"2026-04-27T16:08:58.390912Z","end":"2026-04-27T16:08:58.595305Z","steps":["trace[2134368952] 'process raft request' (duration: 204.233831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:59.448289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.632979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:08:59.448491Z","caller":"traceutil/trace.go:171","msg":"trace[683247884] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1346; }","duration":"293.089281ms","start":"2026-04-27T16:08:59.155385Z","end":"2026-04-27T16:08:59.448474Z","steps":["trace[683247884] 'read index received' (duration: 90.883µs)","trace[683247884] 'applied index is now lower than readState.Index' (duration: 292.997438ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:59.448906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.505364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T16:08:59.448966Z","caller":"traceutil/trace.go:171","msg":"trace[149393417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1298; }","duration":"293.637328ms","start":"2026-04-27T16:08:59.155315Z","end":"2026-04-27T16:08:59.448953Z","steps":["trace[149393417] 'agreement among raft nodes before linearized reading' (duration: 293.301497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:59.448961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.459003ms","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-27T16:08:59.449028Z","caller":"traceutil/trace.go:171","msg":"trace[834732154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1298; }","duration":"293.541315ms","start":"2026-04-27T16:08:59.155472Z","end":"2026-04-27T16:08:59.449014Z","steps":["trace[834732154] 'agreement among raft nodes before linearized reading' (duration: 293.181774ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:59.449063Z","caller":"traceutil/trace.go:171","msg":"trace[93621770] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"1.058047441s","start":"2026-04-27T16:08:58.391004Z","end":"2026-04-27T16:08:59.449052Z","steps":["trace[93621770] 'process raft request' (duration: 234.573535ms)","trace[93621770] 'compare' (duration: 822.160013ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:59.449135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:58.390994Z","time spent":"1.058091532s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:59.44916Z","caller":"traceutil/trace.go:171","msg":"trace[1705328639] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"1.058111693s","start":"2026-04-27T16:08:58.391037Z","end":"2026-04-27T16:08:59.449149Z","steps":["trace[1705328639] 'process raft request' (duration: 1.057346468s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:59.449233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:58.391007Z","time spent":"1.058177235s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:59.450244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.094174ms","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-27T16:08:59.45038Z","caller":"traceutil/trace.go:171","msg":"trace[842300110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1298; }","duration":"294.258309ms","start":"2026-04-27T16:08:59.156102Z","end":"2026-04-27T16:08:59.450361Z","steps":["trace[842300110] 'agreement among raft nodes before linearized reading' (duration: 292.759399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:00.467549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.918245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:09:00.467653Z","caller":"traceutil/trace.go:171","msg":"trace[120663037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1303; }","duration":"289.079201ms","start":"2026-04-27T16:09:00.178549Z","end":"2026-04-27T16:09:00.467628Z","steps":["trace[120663037] 'range keys from in-memory index tree' (duration: 288.816312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:00.467645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.111389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:09:00.467734Z","caller":"traceutil/trace.go:171","msg":"trace[1963907544] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1303; }","duration":"190.260793ms","start":"2026-04-27T16:09:00.277451Z","end":"2026-04-27T16:09:00.467712Z","steps":["trace[1963907544] 'count revisions from in-memory index tree' (duration: 190.035806ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:00.749571Z","caller":"traceutil/trace.go:171","msg":"trace[199933719] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"145.829228ms","start":"2026-04-27T16:09:00.603713Z","end":"2026-04-27T16:09:00.749542Z","steps":["trace[199933719] 'process raft request' (duration: 145.684273ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:01.025087Z","caller":"traceutil/trace.go:171","msg":"trace[2082374921] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1354; }","duration":"244.50364ms","start":"2026-04-27T16:09:00.780555Z","end":"2026-04-27T16:09:01.025059Z","steps":["trace[2082374921] 'read index received' (duration: 203.422524ms)","trace[2082374921] 'applied index is now lower than readState.Index' (duration: 41.073826ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:01.025241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.662965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:09:01.025283Z","caller":"traceutil/trace.go:171","msg":"trace[1766145396] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1305; }","duration":"244.748918ms","start":"2026-04-27T16:09:00.780521Z","end":"2026-04-27T16:09:01.02527Z","steps":["trace[1766145396] 'agreement among raft nodes before linearized reading' (duration: 244.640214ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:01.025205Z","caller":"traceutil/trace.go:171","msg":"trace[606944838] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"351.430683ms","start":"2026-04-27T16:09:00.673748Z","end":"2026-04-27T16:09:01.025179Z","steps":["trace[606944838] 'process raft request' (duration: 310.312386ms)","trace[606944838] 'compare' (duration: 40.715404ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:01.025531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:00.673721Z","time spent":"351.707262ms","remote":"127.0.0.1:33088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:09:01.225861Z","caller":"traceutil/trace.go:171","msg":"trace[1471886576] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"151.538815ms","start":"2026-04-27T16:09:01.074298Z","end":"2026-04-27T16:09:01.225837Z","steps":["trace[1471886576] 'process raft request' (duration: 151.300157ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:01.226197Z","caller":"traceutil/trace.go:171","msg":"trace[1224736246] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"151.141782ms","start":"2026-04-27T16:09:01.075044Z","end":"2026-04-27T16:09:01.226185Z","steps":["trace[1224736246] 'process raft request' (duration: 150.688777ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:07.935647Z","caller":"traceutil/trace.go:171","msg":"trace[576505247] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"502.998058ms","start":"2026-04-27T16:09:07.432593Z","end":"2026-04-27T16:09:07.935591Z","steps":["trace[576505247] 'process raft request' (duration: 502.308776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:07.93588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:07.432582Z","time spent":"503.208035ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:09:09.272852Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.2585561s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T16:09:09.280906Z","caller":"traceutil/trace.go:171","msg":"trace[598826672] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1416; }","duration":"1.803617967s","start":"2026-04-27T16:09:07.477267Z","end":"2026-04-27T16:09:09.280885Z","steps":["trace[598826672] 'read index received' (duration: 457.687594ms)","trace[598826672] 'applied index is now lower than readState.Index' (duration: 1.345929423s)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:09:09.280984Z","caller":"traceutil/trace.go:171","msg":"trace[1495448835] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"1.80706708s","start":"2026-04-27T16:09:07.473876Z","end":"2026-04-27T16:09:09.280943Z","steps":["trace[1495448835] 'process raft request' (duration: 1.799209192s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:09.28115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.803865295s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-27T16:09:09.28114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100268315s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:09:09.281182Z","caller":"traceutil/trace.go:171","msg":"trace[1391215107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1366; }","duration":"1.803938887s","start":"2026-04-27T16:09:07.477234Z","end":"2026-04-27T16:09:09.281173Z","steps":["trace[1391215107] 'agreement among raft nodes before linearized reading' (duration: 1.803770042s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:09.281182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.725690804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-27T16:09:09.281211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:07.477222Z","time spent":"1.803982069s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T16:09:09.281234Z","caller":"traceutil/trace.go:171","msg":"trace[1972087493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1366; }","duration":"1.725782457s","start":"2026-04-27T16:09:07.555439Z","end":"2026-04-27T16:09:09.281221Z","steps":["trace[1972087493] 'agreement among raft nodes before linearized reading' (duration: 1.725627762s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:09.28127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:07.555421Z","time spent":"1.725843489s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T16:09:09.281258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.113899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-04-27T16:09:09.281293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.128293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-27T16:09:09.281218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:07.473853Z","time spent":"1.807219505s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:09:09.281429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.689623782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-27T16:09:09.281433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.719944786s","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-27T16:09:09.281329Z","caller":"traceutil/trace.go:171","msg":"trace[365164985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1366; }","duration":"503.197405ms","start":"2026-04-27T16:09:08.778123Z","end":"2026-04-27T16:09:09.28132Z","steps":["trace[365164985] 'agreement among raft nodes before linearized reading' (duration: 503.093322ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:09.281461Z","caller":"traceutil/trace.go:171","msg":"trace[2112315118] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1366; }","duration":"1.689676184s","start":"2026-04-27T16:09:07.591777Z","end":"2026-04-27T16:09:09.281453Z","steps":["trace[2112315118] 'agreement among raft nodes before linearized reading' (duration: 1.689620003s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:09.281465Z","caller":"traceutil/trace.go:171","msg":"trace[1535595258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1366; }","duration":"1.719988898s","start":"2026-04-27T16:09:07.561469Z","end":"2026-04-27T16:09:09.281458Z","steps":["trace[1535595258] 'agreement among raft nodes before linearized reading' (duration: 1.719909995s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:09.28149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:07.591766Z","time spent":"1.689718405s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T16:09:09.281492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:08.778091Z","time spent":"503.392421ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T16:09:09.281522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:07.561462Z","time spent":"1.720044639s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T16:09:09.281339Z","caller":"traceutil/trace.go:171","msg":"trace[1031803692] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1366; }","duration":"270.232633ms","start":"2026-04-27T16:09:09.011089Z","end":"2026-04-27T16:09:09.281322Z","steps":["trace[1031803692] 'agreement among raft nodes before linearized reading' (duration: 269.996275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:09.281383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.589807153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-27T16:09:09.281674Z","caller":"traceutil/trace.go:171","msg":"trace[1709308708] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1366; }","duration":"1.590118683s","start":"2026-04-27T16:09:07.691538Z","end":"2026-04-27T16:09:09.281657Z","steps":["trace[1709308708] 'agreement among raft nodes before linearized reading' (duration: 1.589804333s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:09.281195Z","caller":"traceutil/trace.go:171","msg":"trace[1535730916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1366; }","duration":"1.100372358s","start":"2026-04-27T16:09:08.180809Z","end":"2026-04-27T16:09:09.281181Z","steps":["trace[1535730916] 'agreement among raft nodes before linearized reading' (duration: 1.100281525s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:09.281729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:07.691519Z","time spent":"1.590186035s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-27T16:09:09.281766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:08.180785Z","time spent":"1.100969928s","remote":"127.0.0.1:33022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T16:11:13.10174Z","caller":"traceutil/trace.go:171","msg":"trace[1744257617] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"204.87441ms","start":"2026-04-27T16:11:12.896841Z","end":"2026-04-27T16:11:13.101715Z","steps":["trace[1744257617] 'process raft request' (duration: 204.732255ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:11:13.29778Z","caller":"traceutil/trace.go:171","msg":"trace[1297973759] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"269.755773ms","start":"2026-04-27T16:11:13.02799Z","end":"2026-04-27T16:11:13.297746Z","steps":["trace[1297973759] 'process raft request' (duration: 212.762504ms)","trace[1297973759] 'compare' (duration: 56.856485ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:11:13.297785Z","caller":"traceutil/trace.go:171","msg":"trace[30777514] linearizableReadLoop","detail":"{readStateIndex:2015; appliedIndex:2014; }","duration":"118.199753ms","start":"2026-04-27T16:11:13.179546Z","end":"2026-04-27T16:11:13.297746Z","steps":["trace[30777514] 'read index received' (duration: 61.120502ms)","trace[30777514] 'applied index is now lower than readState.Index' (duration: 57.077921ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:11:13.297971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.400609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:11:13.298069Z","caller":"traceutil/trace.go:171","msg":"trace[2147351455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1938; }","duration":"118.534184ms","start":"2026-04-27T16:11:13.179512Z","end":"2026-04-27T16:11:13.298046Z","steps":["trace[2147351455] 'agreement among raft nodes before linearized reading' (duration: 118.350598ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:12:24.203133Z","caller":"traceutil/trace.go:171","msg":"trace[908715681] transaction","detail":"{read_only:false; response_revision:2291; number_of_response:1; }","duration":"257.360955ms","start":"2026-04-27T16:12:23.945747Z","end":"2026-04-27T16:12:24.203108Z","steps":["trace[908715681] 'process raft request' (duration: 257.20255ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:12:24.374236Z","caller":"traceutil/trace.go:171","msg":"trace[85592322] linearizableReadLoop","detail":"{readStateIndex:2384; appliedIndex:2382; }","duration":"195.261487ms","start":"2026-04-27T16:12:24.178945Z","end":"2026-04-27T16:12:24.374207Z","steps":["trace[85592322] 'read index received' (duration: 24.111214ms)","trace[85592322] 'applied index is now lower than readState.Index' (duration: 171.149243ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:12:24.374316Z","caller":"traceutil/trace.go:171","msg":"trace[1366325532] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"364.332874ms","start":"2026-04-27T16:12:24.009956Z","end":"2026-04-27T16:12:24.374289Z","steps":["trace[1366325532] 'process raft request' (duration: 361.426862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:12:24.374436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:12:24.009933Z","time spent":"364.439777ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:12:24.374587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.481814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:12:24.374665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.772766ms","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-27T16:12:24.374688Z","caller":"traceutil/trace.go:171","msg":"trace[1705211563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2292; }","duration":"195.718821ms","start":"2026-04-27T16:12:24.178905Z","end":"2026-04-27T16:12:24.374623Z","steps":["trace[1705211563] 'agreement among raft nodes before linearized reading' (duration: 195.440242ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:12:24.374729Z","caller":"traceutil/trace.go:171","msg":"trace[1778112015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2292; }","duration":"143.869199ms","start":"2026-04-27T16:12:24.230842Z","end":"2026-04-27T16:12:24.374712Z","steps":["trace[1778112015] 'agreement among raft nodes before linearized reading' (duration: 143.697124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:12:24.375001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.806218ms","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-27T16:12:24.375139Z","caller":"traceutil/trace.go:171","msg":"trace[253062290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2292; }","duration":"144.999365ms","start":"2026-04-27T16:12:24.230115Z","end":"2026-04-27T16:12:24.375115Z","steps":["trace[253062290] 'agreement among raft nodes before linearized reading' (duration: 144.399486ms)"],"step_count":1}