{"level":"warn","ts":"2026-06-10T03:39:38.831145Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T03:39:38.831248Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19: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-06-10T03:39:38.831325Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T03:39:38.831334Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-06-10T03:39:38.831354Z","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-06-10T03:39:38.832059Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-06-10T03:39:38.832136Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19: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-06-10T03:39:38.83883Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.451514ms"} {"level":"info","ts":"2026-06-10T03:39:38.851127Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-06-10T03:39:38.85122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-06-10T03:39:38.851248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-06-10T03:39:38.85127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-10T03:39:38.851279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-06-10T03:39:38.851322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-06-10T03:39:38.865197Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-10T03:39:38.866842Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-10T03:39:38.868124Z","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-06-10T03:39:38.870939Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-10T03:39:38.872072Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-10T03:39:38.872446Z","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-06-10T03:39:38.872535Z","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-06-10T03:39:38.872552Z","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-06-10T03:39:38.873008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-06-10T03:39:38.873193Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-06-10T03:39:38.877489Z","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-06-10T03:39:38.877624Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-06-10T03:39:38.877669Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-06-10T03:39:38.877996Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-10T03:39:38.878088Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-10T03:39:39.752211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-06-10T03:39:39.752281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-10T03:39:39.752318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-06-10T03:39:39.752341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-06-10T03:39:39.752354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-06-10T03:39:39.752372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-06-10T03:39:39.752395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-06-10T03:39:39.753702Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-06-10T03:39:39.753715Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T03:39:39.753824Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T03:39:39.754073Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-10T03:39:39.754129Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-10T03:39:39.756831Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-10T03:39:39.757678Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"info","ts":"2026-06-10T03:39:39.764057Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T03:39:39.764955Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T03:39:39.765035Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T03:39:39.765056Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T03:40:03.929893Z","caller":"traceutil/trace.go:171","msg":"trace[327009243] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"311.925722ms","start":"2026-06-10T03:40:03.617939Z","end":"2026-06-10T03:40:03.929865Z","steps":["trace[327009243] 'process raft request' (duration: 311.726878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:03.930494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:03.617922Z","time spent":"312.063575ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:40:04.396081Z","caller":"traceutil/trace.go:171","msg":"trace[170805947] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:476; }","duration":"157.645705ms","start":"2026-06-10T03:40:04.238414Z","end":"2026-06-10T03:40:04.39606Z","steps":["trace[170805947] 'read index received' (duration: 131.945482ms)","trace[170805947] 'applied index is now lower than readState.Index' (duration: 25.699333ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:40:04.396208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.784017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T03:40:04.396247Z","caller":"traceutil/trace.go:171","msg":"trace[1690407880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:463; }","duration":"157.899289ms","start":"2026-06-10T03:40:04.238341Z","end":"2026-06-10T03:40:04.39624Z","steps":["trace[1690407880] 'agreement among raft nodes before linearized reading' (duration: 157.790897ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:04.396203Z","caller":"traceutil/trace.go:171","msg":"trace[938711713] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"389.194933ms","start":"2026-06-10T03:40:04.006964Z","end":"2026-06-10T03:40:04.396159Z","steps":["trace[938711713] 'process raft request' (duration: 363.528181ms)","trace[938711713] 'compare' (duration: 25.482878ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:40:04.396401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:04.006943Z","time spent":"389.350405ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:40:17.438308Z","caller":"traceutil/trace.go:171","msg":"trace[1265315598] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"162.275594ms","start":"2026-06-10T03:40:17.275996Z","end":"2026-06-10T03:40:17.438272Z","steps":["trace[1265315598] 'process raft request' (duration: 162.07976ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:17.45134Z","caller":"traceutil/trace.go:171","msg":"trace[569069768] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"161.875555ms","start":"2026-06-10T03:40:17.289441Z","end":"2026-06-10T03:40:17.451316Z","steps":["trace[569069768] 'process raft request' (duration: 161.419676ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:17.451378Z","caller":"traceutil/trace.go:171","msg":"trace[495528934] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:543; }","duration":"118.437972ms","start":"2026-06-10T03:40:17.33292Z","end":"2026-06-10T03:40:17.451358Z","steps":["trace[495528934] 'read index received' (duration: 105.31747ms)","trace[495528934] 'applied index is now lower than readState.Index' (duration: 13.119042ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:40:17.451534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.586164ms","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-06-10T03:40:17.451577Z","caller":"traceutil/trace.go:171","msg":"trace[342588953] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"167.481503ms","start":"2026-06-10T03:40:17.284087Z","end":"2026-06-10T03:40:17.451569Z","steps":["trace[342588953] 'process raft request' (duration: 166.657447ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:17.451775Z","caller":"traceutil/trace.go:171","msg":"trace[556021008] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"104.22725ms","start":"2026-06-10T03:40:17.34754Z","end":"2026-06-10T03:40:17.451767Z","steps":["trace[556021008] 'process raft request' (duration: 103.756291ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:17.45158Z","caller":"traceutil/trace.go:171","msg":"trace[1857680694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"118.701837ms","start":"2026-06-10T03:40:17.332868Z","end":"2026-06-10T03:40:17.45157Z","steps":["trace[1857680694] 'agreement among raft nodes before linearized reading' (duration: 118.556934ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:17.451919Z","caller":"traceutil/trace.go:171","msg":"trace[1463133488] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"162.527008ms","start":"2026-06-10T03:40:17.289381Z","end":"2026-06-10T03:40:17.451908Z","steps":["trace[1463133488] 'process raft request' (duration: 161.431817ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:17.451545Z","caller":"traceutil/trace.go:171","msg":"trace[1280397952] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"167.544814ms","start":"2026-06-10T03:40:17.283989Z","end":"2026-06-10T03:40:17.451534Z","steps":["trace[1280397952] 'process raft request' (duration: 166.664268ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:17.451632Z","caller":"traceutil/trace.go:171","msg":"trace[1734088335] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"160.220195ms","start":"2026-06-10T03:40:17.291401Z","end":"2026-06-10T03:40:17.451622Z","steps":["trace[1734088335] 'process raft request' (duration: 159.588792ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:17.451661Z","caller":"traceutil/trace.go:171","msg":"trace[320147] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"156.938832ms","start":"2026-06-10T03:40:17.294713Z","end":"2026-06-10T03:40:17.451652Z","steps":["trace[320147] 'process raft request' (duration: 156.32287ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:17.451689Z","caller":"traceutil/trace.go:171","msg":"trace[1759498726] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"150.742663ms","start":"2026-06-10T03:40:17.30094Z","end":"2026-06-10T03:40:17.451682Z","steps":["trace[1759498726] 'process raft request' (duration: 150.149111ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:17.451719Z","caller":"traceutil/trace.go:171","msg":"trace[1807760083] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"148.805116ms","start":"2026-06-10T03:40:17.302907Z","end":"2026-06-10T03:40:17.451712Z","steps":["trace[1807760083] 'process raft request' (duration: 148.234615ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:17.451747Z","caller":"traceutil/trace.go:171","msg":"trace[871873301] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"138.038879ms","start":"2026-06-10T03:40:17.313701Z","end":"2026-06-10T03:40:17.451739Z","steps":["trace[871873301] 'process raft request' (duration: 137.535328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:18.126659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.307272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-10T03:40:18.12692Z","caller":"traceutil/trace.go:171","msg":"trace[1147975881] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"216.310241ms","start":"2026-06-10T03:40:17.910557Z","end":"2026-06-10T03:40:18.126867Z","steps":["trace[1147975881] 'process raft request' (duration: 75.51482ms)","trace[1147975881] 'compare' (duration: 139.917834ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:40:18.220222Z","caller":"traceutil/trace.go:171","msg":"trace[408929955] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"262.958965ms","start":"2026-06-10T03:40:17.957206Z","end":"2026-06-10T03:40:18.220165Z","steps":["trace[408929955] 'process raft request' (duration: 262.606758ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:18.220473Z","caller":"traceutil/trace.go:171","msg":"trace[616204148] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:598; }","duration":"229.345471ms","start":"2026-06-10T03:40:17.9911Z","end":"2026-06-10T03:40:18.220445Z","steps":["trace[616204148] 'read index received' (duration: 135.234394ms)","trace[616204148] 'applied index is now lower than readState.Index' (duration: 94.110007ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:40:18.220748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.623346ms","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-06-10T03:40:18.220711Z","caller":"traceutil/trace.go:171","msg":"trace[1869359193] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"244.825817ms","start":"2026-06-10T03:40:17.975838Z","end":"2026-06-10T03:40:18.220664Z","steps":["trace[1869359193] 'process raft request' (duration: 244.308496ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:18.220791Z","caller":"traceutil/trace.go:171","msg":"trace[1694361791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"229.728458ms","start":"2026-06-10T03:40:17.991052Z","end":"2026-06-10T03:40:18.220781Z","steps":["trace[1694361791] 'agreement among raft nodes before linearized reading' (duration: 229.542755ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:18.492857Z","caller":"traceutil/trace.go:171","msg":"trace[1072511604] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"108.000372ms","start":"2026-06-10T03:40:18.384825Z","end":"2026-06-10T03:40:18.492826Z","steps":["trace[1072511604] 'process raft request' (duration: 107.652766ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:18.536816Z","caller":"traceutil/trace.go:171","msg":"trace[1631636620] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"101.939556ms","start":"2026-06-10T03:40:18.434827Z","end":"2026-06-10T03:40:18.536766Z","steps":["trace[1631636620] 'process raft request' (duration: 101.302213ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:19.282546Z","caller":"traceutil/trace.go:171","msg":"trace[1056986109] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"125.535618ms","start":"2026-06-10T03:40:19.156987Z","end":"2026-06-10T03:40:19.282522Z","steps":["trace[1056986109] 'read index received' (duration: 106.638776ms)","trace[1056986109] 'applied index is now lower than readState.Index' (duration: 18.896132ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:40:19.282703Z","caller":"traceutil/trace.go:171","msg":"trace[1222905277] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"128.739171ms","start":"2026-06-10T03:40:19.153912Z","end":"2026-06-10T03:40:19.282651Z","steps":["trace[1222905277] 'process raft request' (duration: 109.94625ms)","trace[1222905277] 'compare' (duration: 18.433664ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:40:19.282794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.783033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-06-10T03:40:19.283019Z","caller":"traceutil/trace.go:171","msg":"trace[448881869] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:604; }","duration":"125.901326ms","start":"2026-06-10T03:40:19.156916Z","end":"2026-06-10T03:40:19.282817Z","steps":["trace[448881869] 'agreement among raft nodes before linearized reading' (duration: 125.737913ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:19.481063Z","caller":"traceutil/trace.go:171","msg":"trace[1680522875] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"108.827618ms","start":"2026-06-10T03:40:19.372198Z","end":"2026-06-10T03:40:19.481026Z","steps":["trace[1680522875] 'read index received' (duration: 40.805482ms)","trace[1680522875] 'applied index is now lower than readState.Index' (duration: 68.021016ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:40:19.481456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.213265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-06-10T03:40:19.48151Z","caller":"traceutil/trace.go:171","msg":"trace[798552879] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:605; }","duration":"109.324137ms","start":"2026-06-10T03:40:19.372168Z","end":"2026-06-10T03:40:19.481492Z","steps":["trace[798552879] 'agreement among raft nodes before linearized reading' (duration: 108.991131ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:19.481866Z","caller":"traceutil/trace.go:171","msg":"trace[1022221733] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"154.76442ms","start":"2026-06-10T03:40:19.327044Z","end":"2026-06-10T03:40:19.481808Z","steps":["trace[1022221733] 'process raft request' (duration: 86.053481ms)","trace[1022221733] 'compare' (duration: 67.74575ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:40:24.088149Z","caller":"traceutil/trace.go:171","msg":"trace[614668381] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"256.338928ms","start":"2026-06-10T03:40:23.831003Z","end":"2026-06-10T03:40:24.087342Z","steps":["trace[614668381] 'process raft request' (duration: 255.340928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:25.023139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.586049ms","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-06-10T03:40:25.023214Z","caller":"traceutil/trace.go:171","msg":"trace[625541995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"714.736662ms","start":"2026-06-10T03:40:24.308457Z","end":"2026-06-10T03:40:25.023194Z","steps":["trace[625541995] 'range keys from in-memory index tree' (duration: 714.420486ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:25.023244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:24.308422Z","time spent":"714.815793ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T03:40:25.023473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.350787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T03:40:25.023598Z","caller":"traceutil/trace.go:171","msg":"trace[342693877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"785.49416ms","start":"2026-06-10T03:40:24.238071Z","end":"2026-06-10T03:40:25.023565Z","steps":["trace[342693877] 'range keys from in-memory index tree' (duration: 785.256134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:25.023648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:24.238011Z","time spent":"785.626944ms","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T03:40:25.058143Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988151645349079162,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T03:40:25.514493Z","caller":"traceutil/trace.go:171","msg":"trace[466450614] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"957.443629ms","start":"2026-06-10T03:40:24.55702Z","end":"2026-06-10T03:40:25.514464Z","steps":["trace[466450614] 'process raft request' (duration: 949.548138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:25.514678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:24.556998Z","time spent":"957.617043ms","remote":"127.0.0.1:56914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:40:26.842337Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.261234207s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-10T03:40:26.92423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395468162s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T03:40:26.924612Z","caller":"traceutil/trace.go:171","msg":"trace[535791412] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.343608536s","start":"2026-06-10T03:40:25.580979Z","end":"2026-06-10T03:40:26.924588Z","steps":["trace[535791412] 'process raft request' (duration: 1.343542075s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:26.924701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:25.580941Z","time spent":"1.343721929s","remote":"127.0.0.1:57040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T03:40:26.924832Z","caller":"traceutil/trace.go:171","msg":"trace[594948699] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.972424141s","start":"2026-06-10T03:40:24.952366Z","end":"2026-06-10T03:40:26.92479Z","steps":["trace[594948699] 'process raft request' (duration: 576.264456ms)","trace[594948699] 'compare' (duration: 1.39484073s)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:40:26.924978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:24.952341Z","time spent":"1.972563705s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:40:26.92502Z","caller":"traceutil/trace.go:171","msg":"trace[397335860] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:645; }","duration":"2.36690092s","start":"2026-06-10T03:40:24.558048Z","end":"2026-06-10T03:40:26.924949Z","steps":["trace[397335860] 'read index received' (duration: 945.781986ms)","trace[397335860] 'applied index is now lower than readState.Index' (duration: 1.421114704s)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:40:26.925144Z","caller":"traceutil/trace.go:171","msg":"trace[1622979034] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.896795551s","start":"2026-06-10T03:40:25.028336Z","end":"2026-06-10T03:40:26.925131Z","steps":["trace[1622979034] 'process raft request' (duration: 1.896034616s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:26.925224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:25.028298Z","time spent":"1.896878993s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:40:26.925268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.341587345s","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-06-10T03:40:26.925295Z","caller":"traceutil/trace.go:171","msg":"trace[626582987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"2.341634965s","start":"2026-06-10T03:40:24.583652Z","end":"2026-06-10T03:40:26.925287Z","steps":["trace[626582987] 'agreement among raft nodes before linearized reading' (duration: 2.341515883s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:26.925316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:24.583641Z","time spent":"2.341670267s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-10T03:40:26.925368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383597634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-10T03:40:26.925406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.771451306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-10T03:40:26.925427Z","caller":"traceutil/trace.go:171","msg":"trace[1123411103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"1.771516627s","start":"2026-06-10T03:40:25.153905Z","end":"2026-06-10T03:40:26.925422Z","steps":["trace[1123411103] 'agreement among raft nodes before linearized reading' (duration: 1.771458435s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:26.925456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:25.153879Z","time spent":"1.771572588s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T03:40:26.925434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.367362829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-s7r9h\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-06-10T03:40:26.925471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310684475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T03:40:26.925501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.900327008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T03:40:26.9255Z","caller":"traceutil/trace.go:171","msg":"trace[2116532688] range","detail":"{range_begin:/registry/pods/kube-system/cilium-s7r9h; range_end:; response_count:1; response_revision:628; }","duration":"2.367494591s","start":"2026-06-10T03:40:24.557992Z","end":"2026-06-10T03:40:26.925487Z","steps":["trace[2116532688] 'agreement among raft nodes before linearized reading' (duration: 2.367341388s)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:26.925502Z","caller":"traceutil/trace.go:171","msg":"trace[584225565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"1.310770317s","start":"2026-06-10T03:40:25.614722Z","end":"2026-06-10T03:40:26.925493Z","steps":["trace[584225565] 'agreement among raft nodes before linearized reading' (duration: 1.310726046s)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:26.925525Z","caller":"traceutil/trace.go:171","msg":"trace[12698754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"1.900392459s","start":"2026-06-10T03:40:25.025126Z","end":"2026-06-10T03:40:26.925518Z","steps":["trace[12698754] 'agreement among raft nodes before linearized reading' (duration: 1.900359439s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:26.925536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:24.557969Z","time spent":"2.367561153s","remote":"127.0.0.1:57014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-s7r9h\" "} {"level":"warn","ts":"2026-06-10T03:40:26.92554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:25.614692Z","time spent":"1.310841408s","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T03:40:26.925543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:25.025096Z","time spent":"1.900443141s","remote":"127.0.0.1:56810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T03:40:26.925424Z","caller":"traceutil/trace.go:171","msg":"trace[1059840203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"1.383676715s","start":"2026-06-10T03:40:25.541731Z","end":"2026-06-10T03:40:26.925408Z","steps":["trace[1059840203] 'agreement among raft nodes before linearized reading' (duration: 1.383508622s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:26.925765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:25.541702Z","time spent":"1.384027522s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-10T03:40:27.995419Z","caller":"traceutil/trace.go:171","msg":"trace[330644577] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"172.960457ms","start":"2026-06-10T03:40:27.822436Z","end":"2026-06-10T03:40:27.995396Z","steps":["trace[330644577] 'process raft request' (duration: 172.436988ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:30.827914Z","caller":"traceutil/trace.go:171","msg":"trace[1821453486] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"132.052313ms","start":"2026-06-10T03:40:30.69583Z","end":"2026-06-10T03:40:30.827883Z","steps":["trace[1821453486] 'read index received' (duration: 79.78345ms)","trace[1821453486] 'applied index is now lower than readState.Index' (duration: 52.267003ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:40:30.828044Z","caller":"traceutil/trace.go:171","msg":"trace[2089120692] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"209.997379ms","start":"2026-06-10T03:40:30.617986Z","end":"2026-06-10T03:40:30.827983Z","steps":["trace[2089120692] 'process raft request' (duration: 157.717166ms)","trace[2089120692] 'compare' (duration: 52.035839ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:40:30.828677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.829339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-06-10T03:40:30.828884Z","caller":"traceutil/trace.go:171","msg":"trace[336250778] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:705; }","duration":"132.961691ms","start":"2026-06-10T03:40:30.695773Z","end":"2026-06-10T03:40:30.828734Z","steps":["trace[336250778] 'agreement among raft nodes before linearized reading' (duration: 132.328238ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:31.079207Z","caller":"traceutil/trace.go:171","msg":"trace[18263980] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"104.122149ms","start":"2026-06-10T03:40:30.975042Z","end":"2026-06-10T03:40:31.079164Z","steps":["trace[18263980] 'process raft request' (duration: 103.883123ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:31.289766Z","caller":"traceutil/trace.go:171","msg":"trace[1928275982] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"314.310741ms","start":"2026-06-10T03:40:30.975409Z","end":"2026-06-10T03:40:31.28972Z","steps":["trace[1928275982] 'process raft request' (duration: 258.804776ms)","trace[1928275982] 'compare' (duration: 55.156438ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:40:31.289867Z","caller":"traceutil/trace.go:171","msg":"trace[122128918] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"314.250169ms","start":"2026-06-10T03:40:30.975574Z","end":"2026-06-10T03:40:31.289824Z","steps":["trace[122128918] 'process raft request' (duration: 314.001234ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:31.29004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:30.975385Z","time spent":"314.475823ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:40:31.290064Z","caller":"traceutil/trace.go:171","msg":"trace[364153658] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:728; }","duration":"194.105374ms","start":"2026-06-10T03:40:31.095926Z","end":"2026-06-10T03:40:31.290032Z","steps":["trace[364153658] 'read index received' (duration: 138.348794ms)","trace[364153658] 'applied index is now lower than readState.Index' (duration: 55.75544ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:40:31.290096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:30.97556Z","time spent":"314.405561ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:40:31.29015Z","caller":"traceutil/trace.go:171","msg":"trace[1416651117] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"314.248928ms","start":"2026-06-10T03:40:30.975866Z","end":"2026-06-10T03:40:31.290115Z","steps":["trace[1416651117] 'process raft request' (duration: 313.79272ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:31.290318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:30.975856Z","time spent":"314.365071ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:40:31.2904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.43641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-06-10T03:40:31.290443Z","caller":"traceutil/trace.go:171","msg":"trace[609481235] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:709; }","duration":"194.539992ms","start":"2026-06-10T03:40:31.095889Z","end":"2026-06-10T03:40:31.290429Z","steps":["trace[609481235] 'agreement among raft nodes before linearized reading' (duration: 194.317698ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:37.228417Z","caller":"traceutil/trace.go:171","msg":"trace[1771946271] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"115.310322ms","start":"2026-06-10T03:40:37.113082Z","end":"2026-06-10T03:40:37.228393Z","steps":["trace[1771946271] 'process raft request' (duration: 115.139879ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:37.439246Z","caller":"traceutil/trace.go:171","msg":"trace[1867916409] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"120.473082ms","start":"2026-06-10T03:40:37.318737Z","end":"2026-06-10T03:40:37.43921Z","steps":["trace[1867916409] 'process raft request' (duration: 69.244858ms)","trace[1867916409] 'compare' (duration: 50.954899ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:40:37.439495Z","caller":"traceutil/trace.go:171","msg":"trace[80568786] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"120.447421ms","start":"2026-06-10T03:40:37.319031Z","end":"2026-06-10T03:40:37.439479Z","steps":["trace[80568786] 'process raft request' (duration: 120.116974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:44.651553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150296119s","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-06-10T03:40:44.651654Z","caller":"traceutil/trace.go:171","msg":"trace[1979776089] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:799; }","duration":"1.150527664s","start":"2026-06-10T03:40:43.5011Z","end":"2026-06-10T03:40:44.651628Z","steps":["trace[1979776089] 'range keys from in-memory index tree' (duration: 1.150029815s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:44.651721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:43.501071Z","time spent":"1.150637686s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T03:40:44.652555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159026828s","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-06-10T03:40:44.652678Z","caller":"traceutil/trace.go:171","msg":"trace[1745697167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"1.159237872s","start":"2026-06-10T03:40:43.493415Z","end":"2026-06-10T03:40:44.652653Z","steps":["trace[1745697167] 'range keys from in-memory index tree' (duration: 1.158822254s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:44.652725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:43.493364Z","time spent":"1.159351124s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T03:40:44.652996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249110955s","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-06-10T03:40:44.65301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.336168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T03:40:44.653084Z","caller":"traceutil/trace.go:171","msg":"trace[284590028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:799; }","duration":"1.249265778s","start":"2026-06-10T03:40:43.403796Z","end":"2026-06-10T03:40:44.653062Z","steps":["trace[284590028] 'range keys from in-memory index tree' (duration: 1.24884741s)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:44.653132Z","caller":"traceutil/trace.go:171","msg":"trace[1469397394] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"414.9274ms","start":"2026-06-10T03:40:44.238186Z","end":"2026-06-10T03:40:44.653114Z","steps":["trace[1469397394] 'read index received' (duration: 244.323347ms)","trace[1469397394] 'applied index is now lower than readState.Index' (duration: 170.602403ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:40:44.65314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:43.403767Z","time spent":"1.24935793s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T03:40:44.653155Z","caller":"traceutil/trace.go:171","msg":"trace[375763371] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"957.302876ms","start":"2026-06-10T03:40:43.695824Z","end":"2026-06-10T03:40:44.653127Z","steps":["trace[375763371] 'process raft request' (duration: 786.754565ms)","trace[375763371] 'compare' (duration: 168.857969ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:40:44.653265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.079194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T03:40:44.653299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:43.69579Z","time spent":"957.426118ms","remote":"127.0.0.1:57060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:40:44.653347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.107571ms","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-06-10T03:40:44.653371Z","caller":"traceutil/trace.go:171","msg":"trace[255528122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"415.189245ms","start":"2026-06-10T03:40:44.238132Z","end":"2026-06-10T03:40:44.653321Z","steps":["trace[255528122] 'agreement among raft nodes before linearized reading' (duration: 415.087073ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:44.653377Z","caller":"traceutil/trace.go:171","msg":"trace[201740753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:800; }","duration":"148.178013ms","start":"2026-06-10T03:40:44.505191Z","end":"2026-06-10T03:40:44.653369Z","steps":["trace[201740753] 'agreement among raft nodes before linearized reading' (duration: 148.094281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:40:44.65341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:40:44.238104Z","time spent":"415.297908ms","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T03:40:44.769014Z","caller":"traceutil/trace.go:171","msg":"trace[977776591] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"110.705943ms","start":"2026-06-10T03:40:44.658286Z","end":"2026-06-10T03:40:44.768992Z","steps":["trace[977776591] 'process raft request' (duration: 100.194122ms)","trace[977776591] 'compare' (duration: 10.405949ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:40:44.770465Z","caller":"traceutil/trace.go:171","msg":"trace[1335045306] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"111.819185ms","start":"2026-06-10T03:40:44.658609Z","end":"2026-06-10T03:40:44.770429Z","steps":["trace[1335045306] 'process raft request' (duration: 111.57876ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:44.771244Z","caller":"traceutil/trace.go:171","msg":"trace[1278217923] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"111.127593ms","start":"2026-06-10T03:40:44.660053Z","end":"2026-06-10T03:40:44.771181Z","steps":["trace[1278217923] 'process raft request' (duration: 110.850307ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:40:44.771288Z","caller":"traceutil/trace.go:171","msg":"trace[617343982] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"112.537939ms","start":"2026-06-10T03:40:44.658713Z","end":"2026-06-10T03:40:44.771251Z","steps":["trace[617343982] 'process raft request' (duration: 111.625261ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:41:13.250579Z","caller":"traceutil/trace.go:171","msg":"trace[1645700188] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"206.260156ms","start":"2026-06-10T03:41:13.044282Z","end":"2026-06-10T03:41:13.250542Z","steps":["trace[1645700188] 'process raft request' (duration: 198.961973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:19.43347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988151645349079961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T03:41:19.933627Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988151645349079961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T03:41:20.053143Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.120991148s","expected-duration":"1s"} {"level":"info","ts":"2026-06-10T03:41:20.053752Z","caller":"traceutil/trace.go:171","msg":"trace[1904896817] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"1.121670621s","start":"2026-06-10T03:41:18.93205Z","end":"2026-06-10T03:41:20.053721Z","steps":["trace[1904896817] 'process raft request' (duration: 1.121461257s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:20.053931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:18.932025Z","time spent":"1.121815223s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:41:20.683336Z","caller":"traceutil/trace.go:171","msg":"trace[1160689901] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1050; }","duration":"1.750266961s","start":"2026-06-10T03:41:18.933036Z","end":"2026-06-10T03:41:20.683303Z","steps":["trace[1160689901] 'read index received' (duration: 1.120407555s)","trace[1160689901] 'applied index is now lower than readState.Index' (duration: 629.857766ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:41:20.683469Z","caller":"traceutil/trace.go:171","msg":"trace[1937211273] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"1.710252289s","start":"2026-06-10T03:41:18.973164Z","end":"2026-06-10T03:41:20.683417Z","steps":["trace[1937211273] 'process raft request' (duration: 1.707882532s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:20.68362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:18.973152Z","time spent":"1.710391492s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:41:20.683628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.750569137s","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-06-10T03:41:20.683695Z","caller":"traceutil/trace.go:171","msg":"trace[65293290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1017; }","duration":"1.75067687s","start":"2026-06-10T03:41:18.933003Z","end":"2026-06-10T03:41:20.68368Z","steps":["trace[65293290] 'agreement among raft nodes before linearized reading' (duration: 1.750447485s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:20.683754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:18.932992Z","time spent":"1.750750741s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T03:41:20.683929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.656058438s","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-06-10T03:41:20.683965Z","caller":"traceutil/trace.go:171","msg":"trace[1586423624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1017; }","duration":"1.656125939s","start":"2026-06-10T03:41:19.027829Z","end":"2026-06-10T03:41:20.683955Z","steps":["trace[1586423624] 'agreement among raft nodes before linearized reading' (duration: 1.656037017s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:20.683994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:19.027811Z","time spent":"1.65617791s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T03:41:20.684131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446625559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T03:41:20.684144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267607026s","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-06-10T03:41:20.684171Z","caller":"traceutil/trace.go:171","msg":"trace[1782690558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"1.446706301s","start":"2026-06-10T03:41:19.237453Z","end":"2026-06-10T03:41:20.684159Z","steps":["trace[1782690558] 'agreement among raft nodes before linearized reading' (duration: 1.446637569s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:20.684178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.655621639s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-10T03:41:20.684189Z","caller":"traceutil/trace.go:171","msg":"trace[135189685] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1017; }","duration":"1.267716698s","start":"2026-06-10T03:41:19.416459Z","end":"2026-06-10T03:41:20.684176Z","steps":["trace[135189685] 'agreement among raft nodes before linearized reading' (duration: 1.267633516s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:20.684205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:19.237432Z","time spent":"1.446766522s","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T03:41:20.684207Z","caller":"traceutil/trace.go:171","msg":"trace[52217878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1017; }","duration":"1.655680129s","start":"2026-06-10T03:41:19.028519Z","end":"2026-06-10T03:41:20.684199Z","steps":["trace[52217878] 'agreement among raft nodes before linearized reading' (duration: 1.655598218s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:20.684199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22538138s","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-06-10T03:41:20.684225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:19.416433Z","time spent":"1.267781409s","remote":"127.0.0.1:57294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-06-10T03:41:20.684241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:19.028471Z","time spent":"1.655763581s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-10T03:41:20.68426Z","caller":"traceutil/trace.go:171","msg":"trace[129448191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1017; }","duration":"1.225468521s","start":"2026-06-10T03:41:19.458778Z","end":"2026-06-10T03:41:20.684246Z","steps":["trace[129448191] 'agreement among raft nodes before linearized reading' (duration: 1.225300728s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:20.684288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:19.458768Z","time spent":"1.225515972s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-10T03:41:20.684466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.798125ms","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-06-10T03:41:20.684526Z","caller":"traceutil/trace.go:171","msg":"trace[404034846] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1017; }","duration":"537.904587ms","start":"2026-06-10T03:41:20.146613Z","end":"2026-06-10T03:41:20.684518Z","steps":["trace[404034846] 'agreement among raft nodes before linearized reading' (duration: 537.783114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:20.684564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:20.146591Z","time spent":"537.961758ms","remote":"127.0.0.1:57176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-06-10T03:41:20.684566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.967288ms","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-06-10T03:41:20.684601Z","caller":"traceutil/trace.go:171","msg":"trace[1997501969] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1017; }","duration":"958.047079ms","start":"2026-06-10T03:41:19.726545Z","end":"2026-06-10T03:41:20.684592Z","steps":["trace[1997501969] 'agreement among raft nodes before linearized reading' (duration: 957.987458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:20.684632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:19.726518Z","time spent":"958.10611ms","remote":"127.0.0.1:57130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-10T03:41:21.192574Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988151645349079966,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T03:41:21.332751Z","caller":"traceutil/trace.go:171","msg":"trace[949681194] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"644.250748ms","start":"2026-06-10T03:41:20.688473Z","end":"2026-06-10T03:41:21.332724Z","steps":["trace[949681194] 'process raft request' (duration: 642.376941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:21.332903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:20.688449Z","time spent":"644.376651ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:41:21.337864Z","caller":"traceutil/trace.go:171","msg":"trace[1417657545] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"648.952039ms","start":"2026-06-10T03:41:20.688888Z","end":"2026-06-10T03:41:21.33784Z","steps":["trace[1417657545] 'process raft request' (duration: 648.826326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:21.338015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:20.688875Z","time spent":"649.063682ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:41:21.338136Z","caller":"traceutil/trace.go:171","msg":"trace[287080190] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1052; }","duration":"646.214855ms","start":"2026-06-10T03:41:20.691902Z","end":"2026-06-10T03:41:21.338117Z","steps":["trace[287080190] 'read index received' (duration: 638.930853ms)","trace[287080190] 'applied index is now lower than readState.Index' (duration: 7.282922ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:41:21.33826Z","caller":"traceutil/trace.go:171","msg":"trace[1283353671] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"649.146542ms","start":"2026-06-10T03:41:20.689102Z","end":"2026-06-10T03:41:21.338249Z","steps":["trace[1283353671] 'process raft request' (duration: 648.703264ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:21.338357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:20.689096Z","time spent":"649.199144ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:41:21.338478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.560682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T03:41:21.338542Z","caller":"traceutil/trace.go:171","msg":"trace[324016116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"646.653625ms","start":"2026-06-10T03:41:20.691875Z","end":"2026-06-10T03:41:21.338528Z","steps":["trace[324016116] 'agreement among raft nodes before linearized reading' (duration: 646.529472ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:21.338576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:20.691866Z","time spent":"646.704265ms","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T03:41:21.338574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.071315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T03:41:21.338616Z","caller":"traceutil/trace.go:171","msg":"trace[870858717] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1021; }","duration":"547.150947ms","start":"2026-06-10T03:41:20.791454Z","end":"2026-06-10T03:41:21.338605Z","steps":["trace[870858717] 'agreement among raft nodes before linearized reading' (duration: 547.083016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:21.338668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:20.791431Z","time spent":"547.218299ms","remote":"127.0.0.1:57146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-06-10T03:41:21.338889Z","caller":"traceutil/trace.go:171","msg":"trace[1213209199] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"649.194504ms","start":"2026-06-10T03:41:20.68968Z","end":"2026-06-10T03:41:21.338874Z","steps":["trace[1213209199] 'process raft request' (duration: 648.341157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:21.338975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:20.689672Z","time spent":"649.268075ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:41:43.113024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.118294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T03:41:43.113169Z","caller":"traceutil/trace.go:171","msg":"trace[1327629187] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"401.470496ms","start":"2026-06-10T03:41:42.711673Z","end":"2026-06-10T03:41:43.113143Z","steps":["trace[1327629187] 'process raft request' (duration: 82.134427ms)","trace[1327629187] 'compare' (duration: 318.938791ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:41:43.113232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:42.711648Z","time spent":"401.556428ms","remote":"127.0.0.1:56850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:41:43.115531Z","caller":"traceutil/trace.go:171","msg":"trace[2134180102] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"352.356165ms","start":"2026-06-10T03:41:42.763151Z","end":"2026-06-10T03:41:43.115507Z","steps":["trace[2134180102] 'process raft request' (duration: 352.210162ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:43.115683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:42.763118Z","time spent":"352.485348ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:41:43.770569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.223355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T03:41:43.77061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.80893ms","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-06-10T03:41:43.770688Z","caller":"traceutil/trace.go:171","msg":"trace[1832575661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"339.928243ms","start":"2026-06-10T03:41:43.430739Z","end":"2026-06-10T03:41:43.770667Z","steps":["trace[1832575661] 'range keys from in-memory index tree' (duration: 339.649967ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:41:43.770708Z","caller":"traceutil/trace.go:171","msg":"trace[1462758072] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1090; }","duration":"254.359538ms","start":"2026-06-10T03:41:43.516274Z","end":"2026-06-10T03:41:43.770633Z","steps":["trace[1462758072] 'count revisions from in-memory index tree' (duration: 254.105003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:43.770725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:43.430717Z","time spent":"339.999453ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-10T03:41:43.770929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.726863ms","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":"warn","ts":"2026-06-10T03:41:43.77094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.876214ms","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-06-10T03:41:43.770958Z","caller":"traceutil/trace.go:171","msg":"trace[612357925] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1090; }","duration":"212.786774ms","start":"2026-06-10T03:41:43.558163Z","end":"2026-06-10T03:41:43.77095Z","steps":["trace[612357925] 'count revisions from in-memory index tree' (duration: 212.666912ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:41:43.77097Z","caller":"traceutil/trace.go:171","msg":"trace[1365702606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"335.925824ms","start":"2026-06-10T03:41:43.435035Z","end":"2026-06-10T03:41:43.770961Z","steps":["trace[1365702606] 'range keys from in-memory index tree' (duration: 335.781042ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:43.770998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:43.435024Z","time spent":"335.967675ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T03:41:43.771177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.732321ms","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-06-10T03:41:43.771209Z","caller":"traceutil/trace.go:171","msg":"trace[1637016511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1090; }","duration":"331.784163ms","start":"2026-06-10T03:41:43.439416Z","end":"2026-06-10T03:41:43.7712Z","steps":["trace[1637016511] 'range keys from in-memory index tree' (duration: 331.636649ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:43.771233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:43.439404Z","time spent":"331.823134ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T03:41:43.771413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.219443ms","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-06-10T03:41:43.771437Z","caller":"traceutil/trace.go:171","msg":"trace[1692197437] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"331.260323ms","start":"2026-06-10T03:41:43.44017Z","end":"2026-06-10T03:41:43.77143Z","steps":["trace[1692197437] 'range keys from in-memory index tree' (duration: 331.0888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:43.771456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:43.440161Z","time spent":"331.290374ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-10T03:41:43.771798Z","caller":"traceutil/trace.go:171","msg":"trace[241834141] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"189.841236ms","start":"2026-06-10T03:41:43.581939Z","end":"2026-06-10T03:41:43.77178Z","steps":["trace[241834141] 'process raft request' (duration: 189.677663ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:41:44.301356Z","caller":"traceutil/trace.go:171","msg":"trace[356784746] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"525.482423ms","start":"2026-06-10T03:41:43.775841Z","end":"2026-06-10T03:41:44.301323Z","steps":["trace[356784746] 'process raft request' (duration: 474.696ms)","trace[356784746] 'compare' (duration: 50.672701ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:41:44.301515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:43.77582Z","time spent":"525.616955ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:41:44.349079Z","caller":"traceutil/trace.go:171","msg":"trace[1460953220] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"572.542954ms","start":"2026-06-10T03:41:43.776498Z","end":"2026-06-10T03:41:44.349041Z","steps":["trace[1460953220] 'process raft request' (duration: 572.272689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:44.349274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:43.776467Z","time spent":"572.712397ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:41:44.349366Z","caller":"traceutil/trace.go:171","msg":"trace[259587646] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1131; }","duration":"188.283784ms","start":"2026-06-10T03:41:44.16105Z","end":"2026-06-10T03:41:44.349334Z","steps":["trace[259587646] 'read index received' (duration: 89.501092ms)","trace[259587646] 'applied index is now lower than readState.Index' (duration: 98.781082ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:41:44.349381Z","caller":"traceutil/trace.go:171","msg":"trace[1100713823] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"572.733098ms","start":"2026-06-10T03:41:43.776596Z","end":"2026-06-10T03:41:44.349329Z","steps":["trace[1100713823] 'process raft request' (duration: 572.391922ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:41:44.34951Z","caller":"traceutil/trace.go:171","msg":"trace[372950085] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"572.932172ms","start":"2026-06-10T03:41:43.776565Z","end":"2026-06-10T03:41:44.349497Z","steps":["trace[372950085] 'process raft request' (duration: 572.644926ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:44.349623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:43.776545Z","time spent":"572.998054ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:41:44.349628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.698556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T03:41:44.34969Z","caller":"traceutil/trace.go:171","msg":"trace[445929143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"111.793628ms","start":"2026-06-10T03:41:44.237879Z","end":"2026-06-10T03:41:44.349673Z","steps":["trace[445929143] 'agreement among raft nodes before linearized reading' (duration: 111.693926ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:44.349753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.693863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-10T03:41:44.349805Z","caller":"traceutil/trace.go:171","msg":"trace[1288918365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1095; }","duration":"188.818825ms","start":"2026-06-10T03:41:44.160973Z","end":"2026-06-10T03:41:44.349792Z","steps":["trace[1288918365] 'agreement among raft nodes before linearized reading' (duration: 188.643022ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:44.350148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:43.776578Z","time spent":"572.931673ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:41:44.489344Z","caller":"traceutil/trace.go:171","msg":"trace[282731059] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"134.786828ms","start":"2026-06-10T03:41:44.354521Z","end":"2026-06-10T03:41:44.489308Z","steps":["trace[282731059] 'process raft request' (duration: 55.271472ms)","trace[282731059] 'compare' (duration: 79.350102ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:41:45.366403Z","caller":"traceutil/trace.go:171","msg":"trace[660675487] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"128.275131ms","start":"2026-06-10T03:41:45.238105Z","end":"2026-06-10T03:41:45.36638Z","steps":["trace[660675487] 'read index received' (duration: 128.116498ms)","trace[660675487] 'applied index is now lower than readState.Index' (duration: 157.253µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:41:45.366459Z","caller":"traceutil/trace.go:171","msg":"trace[183929280] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"245.217489ms","start":"2026-06-10T03:41:45.121212Z","end":"2026-06-10T03:41:45.366429Z","steps":["trace[183929280] 'process raft request' (duration: 245.013525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:45.366664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.541406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T03:41:45.366761Z","caller":"traceutil/trace.go:171","msg":"trace[1361519291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1097; }","duration":"128.678849ms","start":"2026-06-10T03:41:45.238047Z","end":"2026-06-10T03:41:45.366726Z","steps":["trace[1361519291] 'agreement among raft nodes before linearized reading' (duration: 128.528506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:46.324322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.519859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T03:41:46.324505Z","caller":"traceutil/trace.go:171","msg":"trace[477744258] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1097; }","duration":"794.731323ms","start":"2026-06-10T03:41:45.529729Z","end":"2026-06-10T03:41:46.32446Z","steps":["trace[477744258] 'count revisions from in-memory index tree' (duration: 794.428777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:46.324594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:45.529712Z","time spent":"794.865695ms","remote":"127.0.0.1:52876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-06-10T03:41:46.503732Z","caller":"traceutil/trace.go:171","msg":"trace[184788366] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"145.582709ms","start":"2026-06-10T03:41:46.358118Z","end":"2026-06-10T03:41:46.503701Z","steps":["trace[184788366] 'process raft request' (duration: 94.0424ms)","trace[184788366] 'compare' (duration: 51.323774ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:41:46.548876Z","caller":"traceutil/trace.go:171","msg":"trace[1353077961] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"190.527098ms","start":"2026-06-10T03:41:46.358322Z","end":"2026-06-10T03:41:46.548849Z","steps":["trace[1353077961] 'process raft request' (duration: 190.293293ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:41:46.548922Z","caller":"traceutil/trace.go:171","msg":"trace[182183247] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"190.254693ms","start":"2026-06-10T03:41:46.358627Z","end":"2026-06-10T03:41:46.548882Z","steps":["trace[182183247] 'process raft request' (duration: 190.133771ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:41:47.624222Z","caller":"traceutil/trace.go:171","msg":"trace[1819426584] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"252.872768ms","start":"2026-06-10T03:41:47.371296Z","end":"2026-06-10T03:41:47.624169Z","steps":["trace[1819426584] 'process raft request' (duration: 252.666234ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:41:48.520563Z","caller":"traceutil/trace.go:171","msg":"trace[261172292] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"175.910943ms","start":"2026-06-10T03:41:48.34462Z","end":"2026-06-10T03:41:48.520531Z","steps":["trace[261172292] 'process raft request' (duration: 175.641527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:48.876257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.856151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T03:41:48.877052Z","caller":"traceutil/trace.go:171","msg":"trace[215333525] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1145; }","duration":"324.924428ms","start":"2026-06-10T03:41:48.552107Z","end":"2026-06-10T03:41:48.877031Z","steps":["trace[215333525] 'read index received' (duration: 145.217592ms)","trace[215333525] 'applied index is now lower than readState.Index' (duration: 179.704936ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:41:48.877136Z","caller":"traceutil/trace.go:171","msg":"trace[342926238] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"352.186552ms","start":"2026-06-10T03:41:48.524938Z","end":"2026-06-10T03:41:48.877124Z","steps":["trace[342926238] 'process raft request' (duration: 172.368883ms)","trace[342926238] 'compare' (duration: 178.723147ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:41:48.877224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:48.524906Z","time spent":"352.256843ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:41:48.877512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.403197ms","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-06-10T03:41:48.877543Z","caller":"traceutil/trace.go:171","msg":"trace[941938888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1105; }","duration":"325.46429ms","start":"2026-06-10T03:41:48.552069Z","end":"2026-06-10T03:41:48.877533Z","steps":["trace[941938888] 'agreement among raft nodes before linearized reading' (duration: 325.329016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:48.877571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:48.552048Z","time spent":"325.51698ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-10T03:41:48.913666Z","caller":"traceutil/trace.go:171","msg":"trace[1520257913] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"357.385614ms","start":"2026-06-10T03:41:48.556232Z","end":"2026-06-10T03:41:48.913617Z","steps":["trace[1520257913] 'process raft request' (duration: 357.241281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:48.913763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.907605ms","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-06-10T03:41:48.913797Z","caller":"traceutil/trace.go:171","msg":"trace[12048391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1106; }","duration":"331.007227ms","start":"2026-06-10T03:41:48.582778Z","end":"2026-06-10T03:41:48.913786Z","steps":["trace[12048391] 'agreement among raft nodes before linearized reading' (duration: 330.940646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:48.913825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:48.582756Z","time spent":"331.063118ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T03:41:48.91385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:48.556214Z","time spent":"357.549376ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:41:48.91369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.768099ms","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-06-10T03:41:48.914037Z","caller":"traceutil/trace.go:171","msg":"trace[1791410218] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1106; }","duration":"361.137837ms","start":"2026-06-10T03:41:48.552888Z","end":"2026-06-10T03:41:48.914026Z","steps":["trace[1791410218] 'agreement among raft nodes before linearized reading' (duration: 360.701188ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:41:48.914067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:41:48.552881Z","time spent":"361.178238ms","remote":"127.0.0.1:57084","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-06-10T03:42:02.814746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.10547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-10T03:42:02.814833Z","caller":"traceutil/trace.go:171","msg":"trace[1174971640] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:1202; }","duration":"146.265753ms","start":"2026-06-10T03:42:02.668548Z","end":"2026-06-10T03:42:02.814814Z","steps":["trace[1174971640] 'range keys from in-memory index tree' (duration: 145.916166ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:04.594358Z","caller":"traceutil/trace.go:171","msg":"trace[1096788792] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"912.219102ms","start":"2026-06-10T03:42:03.682106Z","end":"2026-06-10T03:42:04.594325Z","steps":["trace[1096788792] 'process raft request' (duration: 912.031398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:04.594538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:03.682073Z","time spent":"912.373945ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:42:05.117489Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988151645349080438,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T03:42:05.275923Z","caller":"traceutil/trace.go:171","msg":"trace[549986661] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1254; }","duration":"658.745021ms","start":"2026-06-10T03:42:04.617145Z","end":"2026-06-10T03:42:05.27589Z","steps":["trace[549986661] 'read index received' (duration: 657.194ms)","trace[549986661] 'applied index is now lower than readState.Index' (duration: 1.549811ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:42:05.276015Z","caller":"traceutil/trace.go:171","msg":"trace[1820793821] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"1.183314427s","start":"2026-06-10T03:42:04.092665Z","end":"2026-06-10T03:42:05.275979Z","steps":["trace[1820793821] 'process raft request' (duration: 1.181632684s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:05.276206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:04.092633Z","time spent":"1.183450659s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:42:05.276215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.875134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-10T03:42:05.276267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.410419ms","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-06-10T03:42:05.276282Z","caller":"traceutil/trace.go:171","msg":"trace[1840889586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1209; }","duration":"331.993217ms","start":"2026-06-10T03:42:04.944272Z","end":"2026-06-10T03:42:05.276265Z","steps":["trace[1840889586] 'agreement among raft nodes before linearized reading' (duration: 331.793483ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:05.276309Z","caller":"traceutil/trace.go:171","msg":"trace[629742354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1209; }","duration":"253.50365ms","start":"2026-06-10T03:42:05.022794Z","end":"2026-06-10T03:42:05.276298Z","steps":["trace[629742354] 'agreement among raft nodes before linearized reading' (duration: 253.274676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:05.276318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:04.944248Z","time spent":"332.063568ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T03:42:05.276279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.108398ms","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-06-10T03:42:05.276406Z","caller":"traceutil/trace.go:171","msg":"trace[478898585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1209; }","duration":"659.298922ms","start":"2026-06-10T03:42:04.617074Z","end":"2026-06-10T03:42:05.276373Z","steps":["trace[478898585] 'agreement among raft nodes before linearized reading' (duration: 658.947075ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:05.276466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.225114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-10T03:42:05.276485Z","caller":"traceutil/trace.go:171","msg":"trace[561074063] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1209; }","duration":"256.304856ms","start":"2026-06-10T03:42:05.020172Z","end":"2026-06-10T03:42:05.276477Z","steps":["trace[561074063] 'agreement among raft nodes before linearized reading' (duration: 256.254875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:05.276479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:04.617047Z","time spent":"659.413154ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T03:42:05.276653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.116463ms","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-06-10T03:42:05.27667Z","caller":"traceutil/trace.go:171","msg":"trace[943449941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1209; }","duration":"275.187314ms","start":"2026-06-10T03:42:05.001477Z","end":"2026-06-10T03:42:05.276664Z","steps":["trace[943449941] 'agreement among raft nodes before linearized reading' (duration: 275.138263ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:20.304675Z","caller":"traceutil/trace.go:171","msg":"trace[74479460] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"203.653785ms","start":"2026-06-10T03:42:20.101005Z","end":"2026-06-10T03:42:20.304659Z","steps":["trace[74479460] 'process raft request' (duration: 203.525632ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:20.931297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.451113ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T03:42:20.931439Z","caller":"traceutil/trace.go:171","msg":"trace[1804258655] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1278; }","duration":"148.621086ms","start":"2026-06-10T03:42:20.782798Z","end":"2026-06-10T03:42:20.931419Z","steps":["trace[1804258655] 'range keys from in-memory index tree' (duration: 148.430772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:20.931449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.005882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T03:42:20.931651Z","caller":"traceutil/trace.go:171","msg":"trace[395076484] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"619.932057ms","start":"2026-06-10T03:42:20.311687Z","end":"2026-06-10T03:42:20.931619Z","steps":["trace[395076484] 'process raft request' (duration: 327.673491ms)","trace[395076484] 'compare' (duration: 291.887059ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:42:20.931815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:20.31167Z","time spent":"620.074751ms","remote":"127.0.0.1:57096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:42:20.932207Z","caller":"traceutil/trace.go:171","msg":"trace[2011280418] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1327; }","duration":"523.051219ms","start":"2026-06-10T03:42:20.40914Z","end":"2026-06-10T03:42:20.932191Z","steps":["trace[2011280418] 'read index received' (duration: 230.196552ms)","trace[2011280418] 'applied index is now lower than readState.Index' (duration: 292.847377ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:42:20.932207Z","caller":"traceutil/trace.go:171","msg":"trace[109197604] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"616.090664ms","start":"2026-06-10T03:42:20.316097Z","end":"2026-06-10T03:42:20.932188Z","steps":["trace[109197604] 'process raft request' (duration: 615.463652ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:20.932435Z","caller":"traceutil/trace.go:171","msg":"trace[1436923591] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"273.035749ms","start":"2026-06-10T03:42:20.659338Z","end":"2026-06-10T03:42:20.932373Z","steps":["trace[1436923591] 'process raft request' (duration: 272.801124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:20.933301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:20.316085Z","time spent":"616.553433ms","remote":"127.0.0.1:57292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:42:20.932525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.385126ms","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-06-10T03:42:20.933965Z","caller":"traceutil/trace.go:171","msg":"trace[423096927] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1281; }","duration":"524.559978ms","start":"2026-06-10T03:42:20.409121Z","end":"2026-06-10T03:42:20.933681Z","steps":["trace[423096927] 'agreement among raft nodes before linearized reading' (duration: 523.330755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:20.934467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:20.409054Z","time spent":"525.049207ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-06-10T03:42:25.27339Z","caller":"traceutil/trace.go:171","msg":"trace[450143302] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"326.642542ms","start":"2026-06-10T03:42:24.94673Z","end":"2026-06-10T03:42:25.273373Z","steps":["trace[450143302] 'process raft request' (duration: 326.250965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:25.273493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:24.946706Z","time spent":"326.730143ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:42:25.614778Z","caller":"traceutil/trace.go:171","msg":"trace[1876269978] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"144.853624ms","start":"2026-06-10T03:42:25.469903Z","end":"2026-06-10T03:42:25.614757Z","steps":["trace[1876269978] 'process raft request' (duration: 144.816853ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:25.615143Z","caller":"traceutil/trace.go:171","msg":"trace[775563562] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"173.742615ms","start":"2026-06-10T03:42:25.441388Z","end":"2026-06-10T03:42:25.61513Z","steps":["trace[775563562] 'process raft request' (duration: 173.151034ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:25.61528Z","caller":"traceutil/trace.go:171","msg":"trace[1571694606] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"171.869219ms","start":"2026-06-10T03:42:25.443402Z","end":"2026-06-10T03:42:25.615271Z","steps":["trace[1571694606] 'process raft request' (duration: 171.223087ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:25.615415Z","caller":"traceutil/trace.go:171","msg":"trace[1056514897] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"171.40013ms","start":"2026-06-10T03:42:25.444007Z","end":"2026-06-10T03:42:25.615407Z","steps":["trace[1056514897] 'process raft request' (duration: 170.652566ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:25.615706Z","caller":"traceutil/trace.go:171","msg":"trace[427869744] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"171.432711ms","start":"2026-06-10T03:42:25.444261Z","end":"2026-06-10T03:42:25.615694Z","steps":["trace[427869744] 'process raft request' (duration: 170.427662ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:27.460806Z","caller":"traceutil/trace.go:171","msg":"trace[2012263951] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"225.895499ms","start":"2026-06-10T03:42:27.23489Z","end":"2026-06-10T03:42:27.460785Z","steps":["trace[2012263951] 'read index received' (duration: 225.788327ms)","trace[2012263951] 'applied index is now lower than readState.Index' (duration: 106.212µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:42:27.460981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.072723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T03:42:27.461015Z","caller":"traceutil/trace.go:171","msg":"trace[379254499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1315; }","duration":"226.159644ms","start":"2026-06-10T03:42:27.234845Z","end":"2026-06-10T03:42:27.461005Z","steps":["trace[379254499] 'agreement among raft nodes before linearized reading' (duration: 226.027832ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:27.461226Z","caller":"traceutil/trace.go:171","msg":"trace[1024045368] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"883.267461ms","start":"2026-06-10T03:42:26.577927Z","end":"2026-06-10T03:42:27.461194Z","steps":["trace[1024045368] 'process raft request' (duration: 882.71828ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:27.461453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:26.577889Z","time spent":"883.419695ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:42:27.523852Z","caller":"traceutil/trace.go:171","msg":"trace[67696399] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"245.781759ms","start":"2026-06-10T03:42:27.278035Z","end":"2026-06-10T03:42:27.523817Z","steps":["trace[67696399] 'process raft request' (duration: 245.598355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:27.869047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.198078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T03:42:27.869162Z","caller":"traceutil/trace.go:171","msg":"trace[88065677] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1366; }","duration":"247.662765ms","start":"2026-06-10T03:42:27.621484Z","end":"2026-06-10T03:42:27.869147Z","steps":["trace[88065677] 'read index received' (duration: 111.207363ms)","trace[88065677] 'applied index is now lower than readState.Index' (duration: 136.453912ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:42:27.8692Z","caller":"traceutil/trace.go:171","msg":"trace[1095848178] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"342.236319ms","start":"2026-06-10T03:42:27.526923Z","end":"2026-06-10T03:42:27.86916Z","steps":["trace[1095848178] 'process raft request' (duration: 205.844477ms)","trace[1095848178] 'compare' (duration: 136.023935ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:42:27.869295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:27.5269Z","time spent":"342.351581ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:42:27.869368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.883139ms","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-06-10T03:42:27.869404Z","caller":"traceutil/trace.go:171","msg":"trace[1307131064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1317; }","duration":"247.963381ms","start":"2026-06-10T03:42:27.621432Z","end":"2026-06-10T03:42:27.869395Z","steps":["trace[1307131064] 'agreement among raft nodes before linearized reading' (duration: 247.819848ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:27.869474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.832158ms","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-06-10T03:42:27.869511Z","caller":"traceutil/trace.go:171","msg":"trace[1180392165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1317; }","duration":"247.88452ms","start":"2026-06-10T03:42:27.621617Z","end":"2026-06-10T03:42:27.869502Z","steps":["trace[1180392165] 'agreement among raft nodes before linearized reading' (duration: 247.775288ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:27.869517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.952011ms","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-06-10T03:42:27.869598Z","caller":"traceutil/trace.go:171","msg":"trace[1547160462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1317; }","duration":"248.091653ms","start":"2026-06-10T03:42:27.621485Z","end":"2026-06-10T03:42:27.869577Z","steps":["trace[1547160462] 'agreement among raft nodes before linearized reading' (duration: 247.88713ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:27.869588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.969691ms","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-06-10T03:42:27.869654Z","caller":"traceutil/trace.go:171","msg":"trace[164761632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1317; }","duration":"248.089694ms","start":"2026-06-10T03:42:27.62155Z","end":"2026-06-10T03:42:27.86964Z","steps":["trace[164761632] 'agreement among raft nodes before linearized reading' (duration: 247.87352ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:28.054053Z","caller":"traceutil/trace.go:171","msg":"trace[517217063] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"179.017885ms","start":"2026-06-10T03:42:27.875011Z","end":"2026-06-10T03:42:28.054029Z","steps":["trace[517217063] 'process raft request' (duration: 149.423161ms)","trace[517217063] 'compare' (duration: 29.452052ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:42:28.054178Z","caller":"traceutil/trace.go:171","msg":"trace[860461732] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"178.080347ms","start":"2026-06-10T03:42:27.876086Z","end":"2026-06-10T03:42:28.054166Z","steps":["trace[860461732] 'process raft request' (duration: 177.908714ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:28.054346Z","caller":"traceutil/trace.go:171","msg":"trace[959780720] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"178.128148ms","start":"2026-06-10T03:42:27.876211Z","end":"2026-06-10T03:42:28.054339Z","steps":["trace[959780720] 'process raft request' (duration: 177.924154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:30.161518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.24036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T03:42:30.161602Z","caller":"traceutil/trace.go:171","msg":"trace[244245514] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1333; }","duration":"103.360692ms","start":"2026-06-10T03:42:30.058222Z","end":"2026-06-10T03:42:30.161583Z","steps":["trace[244245514] 'count revisions from in-memory index tree' (duration: 103.184178ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:30.161605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.495033ms","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-06-10T03:42:30.161686Z","caller":"traceutil/trace.go:171","msg":"trace[1044374481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1333; }","duration":"104.628696ms","start":"2026-06-10T03:42:30.057037Z","end":"2026-06-10T03:42:30.161666Z","steps":["trace[1044374481] 'range keys from in-memory index tree' (duration: 104.266718ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:30.161713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.607096ms","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-06-10T03:42:30.161783Z","caller":"traceutil/trace.go:171","msg":"trace[1185142758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1333; }","duration":"103.694748ms","start":"2026-06-10T03:42:30.058069Z","end":"2026-06-10T03:42:30.161764Z","steps":["trace[1185142758] 'range keys from in-memory index tree' (duration: 103.490565ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:30.161703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.827301ms","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-06-10T03:42:30.161918Z","caller":"traceutil/trace.go:171","msg":"trace[938232312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1333; }","duration":"104.095456ms","start":"2026-06-10T03:42:30.057814Z","end":"2026-06-10T03:42:30.161909Z","steps":["trace[938232312] 'range keys from in-memory index tree' (duration: 103.667057ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:34.367584Z","caller":"traceutil/trace.go:171","msg":"trace[1420092348] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"468.142161ms","start":"2026-06-10T03:42:33.899414Z","end":"2026-06-10T03:42:34.367556Z","steps":["trace[1420092348] 'process raft request' (duration: 467.975918ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:34.367741Z","caller":"traceutil/trace.go:171","msg":"trace[1022457859] linearizableReadLoop","detail":"{readStateIndex:1419; appliedIndex:1419; }","duration":"422.696204ms","start":"2026-06-10T03:42:33.945018Z","end":"2026-06-10T03:42:34.367714Z","steps":["trace[1022457859] 'read index received' (duration: 422.686124ms)","trace[1022457859] 'applied index is now lower than readState.Index' (duration: 7.98µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:42:34.367772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:33.899395Z","time spent":"468.282824ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:42:34.367922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.100782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T03:42:34.367958Z","caller":"traceutil/trace.go:171","msg":"trace[1198396456] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1367; }","duration":"413.165243ms","start":"2026-06-10T03:42:33.954782Z","end":"2026-06-10T03:42:34.367947Z","steps":["trace[1198396456] 'agreement among raft nodes before linearized reading' (duration: 413.05713ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:34.367998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:33.954726Z","time spent":"413.262765ms","remote":"127.0.0.1:57114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-06-10T03:42:34.368066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.394689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T03:42:34.368186Z","caller":"traceutil/trace.go:171","msg":"trace[1355512475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1367; }","duration":"130.572242ms","start":"2026-06-10T03:42:34.237583Z","end":"2026-06-10T03:42:34.368156Z","steps":["trace[1355512475] 'agreement among raft nodes before linearized reading' (duration: 130.387139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:34.368323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.310897ms","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-06-10T03:42:34.36836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.103136ms","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-06-10T03:42:34.368366Z","caller":"traceutil/trace.go:171","msg":"trace[1530329655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1367; }","duration":"423.403889ms","start":"2026-06-10T03:42:33.944951Z","end":"2026-06-10T03:42:34.368355Z","steps":["trace[1530329655] 'agreement among raft nodes before linearized reading' (duration: 423.261036ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:34.368394Z","caller":"traceutil/trace.go:171","msg":"trace[1153683033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1367; }","duration":"181.157107ms","start":"2026-06-10T03:42:34.187227Z","end":"2026-06-10T03:42:34.368385Z","steps":["trace[1153683033] 'agreement among raft nodes before linearized reading' (duration: 181.012074ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:34.368407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:33.944924Z","time spent":"423.47621ms","remote":"127.0.0.1:57084","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-06-10T03:42:34.369147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.4954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-10T03:42:34.369188Z","caller":"traceutil/trace.go:171","msg":"trace[1273316290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1367; }","duration":"183.600132ms","start":"2026-06-10T03:42:34.185578Z","end":"2026-06-10T03:42:34.369178Z","steps":["trace[1273316290] 'agreement among raft nodes before linearized reading' (duration: 183.335567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:34.370264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.16853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-10T03:42:34.370336Z","caller":"traceutil/trace.go:171","msg":"trace[1697703524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1367; }","duration":"188.284721ms","start":"2026-06-10T03:42:34.182034Z","end":"2026-06-10T03:42:34.370319Z","steps":["trace[1697703524] 'agreement among raft nodes before linearized reading' (duration: 186.918105ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:34.752393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.105744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T03:42:34.752696Z","caller":"traceutil/trace.go:171","msg":"trace[422746490] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"378.957209ms","start":"2026-06-10T03:42:34.373711Z","end":"2026-06-10T03:42:34.752668Z","steps":["trace[422746490] 'process raft request' (duration: 77.467318ms)","trace[422746490] 'compare' (duration: 300.84056ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:42:34.75278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:34.373675Z","time spent":"379.066611ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:42:34.753316Z","caller":"traceutil/trace.go:171","msg":"trace[160256381] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"377.89966ms","start":"2026-06-10T03:42:34.375405Z","end":"2026-06-10T03:42:34.753305Z","steps":["trace[160256381] 'process raft request' (duration: 377.835389ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:34.75337Z","caller":"traceutil/trace.go:171","msg":"trace[114290856] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"379.029232ms","start":"2026-06-10T03:42:34.374316Z","end":"2026-06-10T03:42:34.753345Z","steps":["trace[114290856] 'process raft request' (duration: 378.302368ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:34.753388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:34.375393Z","time spent":"377.960641ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:42:34.753536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:34.374289Z","time spent":"379.136313ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:42:34.753763Z","caller":"traceutil/trace.go:171","msg":"trace[577664762] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"377.874859ms","start":"2026-06-10T03:42:34.375879Z","end":"2026-06-10T03:42:34.753753Z","steps":["trace[577664762] 'process raft request' (duration: 377.397671ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:34.753826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:34.375863Z","time spent":"377.93857ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:42:34.753553Z","caller":"traceutil/trace.go:171","msg":"trace[1089527944] linearizableReadLoop","detail":"{readStateIndex:1424; appliedIndex:1420; }","duration":"162.952088ms","start":"2026-06-10T03:42:34.59058Z","end":"2026-06-10T03:42:34.753532Z","steps":["trace[1089527944] 'read index received' (duration: 55.051µs)","trace[1089527944] 'applied index is now lower than readState.Index' (duration: 162.895767ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:42:34.753938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.331455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T03:42:34.753984Z","caller":"traceutil/trace.go:171","msg":"trace[92101297] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1372; }","duration":"163.442757ms","start":"2026-06-10T03:42:34.59053Z","end":"2026-06-10T03:42:34.753972Z","steps":["trace[92101297] 'agreement among raft nodes before linearized reading' (duration: 163.350466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:36.044104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.397309ms","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-06-10T03:42:36.044147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146523844s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-10T03:42:36.044222Z","caller":"traceutil/trace.go:171","msg":"trace[208200306] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1372; }","duration":"1.146642037s","start":"2026-06-10T03:42:34.897559Z","end":"2026-06-10T03:42:36.044201Z","steps":["trace[208200306] 'range keys from in-memory index tree' (duration: 1.146344991s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:36.044263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:34.897534Z","time spent":"1.146719998s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-06-10T03:42:36.04421Z","caller":"traceutil/trace.go:171","msg":"trace[285628361] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1372; }","duration":"606.551202ms","start":"2026-06-10T03:42:35.437635Z","end":"2026-06-10T03:42:36.044186Z","steps":["trace[285628361] 'count revisions from in-memory index tree' (duration: 606.322858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:36.044648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:35.437616Z","time spent":"606.920569ms","remote":"127.0.0.1:57106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-10T03:42:36.044818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.574128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T03:42:36.044867Z","caller":"traceutil/trace.go:171","msg":"trace[640310889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1372; }","duration":"807.658869ms","start":"2026-06-10T03:42:35.237192Z","end":"2026-06-10T03:42:36.044851Z","steps":["trace[640310889] 'range keys from in-memory index tree' (duration: 807.375974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:36.044906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:35.237171Z","time spent":"807.72606ms","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T03:42:36.286699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988151645349080776,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T03:42:36.549799Z","caller":"traceutil/trace.go:171","msg":"trace[455510093] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"999.675792ms","start":"2026-06-10T03:42:35.550097Z","end":"2026-06-10T03:42:36.549773Z","steps":["trace[455510093] 'process raft request' (duration: 999.495809ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:36.549965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:35.550076Z","time spent":"999.800095ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:42:37.030963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.13649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T03:42:37.031099Z","caller":"traceutil/trace.go:171","msg":"trace[838172371] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1424; }","duration":"1.244432642s","start":"2026-06-10T03:42:35.786654Z","end":"2026-06-10T03:42:37.031087Z","steps":["trace[838172371] 'read index received' (duration: 762.997717ms)","trace[838172371] 'applied index is now lower than readState.Index' (duration: 481.434185ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T03:42:37.03117Z","caller":"traceutil/trace.go:171","msg":"trace[522438459] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"1.326758713s","start":"2026-06-10T03:42:35.704403Z","end":"2026-06-10T03:42:37.031162Z","steps":["trace[522438459] 'process raft request' (duration: 1.193364488s)","trace[522438459] 'compare' (duration: 132.672321ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T03:42:37.031205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:35.704375Z","time spent":"1.326810604s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T03:42:37.031285Z","caller":"traceutil/trace.go:171","msg":"trace[244755428] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"981.523976ms","start":"2026-06-10T03:42:36.049756Z","end":"2026-06-10T03:42:37.03128Z","steps":["trace[244755428] 'process raft request' (duration: 981.288311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:37.03131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:36.049736Z","time spent":"981.559287ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T03:42:37.031665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.40888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T03:42:37.031753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.245104315s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-06-10T03:42:37.031744Z","caller":"traceutil/trace.go:171","msg":"trace[2091203436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1375; }","duration":"985.536293ms","start":"2026-06-10T03:42:36.046188Z","end":"2026-06-10T03:42:37.031724Z","steps":["trace[2091203436] 'agreement among raft nodes before linearized reading' (duration: 985.41521ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:37.03178Z","caller":"traceutil/trace.go:171","msg":"trace[1323750989] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1375; }","duration":"1.245172326s","start":"2026-06-10T03:42:35.786598Z","end":"2026-06-10T03:42:37.031771Z","steps":["trace[1323750989] 'agreement among raft nodes before linearized reading' (duration: 1.245075594s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:37.031799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:35.786577Z","time spent":"1.245218387s","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-10T03:42:37.031797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:36.046158Z","time spent":"985.629974ms","remote":"127.0.0.1:56810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T03:42:37.031841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.871588ms","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-06-10T03:42:37.03188Z","caller":"traceutil/trace.go:171","msg":"trace[2089790171] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1375; }","duration":"639.957299ms","start":"2026-06-10T03:42:36.391913Z","end":"2026-06-10T03:42:37.03187Z","steps":["trace[2089790171] 'agreement among raft nodes before linearized reading' (duration: 639.802277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:37.031883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.268212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-10T03:42:37.031864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.244378ms","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-06-10T03:42:37.031865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.569259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-10T03:42:37.03193Z","caller":"traceutil/trace.go:171","msg":"trace[1486317364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1375; }","duration":"659.35436ms","start":"2026-06-10T03:42:36.372561Z","end":"2026-06-10T03:42:37.031915Z","steps":["trace[1486317364] 'agreement among raft nodes before linearized reading' (duration: 659.189307ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:37.03195Z","caller":"traceutil/trace.go:171","msg":"trace[1646919986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1375; }","duration":"274.708061ms","start":"2026-06-10T03:42:36.757221Z","end":"2026-06-10T03:42:37.031929Z","steps":["trace[1646919986] 'agreement among raft nodes before linearized reading' (duration: 274.446457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:37.031904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:36.391884Z","time spent":"640.013901ms","remote":"127.0.0.1:57084","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-06-10T03:42:37.031975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.310344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-10T03:42:37.031909Z","caller":"traceutil/trace.go:171","msg":"trace[1175660922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1375; }","duration":"275.333722ms","start":"2026-06-10T03:42:36.756571Z","end":"2026-06-10T03:42:37.031905Z","steps":["trace[1175660922] 'agreement among raft nodes before linearized reading' (duration: 275.272031ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T03:42:37.032061Z","caller":"traceutil/trace.go:171","msg":"trace[1387830765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1375; }","duration":"274.466237ms","start":"2026-06-10T03:42:36.757574Z","end":"2026-06-10T03:42:37.03204Z","steps":["trace[1387830765] 'agreement among raft nodes before linearized reading' (duration: 274.140081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T03:42:37.031969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T03:42:36.372536Z","time spent":"659.424011ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}