{"level":"warn","ts":"2026-07-16T00:18:55.830665Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T00:18:55.830782Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-07-16T00:18:55.830851Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T00:18:55.830861Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-16T00:18:55.83088Z","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-07-16T00:18:55.831564Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-07-16T00:18:55.831633Z","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.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-07-16T00:18:55.838736Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.901716ms"} {"level":"info","ts":"2026-07-16T00:18:55.853046Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-07-16T00:18:55.853141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T00:18:55.853163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-07-16T00:18:55.853182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T00:18:55.853195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-07-16T00:18:55.853237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-07-16T00:18:55.861341Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T00:18:55.862712Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T00:18:55.864098Z","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-07-16T00:18:55.866257Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T00:18:55.866935Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T00:18:55.867486Z","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-07-16T00:18:55.867687Z","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-07-16T00:18:55.867751Z","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-07-16T00:18:55.868737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-07-16T00:18:55.868863Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-16T00:18:55.871561Z","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-07-16T00:18:55.871813Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T00:18:55.871846Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T00:18:55.871903Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-16T00:18:55.871916Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-16T00:18:56.15448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T00:18:56.154589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T00:18:56.154629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-07-16T00:18:56.154676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-07-16T00:18:56.154694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-16T00:18:56.154713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-07-16T00:18:56.154734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-16T00:18:56.155731Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T00:18:56.155964Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T00:18:56.155975Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T00:18:56.158476Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T00:18:56.158592Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T00:18:56.156629Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:18:56.159773Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-07-16T00:18:56.161258Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:18:56.161351Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:18:56.161422Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:18:56.172089Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-16T00:19:08.729889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.326106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:19:08.729982Z","caller":"traceutil/trace.go:171","msg":"trace[1525116793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:362; }","duration":"125.481779ms","start":"2026-07-16T00:19:08.604483Z","end":"2026-07-16T00:19:08.729965Z","steps":["trace[1525116793] 'range keys from in-memory index tree' (duration: 125.213374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:12.173025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.485454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:19:12.17313Z","caller":"traceutil/trace.go:171","msg":"trace[371913232] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:389; }","duration":"125.258866ms","start":"2026-07-16T00:19:12.047853Z","end":"2026-07-16T00:19:12.173112Z","steps":["trace[371913232] 'read index received' (duration: 42.521µs)","trace[371913232] 'applied index is now lower than readState.Index' (duration: 125.214905ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:19:12.173176Z","caller":"traceutil/trace.go:171","msg":"trace[1615114533] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"417.79801ms","start":"2026-07-16T00:19:11.755338Z","end":"2026-07-16T00:19:12.173136Z","steps":["trace[1615114533] 'process raft request' (duration: 123.510811ms)","trace[1615114533] 'compare' (duration: 293.397962ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:19:12.173318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.82965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-07-16T00:19:12.173371Z","caller":"traceutil/trace.go:171","msg":"trace[1633372954] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:374; }","duration":"115.991413ms","start":"2026-07-16T00:19:12.057368Z","end":"2026-07-16T00:19:12.173359Z","steps":["trace[1633372954] 'agreement among raft nodes before linearized reading' (duration: 115.83176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:12.173339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:11.755321Z","time spent":"417.934362ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:19:12.173266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.387688ms","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-07-16T00:19:12.173467Z","caller":"traceutil/trace.go:171","msg":"trace[804556725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:374; }","duration":"125.637143ms","start":"2026-07-16T00:19:12.047801Z","end":"2026-07-16T00:19:12.173438Z","steps":["trace[804556725] 'agreement among raft nodes before linearized reading' (duration: 125.360178ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:12.284095Z","caller":"traceutil/trace.go:171","msg":"trace[914173830] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"102.369026ms","start":"2026-07-16T00:19:12.181693Z","end":"2026-07-16T00:19:12.284062Z","steps":["trace[914173830] 'process raft request' (duration: 101.600291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:12.638734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.251193ms","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-07-16T00:19:12.638814Z","caller":"traceutil/trace.go:171","msg":"trace[576655141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:376; }","duration":"108.377835ms","start":"2026-07-16T00:19:12.530417Z","end":"2026-07-16T00:19:12.638795Z","steps":["trace[576655141] 'range keys from in-memory index tree' (duration: 108.14069ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:28.983707Z","caller":"traceutil/trace.go:171","msg":"trace[692391692] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"215.137997ms","start":"2026-07-16T00:19:28.768542Z","end":"2026-07-16T00:19:28.98368Z","steps":["trace[692391692] 'process raft request' (duration: 214.962523ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:33.39574Z","caller":"traceutil/trace.go:171","msg":"trace[443076881] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"159.006838ms","start":"2026-07-16T00:19:33.236706Z","end":"2026-07-16T00:19:33.395713Z","steps":["trace[443076881] 'process raft request' (duration: 158.250713ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:33.790367Z","caller":"traceutil/trace.go:171","msg":"trace[80241497] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"196.976431ms","start":"2026-07-16T00:19:33.593355Z","end":"2026-07-16T00:19:33.790332Z","steps":["trace[80241497] 'process raft request' (duration: 164.660948ms)","trace[80241497] 'compare' (duration: 32.14917ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:19:33.793078Z","caller":"traceutil/trace.go:171","msg":"trace[1937913272] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"196.652545ms","start":"2026-07-16T00:19:33.596357Z","end":"2026-07-16T00:19:33.79301Z","steps":["trace[1937913272] 'process raft request' (duration: 196.44175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:36.095775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870774183883104,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T00:19:36.428766Z","caller":"traceutil/trace.go:171","msg":"trace[613023585] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"852.104109ms","start":"2026-07-16T00:19:35.576611Z","end":"2026-07-16T00:19:36.428715Z","steps":["trace[613023585] 'process raft request' (duration: 849.329785ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:36.428964Z","caller":"traceutil/trace.go:171","msg":"trace[928763048] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"844.802576ms","start":"2026-07-16T00:19:35.584145Z","end":"2026-07-16T00:19:36.428948Z","steps":["trace[928763048] 'process raft request' (duration: 844.48724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:36.429027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:35.576597Z","time spent":"852.277532ms","remote":"127.0.0.1:49890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:19:36.429145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:35.584134Z","time spent":"844.898367ms","remote":"127.0.0.1:50412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:19:36.429289Z","caller":"traceutil/trace.go:171","msg":"trace[1730522671] transaction","detail":"{read_only:false; number_of_response:1; response_revision:513; }","duration":"839.934389ms","start":"2026-07-16T00:19:35.589322Z","end":"2026-07-16T00:19:36.429256Z","steps":["trace[1730522671] 'process raft request' (duration: 839.813217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:36.429469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:35.589313Z","time spent":"840.061053ms","remote":"127.0.0.1:49890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7374,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:19:36.429477Z","caller":"traceutil/trace.go:171","msg":"trace[982658253] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"839.328519ms","start":"2026-07-16T00:19:35.590125Z","end":"2026-07-16T00:19:36.429453Z","steps":["trace[982658253] 'process raft request' (duration: 839.217736ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:36.429527Z","caller":"traceutil/trace.go:171","msg":"trace[1085096329] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"845.317646ms","start":"2026-07-16T00:19:35.584197Z","end":"2026-07-16T00:19:36.429515Z","steps":["trace[1085096329] 'process raft request' (duration: 844.680363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:36.429575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:35.584192Z","time spent":"845.361087ms","remote":"127.0.0.1:50412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:19:36.429631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:35.590094Z","time spent":"839.450741ms","remote":"127.0.0.1:49890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:19:36.429754Z","caller":"traceutil/trace.go:171","msg":"trace[818326894] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"842.305117ms","start":"2026-07-16T00:19:35.58744Z","end":"2026-07-16T00:19:36.429745Z","steps":["trace[818326894] 'process raft request' (duration: 841.564612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:36.429824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:35.587431Z","time spent":"842.344378ms","remote":"127.0.0.1:49890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:19:36.429853Z","caller":"traceutil/trace.go:171","msg":"trace[231793011] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"840.415579ms","start":"2026-07-16T00:19:35.589419Z","end":"2026-07-16T00:19:36.429834Z","steps":["trace[231793011] 'process raft request' (duration: 839.780376ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:36.428861Z","caller":"traceutil/trace.go:171","msg":"trace[1458416132] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"849.789654ms","start":"2026-07-16T00:19:35.578966Z","end":"2026-07-16T00:19:36.428755Z","steps":["trace[1458416132] 'process raft request' (duration: 849.620841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:36.429933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:35.589413Z","time spent":"840.47997ms","remote":"127.0.0.1:49890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:19:36.430037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:35.578938Z","time spent":"850.978847ms","remote":"127.0.0.1:49890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:19:36.434458Z","caller":"traceutil/trace.go:171","msg":"trace[404043734] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:531; }","duration":"839.301227ms","start":"2026-07-16T00:19:35.595138Z","end":"2026-07-16T00:19:36.434439Z","steps":["trace[404043734] 'read index received' (duration: 830.826591ms)","trace[404043734] 'applied index is now lower than readState.Index' (duration: 8.473696ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:19:36.434544Z","caller":"traceutil/trace.go:171","msg":"trace[1390502096] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"749.140851ms","start":"2026-07-16T00:19:35.685396Z","end":"2026-07-16T00:19:36.434536Z","steps":["trace[1390502096] 'process raft request' (duration: 748.947538ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:36.43563Z","caller":"traceutil/trace.go:171","msg":"trace[949007650] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"836.730687ms","start":"2026-07-16T00:19:35.597798Z","end":"2026-07-16T00:19:36.434528Z","steps":["trace[949007650] 'process raft request' (duration: 836.273358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:36.435775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:35.597767Z","time spent":"837.93005ms","remote":"127.0.0.1:49890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:19:36.436912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:35.685355Z","time spent":"751.515897ms","remote":"127.0.0.1:49890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:19:36.437325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.878031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:19:36.437375Z","caller":"traceutil/trace.go:171","msg":"trace[410767795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"835.957703ms","start":"2026-07-16T00:19:35.601404Z","end":"2026-07-16T00:19:36.437362Z","steps":["trace[410767795] 'agreement among raft nodes before linearized reading' (duration: 835.619686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:36.437414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:35.601396Z","time spent":"836.011483ms","remote":"127.0.0.1:49876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T00:19:36.437614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.37211ms","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-07-16T00:19:36.437684Z","caller":"traceutil/trace.go:171","msg":"trace[1261437035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"488.420451ms","start":"2026-07-16T00:19:35.949228Z","end":"2026-07-16T00:19:36.437648Z","steps":["trace[1261437035] 'agreement among raft nodes before linearized reading' (duration: 488.294049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:36.437714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:35.949145Z","time spent":"488.562334ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T00:19:36.442316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.131151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-07-16T00:19:36.44239Z","caller":"traceutil/trace.go:171","msg":"trace[1580188621] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:517; }","duration":"847.254744ms","start":"2026-07-16T00:19:35.595099Z","end":"2026-07-16T00:19:36.442353Z","steps":["trace[1580188621] 'agreement among raft nodes before linearized reading' (duration: 842.250525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:36.44245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:35.595082Z","time spent":"847.355175ms","remote":"127.0.0.1:50412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":1032,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-07-16T00:19:37.708255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.469608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:59631"} {"level":"info","ts":"2026-07-16T00:19:37.708467Z","caller":"traceutil/trace.go:171","msg":"trace[1296664407] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"254.388946ms","start":"2026-07-16T00:19:37.454014Z","end":"2026-07-16T00:19:37.708403Z","steps":["trace[1296664407] 'process raft request' (duration: 253.250034ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:37.708558Z","caller":"traceutil/trace.go:171","msg":"trace[781150672] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:567; }","duration":"302.743733ms","start":"2026-07-16T00:19:37.405733Z","end":"2026-07-16T00:19:37.708476Z","steps":["trace[781150672] 'range keys from in-memory index tree' (duration: 302.04741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:37.708692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:37.405727Z","time spent":"302.949236ms","remote":"127.0.0.1:49890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":59653,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-07-16T00:19:37.720331Z","caller":"traceutil/trace.go:171","msg":"trace[1645305998] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:591; }","duration":"117.284899ms","start":"2026-07-16T00:19:37.60303Z","end":"2026-07-16T00:19:37.720315Z","steps":["trace[1645305998] 'read index received' (duration: 104.170142ms)","trace[1645305998] 'applied index is now lower than readState.Index' (duration: 13.113847ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:19:37.720443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.409181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:19:37.720491Z","caller":"traceutil/trace.go:171","msg":"trace[799969498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"117.500083ms","start":"2026-07-16T00:19:37.602983Z","end":"2026-07-16T00:19:37.720483Z","steps":["trace[799969498] 'agreement among raft nodes before linearized reading' (duration: 117.419622ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:37.731122Z","caller":"traceutil/trace.go:171","msg":"trace[41783647] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"163.120757ms","start":"2026-07-16T00:19:37.567977Z","end":"2026-07-16T00:19:37.731098Z","steps":["trace[41783647] 'process raft request' (duration: 151.741354ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:42.654403Z","caller":"traceutil/trace.go:171","msg":"trace[1841534914] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"102.967468ms","start":"2026-07-16T00:19:42.551398Z","end":"2026-07-16T00:19:42.654365Z","steps":["trace[1841534914] 'process raft request' (duration: 55.584399ms)","trace[1841534914] 'compare' (duration: 47.253186ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:19:42.906639Z","caller":"traceutil/trace.go:171","msg":"trace[1510771082] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"265.162018ms","start":"2026-07-16T00:19:42.641391Z","end":"2026-07-16T00:19:42.906553Z","steps":["trace[1510771082] 'process raft request' (duration: 264.301041ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:42.907042Z","caller":"traceutil/trace.go:171","msg":"trace[353872275] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"252.669703ms","start":"2026-07-16T00:19:42.654351Z","end":"2026-07-16T00:19:42.907021Z","steps":["trace[353872275] 'read index received' (duration: 250.925249ms)","trace[353872275] 'applied index is now lower than readState.Index' (duration: 1.743304ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:19:42.907226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.72829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:19:42.907333Z","caller":"traceutil/trace.go:171","msg":"trace[2042835939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"261.899003ms","start":"2026-07-16T00:19:42.645419Z","end":"2026-07-16T00:19:42.907318Z","steps":["trace[2042835939] 'agreement among raft nodes before linearized reading' (duration: 261.669139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:43.475863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.025007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:19:43.476076Z","caller":"traceutil/trace.go:171","msg":"trace[2102933236] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"817.404489ms","start":"2026-07-16T00:19:42.658577Z","end":"2026-07-16T00:19:43.475981Z","steps":["trace[2102933236] 'process raft request' (duration: 558.122657ms)","trace[2102933236] 'compare' (duration: 258.825983ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:19:43.476188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:42.658553Z","time spent":"817.583504ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:19:43.539304Z","caller":"traceutil/trace.go:171","msg":"trace[689518267] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"396.93177ms","start":"2026-07-16T00:19:43.142323Z","end":"2026-07-16T00:19:43.539255Z","steps":["trace[689518267] 'read index received' (duration: 74.295566ms)","trace[689518267] 'applied index is now lower than readState.Index' (duration: 322.635034ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:19:43.539313Z","caller":"traceutil/trace.go:171","msg":"trace[1680615764] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"574.954268ms","start":"2026-07-16T00:19:42.964327Z","end":"2026-07-16T00:19:43.539281Z","steps":["trace[1680615764] 'process raft request' (duration: 574.824595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:43.539453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:42.964285Z","time spent":"575.099631ms","remote":"127.0.0.1:50400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:19:43.539755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.43299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k92dt\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-16T00:19:43.539828Z","caller":"traceutil/trace.go:171","msg":"trace[1594518003] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k92dt; range_end:; response_count:1; response_revision:604; }","duration":"397.555661ms","start":"2026-07-16T00:19:43.142257Z","end":"2026-07-16T00:19:43.539812Z","steps":["trace[1594518003] 'agreement among raft nodes before linearized reading' (duration: 397.145694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:43.539747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.077822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-16T00:19:43.539896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:43.142199Z","time spent":"397.688474ms","remote":"127.0.0.1:50022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-k92dt\" "} {"level":"info","ts":"2026-07-16T00:19:43.539961Z","caller":"traceutil/trace.go:171","msg":"trace[363081289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"346.361738ms","start":"2026-07-16T00:19:43.193546Z","end":"2026-07-16T00:19:43.539908Z","steps":["trace[363081289] 'agreement among raft nodes before linearized reading' (duration: 345.92853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:43.54003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:43.193522Z","time spent":"346.49707ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T00:19:44.727321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112599826s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:19:44.727465Z","caller":"traceutil/trace.go:171","msg":"trace[668073109] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"1.123421197s","start":"2026-07-16T00:19:43.604024Z","end":"2026-07-16T00:19:44.727445Z","steps":["trace[668073109] 'read index received' (duration: 10.450354ms)","trace[668073109] 'applied index is now lower than readState.Index' (duration: 1.112968763s)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:19:44.727577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123563221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:19:44.727553Z","caller":"traceutil/trace.go:171","msg":"trace[1537359679] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.173608741s","start":"2026-07-16T00:19:43.553901Z","end":"2026-07-16T00:19:44.727509Z","steps":["trace[1537359679] 'process raft request' (duration: 60.674499ms)","trace[1537359679] 'compare' (duration: 1.11230575s)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:19:44.727609Z","caller":"traceutil/trace.go:171","msg":"trace[1634447737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"1.123623741s","start":"2026-07-16T00:19:43.603972Z","end":"2026-07-16T00:19:44.727596Z","steps":["trace[1634447737] 'agreement among raft nodes before linearized reading' (duration: 1.12355735s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:44.727648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:43.603957Z","time spent":"1.123683392s","remote":"127.0.0.1:49872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T00:19:44.727746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:43.55389Z","time spent":"1.173748764s","remote":"127.0.0.1:50022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:19:45.146763Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.205360554s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T00:19:45.147934Z","caller":"traceutil/trace.go:171","msg":"trace[1157260336] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"1.208598187s","start":"2026-07-16T00:19:43.939304Z","end":"2026-07-16T00:19:45.147902Z","steps":["trace[1157260336] 'process raft request' (duration: 1.207908714s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:45.14811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:43.939251Z","time spent":"1.208766622s","remote":"127.0.0.1:49932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:19:45.165977Z","caller":"traceutil/trace.go:171","msg":"trace[1234001250] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:631; }","duration":"438.452053ms","start":"2026-07-16T00:19:44.727495Z","end":"2026-07-16T00:19:45.165947Z","steps":["trace[1234001250] 'read index received' (duration: 419.503762ms)","trace[1234001250] 'applied index is now lower than readState.Index' (duration: 18.946761ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:19:45.16628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.983586ms","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-07-16T00:19:45.166302Z","caller":"traceutil/trace.go:171","msg":"trace[494674599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"508.057767ms","start":"2026-07-16T00:19:44.658237Z","end":"2026-07-16T00:19:45.166295Z","steps":["trace[494674599] 'agreement among raft nodes before linearized reading' (duration: 507.879603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:45.166329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:44.658226Z","time spent":"508.096628ms","remote":"127.0.0.1:50122","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":"warn","ts":"2026-07-16T00:19:45.166376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.354695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:19:45.16646Z","caller":"traceutil/trace.go:171","msg":"trace[1133096489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"428.476347ms","start":"2026-07-16T00:19:44.737958Z","end":"2026-07-16T00:19:45.166435Z","steps":["trace[1133096489] 'agreement among raft nodes before linearized reading' (duration: 428.312764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:45.166496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:44.737938Z","time spent":"428.552928ms","remote":"127.0.0.1:49876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T00:19:45.166606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.470803ms","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-07-16T00:19:45.167258Z","caller":"traceutil/trace.go:171","msg":"trace[1328955283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"615.171936ms","start":"2026-07-16T00:19:44.552073Z","end":"2026-07-16T00:19:45.167245Z","steps":["trace[1328955283] 'agreement among raft nodes before linearized reading' (duration: 614.279659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:45.167288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:44.552044Z","time spent":"615.236317ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T00:20:02.440392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.158087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:20:02.440497Z","caller":"traceutil/trace.go:171","msg":"trace[294881071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:773; }","duration":"836.285359ms","start":"2026-07-16T00:20:01.60419Z","end":"2026-07-16T00:20:02.440475Z","steps":["trace[294881071] 'range keys from in-memory index tree' (duration: 836.050565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:02.440521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.675771ms","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-07-16T00:20:02.440606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:01.604121Z","time spent":"836.470512ms","remote":"127.0.0.1:49876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T00:20:02.440597Z","caller":"traceutil/trace.go:171","msg":"trace[936949486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:773; }","duration":"801.752952ms","start":"2026-07-16T00:20:01.638803Z","end":"2026-07-16T00:20:02.440556Z","steps":["trace[936949486] 'range keys from in-memory index tree' (duration: 801.521268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:02.440414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.511848ms","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-07-16T00:20:02.440767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:01.638786Z","time spent":"801.965096ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-16T00:20:02.44086Z","caller":"traceutil/trace.go:171","msg":"trace[813946615] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:773; }","duration":"800.926626ms","start":"2026-07-16T00:20:01.639855Z","end":"2026-07-16T00:20:02.440781Z","steps":["trace[813946615] 'range keys from in-memory index tree' (duration: 800.303585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:02.441077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:01.639849Z","time spent":"801.147611ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-16T00:20:02.696293Z","caller":"traceutil/trace.go:171","msg":"trace[779786494] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"884.827811ms","start":"2026-07-16T00:20:01.810026Z","end":"2026-07-16T00:20:02.694854Z","steps":["trace[779786494] 'process raft request' (duration: 884.672928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:02.696665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:01.809987Z","time spent":"886.395891ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:20:02.70297Z","caller":"traceutil/trace.go:171","msg":"trace[1873586906] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"419.037503ms","start":"2026-07-16T00:20:02.283907Z","end":"2026-07-16T00:20:02.702945Z","steps":["trace[1873586906] 'process raft request' (duration: 418.707485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:02.703148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:02.283873Z","time spent":"419.182706ms","remote":"127.0.0.1:50092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:20:02.703489Z","caller":"traceutil/trace.go:171","msg":"trace[1681935581] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"257.527317ms","start":"2026-07-16T00:20:02.445944Z","end":"2026-07-16T00:20:02.703471Z","steps":["trace[1681935581] 'process raft request' (duration: 256.948117ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:02.703576Z","caller":"traceutil/trace.go:171","msg":"trace[2085290932] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"303.894486ms","start":"2026-07-16T00:20:02.399668Z","end":"2026-07-16T00:20:02.703563Z","steps":["trace[2085290932] 'process raft request' (duration: 303.166461ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:02.703636Z","caller":"traceutil/trace.go:171","msg":"trace[735636651] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:805; }","duration":"266.472672ms","start":"2026-07-16T00:20:02.437151Z","end":"2026-07-16T00:20:02.703624Z","steps":["trace[735636651] 'read index received' (duration: 257.746441ms)","trace[735636651] 'applied index is now lower than readState.Index' (duration: 8.725181ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:20:02.703676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:02.399629Z","time spent":"303.991807ms","remote":"127.0.0.1:50020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5052,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:20:02.703738Z","caller":"traceutil/trace.go:171","msg":"trace[1983125398] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"257.771202ms","start":"2026-07-16T00:20:02.44596Z","end":"2026-07-16T00:20:02.703732Z","steps":["trace[1983125398] 'process raft request' (duration: 257.435046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:02.703864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.718277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T00:20:02.703895Z","caller":"traceutil/trace.go:171","msg":"trace[1905458299] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:778; }","duration":"266.792768ms","start":"2026-07-16T00:20:02.437095Z","end":"2026-07-16T00:20:02.703888Z","steps":["trace[1905458299] 'agreement among raft nodes before linearized reading' (duration: 266.697556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:02.704056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.584155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:20:02.704102Z","caller":"traceutil/trace.go:171","msg":"trace[882029493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"261.74502ms","start":"2026-07-16T00:20:02.442347Z","end":"2026-07-16T00:20:02.704092Z","steps":["trace[882029493] 'agreement among raft nodes before linearized reading' (duration: 261.669479ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:37.43793Z","caller":"traceutil/trace.go:171","msg":"trace[132125781] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"548.861074ms","start":"2026-07-16T00:20:36.889043Z","end":"2026-07-16T00:20:37.437904Z","steps":["trace[132125781] 'process raft request' (duration: 548.707872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:37.438095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:36.889015Z","time spent":"549.000758ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:20:38.006885Z","caller":"traceutil/trace.go:171","msg":"trace[1449289933] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"1.116737363s","start":"2026-07-16T00:20:36.890113Z","end":"2026-07-16T00:20:38.006851Z","steps":["trace[1449289933] 'process raft request' (duration: 1.114855846s)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:38.007007Z","caller":"traceutil/trace.go:171","msg":"trace[1452058250] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1022; }","duration":"977.93442ms","start":"2026-07-16T00:20:37.029051Z","end":"2026-07-16T00:20:38.006985Z","steps":["trace[1452058250] 'read index received' (duration: 408.782227ms)","trace[1452058250] 'applied index is now lower than readState.Index' (duration: 569.150683ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:20:38.007041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:36.890092Z","time spent":"1.116868595s","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:20:38.007044Z","caller":"traceutil/trace.go:171","msg":"trace[931681977] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"512.758314ms","start":"2026-07-16T00:20:37.494246Z","end":"2026-07-16T00:20:38.007004Z","steps":["trace[931681977] 'process raft request' (duration: 512.598021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:38.007239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:37.494227Z","time spent":"512.909776ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:20:38.007272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.214536ms","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-07-16T00:20:38.007321Z","caller":"traceutil/trace.go:171","msg":"trace[131217813] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:987; }","duration":"978.286689ms","start":"2026-07-16T00:20:37.02902Z","end":"2026-07-16T00:20:38.007307Z","steps":["trace[131217813] 'agreement among raft nodes before linearized reading' (duration: 978.102394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:38.007368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:37.029011Z","time spent":"978.346329ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-16T00:20:38.00743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.796609ms","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-07-16T00:20:38.007468Z","caller":"traceutil/trace.go:171","msg":"trace[852252519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"563.86237ms","start":"2026-07-16T00:20:37.443595Z","end":"2026-07-16T00:20:38.007457Z","steps":["trace[852252519] 'agreement among raft nodes before linearized reading' (duration: 563.742748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:38.007497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:37.443579Z","time spent":"563.911741ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T00:20:38.007529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.526491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T00:20:38.007566Z","caller":"traceutil/trace.go:171","msg":"trace[2117639734] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:987; }","duration":"542.588311ms","start":"2026-07-16T00:20:37.464966Z","end":"2026-07-16T00:20:38.007554Z","steps":["trace[2117639734] 'agreement among raft nodes before linearized reading' (duration: 542.51981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:38.007601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:37.464953Z","time spent":"542.638322ms","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-07-16T00:20:38.007674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.225349ms","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-07-16T00:20:38.00775Z","caller":"traceutil/trace.go:171","msg":"trace[1084987741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:987; }","duration":"559.351352ms","start":"2026-07-16T00:20:37.448377Z","end":"2026-07-16T00:20:38.007729Z","steps":["trace[1084987741] 'agreement among raft nodes before linearized reading' (duration: 559.130017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:38.007789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:37.448353Z","time spent":"559.427832ms","remote":"127.0.0.1:50122","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":"warn","ts":"2026-07-16T00:20:39.139733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.805606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:20:39.139937Z","caller":"traceutil/trace.go:171","msg":"trace[1312208257] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1025; }","duration":"535.540102ms","start":"2026-07-16T00:20:38.604383Z","end":"2026-07-16T00:20:39.139923Z","steps":["trace[1312208257] 'read index received' (duration: 25.571903ms)","trace[1312208257] 'applied index is now lower than readState.Index' (duration: 509.967509ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:20:39.139956Z","caller":"traceutil/trace.go:171","msg":"trace[523154809] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"1.126956114s","start":"2026-07-16T00:20:38.012969Z","end":"2026-07-16T00:20:39.139925Z","steps":["trace[523154809] 'process raft request' (duration: 616.889733ms)","trace[523154809] 'compare' (duration: 509.48577ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:20:39.140123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:38.012949Z","time spent":"1.127092228s","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:20:39.140157Z","caller":"traceutil/trace.go:171","msg":"trace[1638503339] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"1.126919764s","start":"2026-07-16T00:20:38.013228Z","end":"2026-07-16T00:20:39.140148Z","steps":["trace[1638503339] 'process raft request' (duration: 1.126615327s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:39.140205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:38.013219Z","time spent":"1.126961715s","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:20:39.140293Z","caller":"traceutil/trace.go:171","msg":"trace[77119102] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"1.126921685s","start":"2026-07-16T00:20:38.013366Z","end":"2026-07-16T00:20:39.140288Z","steps":["trace[77119102] 'process raft request' (duration: 1.126518206s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:39.140324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:38.013343Z","time spent":"1.126963375s","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:20:39.140366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.78275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T00:20:39.140438Z","caller":"traceutil/trace.go:171","msg":"trace[957684652] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:990; }","duration":"370.864111ms","start":"2026-07-16T00:20:38.769558Z","end":"2026-07-16T00:20:39.140423Z","steps":["trace[957684652] 'agreement among raft nodes before linearized reading' (duration: 370.746649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:39.140474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:38.769481Z","time spent":"370.984213ms","remote":"127.0.0.1:50114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-07-16T00:20:39.140486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.114074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:20:39.140509Z","caller":"traceutil/trace.go:171","msg":"trace[1661915603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:990; }","duration":"536.179085ms","start":"2026-07-16T00:20:38.604324Z","end":"2026-07-16T00:20:39.140503Z","steps":["trace[1661915603] 'agreement among raft nodes before linearized reading' (duration: 536.140174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:39.140528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:38.604303Z","time spent":"536.221186ms","remote":"127.0.0.1:49876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T00:21:05.521725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234211026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:21:05.521843Z","caller":"traceutil/trace.go:171","msg":"trace[2018509624] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1074; }","duration":"1.23439826s","start":"2026-07-16T00:21:04.287419Z","end":"2026-07-16T00:21:05.521817Z","steps":["trace[2018509624] 'count revisions from in-memory index tree' (duration: 1.234115005s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:05.521899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:04.287394Z","time spent":"1.234488482s","remote":"127.0.0.1:50092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-07-16T00:21:05.53192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.637998ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-16T00:21:05.532037Z","caller":"traceutil/trace.go:171","msg":"trace[1787622730] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"927.684311ms","start":"2026-07-16T00:21:04.604333Z","end":"2026-07-16T00:21:05.532017Z","steps":["trace[1787622730] 'read index received' (duration: 85.963152ms)","trace[1787622730] 'applied index is now lower than readState.Index' (duration: 841.719549ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:21:05.532197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.869584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T00:21:05.532369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.639692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-16T00:21:05.532374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.503716ms","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-07-16T00:21:05.532414Z","caller":"traceutil/trace.go:171","msg":"trace[2119382212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1074; }","duration":"254.688753ms","start":"2026-07-16T00:21:05.277715Z","end":"2026-07-16T00:21:05.532404Z","steps":["trace[2119382212] 'agreement among raft nodes before linearized reading' (duration: 254.52653ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:21:05.53245Z","caller":"traceutil/trace.go:171","msg":"trace[215535414] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1074; }","duration":"235.634408ms","start":"2026-07-16T00:21:05.296802Z","end":"2026-07-16T00:21:05.532437Z","steps":["trace[215535414] 'agreement among raft nodes before linearized reading' (duration: 235.390794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:05.532293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.497479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-16T00:21:05.532396Z","caller":"traceutil/trace.go:171","msg":"trace[503175601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1074; }","duration":"928.100829ms","start":"2026-07-16T00:21:04.604277Z","end":"2026-07-16T00:21:05.532378Z","steps":["trace[503175601] 'agreement among raft nodes before linearized reading' (duration: 927.842724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:05.532697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:04.604248Z","time spent":"928.437896ms","remote":"127.0.0.1:49876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T00:21:05.532531Z","caller":"traceutil/trace.go:171","msg":"trace[1900963207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1074; }","duration":"255.770154ms","start":"2026-07-16T00:21:05.276731Z","end":"2026-07-16T00:21:05.532501Z","steps":["trace[1900963207] 'agreement among raft nodes before linearized reading' (duration: 255.414778ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:21:06.637349Z","caller":"traceutil/trace.go:171","msg":"trace[129369916] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"490.456235ms","start":"2026-07-16T00:21:06.146872Z","end":"2026-07-16T00:21:06.637328Z","steps":["trace[129369916] 'process raft request' (duration: 490.311392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:06.6375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:06.146843Z","time spent":"490.574708ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:21:06.637709Z","caller":"traceutil/trace.go:171","msg":"trace[1842843168] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1123; }","duration":"478.46555ms","start":"2026-07-16T00:21:06.159204Z","end":"2026-07-16T00:21:06.63767Z","steps":["trace[1842843168] 'read index received' (duration: 478.45454ms)","trace[1842843168] 'applied index is now lower than readState.Index' (duration: 9.34µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:21:06.637952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.738065ms","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-07-16T00:21:06.637988Z","caller":"traceutil/trace.go:171","msg":"trace[276525809] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1079; }","duration":"478.828497ms","start":"2026-07-16T00:21:06.159148Z","end":"2026-07-16T00:21:06.637976Z","steps":["trace[276525809] 'agreement among raft nodes before linearized reading' (duration: 478.678984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:06.638018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:06.159125Z","time spent":"478.885757ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T00:21:06.638423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.143335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T00:21:06.638542Z","caller":"traceutil/trace.go:171","msg":"trace[917071969] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1079; }","duration":"280.268547ms","start":"2026-07-16T00:21:06.358216Z","end":"2026-07-16T00:21:06.638484Z","steps":["trace[917071969] 'agreement among raft nodes before linearized reading' (duration: 279.728607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:07.060759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.016508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:21:07.060884Z","caller":"traceutil/trace.go:171","msg":"trace[177154869] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"418.894706ms","start":"2026-07-16T00:21:06.641969Z","end":"2026-07-16T00:21:07.060864Z","steps":["trace[177154869] 'process raft request' (duration: 285.682694ms)","trace[177154869] 'compare' (duration: 132.875496ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:21:07.060993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:06.64195Z","time spent":"419.008408ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:21:07.842023Z","caller":"traceutil/trace.go:171","msg":"trace[1558932842] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1125; }","duration":"171.294833ms","start":"2026-07-16T00:21:07.670707Z","end":"2026-07-16T00:21:07.842002Z","steps":["trace[1558932842] 'read index received' (duration: 155.365953ms)","trace[1558932842] 'applied index is now lower than readState.Index' (duration: 15.92764ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:21:07.84216Z","caller":"traceutil/trace.go:171","msg":"trace[1846751182] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"174.788642ms","start":"2026-07-16T00:21:07.667345Z","end":"2026-07-16T00:21:07.842134Z","steps":["trace[1846751182] 'process raft request' (duration: 158.751061ms)","trace[1846751182] 'compare' (duration: 15.579933ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:21:07.842188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.473177ms","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-07-16T00:21:07.842236Z","caller":"traceutil/trace.go:171","msg":"trace[2118753922] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"174.925886ms","start":"2026-07-16T00:21:07.667277Z","end":"2026-07-16T00:21:07.842203Z","steps":["trace[2118753922] 'process raft request' (duration: 174.62863ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:21:07.842365Z","caller":"traceutil/trace.go:171","msg":"trace[77958619] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1083; }","duration":"171.584099ms","start":"2026-07-16T00:21:07.670635Z","end":"2026-07-16T00:21:07.842219Z","steps":["trace[77958619] 'agreement among raft nodes before linearized reading' (duration: 171.452896ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:21:22.801726Z","caller":"traceutil/trace.go:171","msg":"trace[434773637] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"100.148729ms","start":"2026-07-16T00:21:22.701559Z","end":"2026-07-16T00:21:22.801707Z","steps":["trace[434773637] 'process raft request' (duration: 100.021167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:27.449599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.527847932s","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-07-16T00:21:27.449657Z","caller":"traceutil/trace.go:171","msg":"trace[1691175781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1193; }","duration":"1.527948593s","start":"2026-07-16T00:21:25.921697Z","end":"2026-07-16T00:21:27.449645Z","steps":["trace[1691175781] 'range keys from in-memory index tree' (duration: 1.527716549s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:27.449681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:25.92168Z","time spent":"1.527996904s","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T00:21:27.44983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.52627411s","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-07-16T00:21:27.449847Z","caller":"traceutil/trace.go:171","msg":"trace[948413517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1193; }","duration":"1.526310332s","start":"2026-07-16T00:21:25.923532Z","end":"2026-07-16T00:21:27.449843Z","steps":["trace[948413517] 'range keys from in-memory index tree' (duration: 1.52620646s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:27.449859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:25.923524Z","time spent":"1.526333062s","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T00:21:27.449593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.011732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:21:27.449965Z","caller":"traceutil/trace.go:171","msg":"trace[1493866429] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1193; }","duration":"902.459051ms","start":"2026-07-16T00:21:26.547499Z","end":"2026-07-16T00:21:27.449958Z","steps":["trace[1493866429] 'count revisions from in-memory index tree' (duration: 901.87871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:27.449972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.190536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:21:27.450028Z","caller":"traceutil/trace.go:171","msg":"trace[1633340928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1193; }","duration":"847.273668ms","start":"2026-07-16T00:21:26.602733Z","end":"2026-07-16T00:21:27.450006Z","steps":["trace[1633340928] 'range keys from in-memory index tree' (duration: 847.065963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:27.450055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.525889502s","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-07-16T00:21:27.450066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:26.602713Z","time spent":"847.344879ms","remote":"127.0.0.1:49876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T00:21:27.450104Z","caller":"traceutil/trace.go:171","msg":"trace[1596247988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1193; }","duration":"1.525972644s","start":"2026-07-16T00:21:25.924117Z","end":"2026-07-16T00:21:27.45009Z","steps":["trace[1596247988] 'range keys from in-memory index tree' (duration: 1.52574907s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:27.450143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:25.9241Z","time spent":"1.526036185s","remote":"127.0.0.1:50122","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":"warn","ts":"2026-07-16T00:21:27.449985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:26.547478Z","time spent":"902.500062ms","remote":"127.0.0.1:45940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-16T00:21:27.450349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.321129ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T00:21:27.450414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.836771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:21:27.450427Z","caller":"traceutil/trace.go:171","msg":"trace[1897893475] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1193; }","duration":"291.40053ms","start":"2026-07-16T00:21:27.159014Z","end":"2026-07-16T00:21:27.450414Z","steps":["trace[1897893475] 'range keys from in-memory index tree' (duration: 291.295188ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:21:27.450452Z","caller":"traceutil/trace.go:171","msg":"trace[128748573] linearizableReadLoop","detail":"{readStateIndex:1244; appliedIndex:1243; }","duration":"287.75412ms","start":"2026-07-16T00:21:27.162693Z","end":"2026-07-16T00:21:27.450447Z","steps":["trace[128748573] 'read index received' (duration: 28.54µs)","trace[128748573] 'applied index is now lower than readState.Index' (duration: 287.72503ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:21:27.450503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.810911ms","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-07-16T00:21:27.450516Z","caller":"traceutil/trace.go:171","msg":"trace[1924186783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1194; }","duration":"287.844082ms","start":"2026-07-16T00:21:27.162667Z","end":"2026-07-16T00:21:27.450511Z","steps":["trace[1924186783] 'agreement among raft nodes before linearized reading' (duration: 287.799971ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:21:27.450499Z","caller":"traceutil/trace.go:171","msg":"trace[1161469563] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"637.280001ms","start":"2026-07-16T00:21:26.813192Z","end":"2026-07-16T00:21:27.450472Z","steps":["trace[1161469563] 'process raft request' (duration: 238.349378ms)","trace[1161469563] 'compare' (duration: 398.77617ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:21:27.450598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:26.813168Z","time spent":"637.377723ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:21:27.924887Z","caller":"traceutil/trace.go:171","msg":"trace[13849997] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"470.301252ms","start":"2026-07-16T00:21:27.454561Z","end":"2026-07-16T00:21:27.924862Z","steps":["trace[13849997] 'process raft request' (duration: 470.128147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:27.925082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:27.454539Z","time spent":"470.456755ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:21:27.925391Z","caller":"traceutil/trace.go:171","msg":"trace[913315513] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"470.010066ms","start":"2026-07-16T00:21:27.455351Z","end":"2026-07-16T00:21:27.925361Z","steps":["trace[913315513] 'process raft request' (duration: 469.473495ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:21:27.925494Z","caller":"traceutil/trace.go:171","msg":"trace[840855215] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"469.947945ms","start":"2026-07-16T00:21:27.455527Z","end":"2026-07-16T00:21:27.925475Z","steps":["trace[840855215] 'process raft request' (duration: 469.744121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:27.925555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:27.455339Z","time spent":"470.133659ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:21:27.925602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:27.455519Z","time spent":"470.026806ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:21:27.925414Z","caller":"traceutil/trace.go:171","msg":"trace[517624263] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1245; }","duration":"321.138319ms","start":"2026-07-16T00:21:27.604255Z","end":"2026-07-16T00:21:27.925393Z","steps":["trace[517624263] 'read index received' (duration: 320.358333ms)","trace[517624263] 'applied index is now lower than readState.Index' (duration: 778.725µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:21:27.925503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.24299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T00:21:27.925822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.560693ms","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-07-16T00:21:27.925852Z","caller":"traceutil/trace.go:171","msg":"trace[938889717] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1198; }","duration":"161.630575ms","start":"2026-07-16T00:21:27.764212Z","end":"2026-07-16T00:21:27.925843Z","steps":["trace[938889717] 'agreement among raft nodes before linearized reading' (duration: 161.521173ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:21:27.925871Z","caller":"traceutil/trace.go:171","msg":"trace[171441722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1198; }","duration":"321.582868ms","start":"2026-07-16T00:21:27.604201Z","end":"2026-07-16T00:21:27.925783Z","steps":["trace[171441722] 'agreement among raft nodes before linearized reading' (duration: 321.253312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:27.926065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:27.604182Z","time spent":"321.809292ms","remote":"127.0.0.1:49876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T00:21:39.707856Z","caller":"traceutil/trace.go:171","msg":"trace[47961845] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"210.835092ms","start":"2026-07-16T00:21:39.496989Z","end":"2026-07-16T00:21:39.707824Z","steps":["trace[47961845] 'process raft request' (duration: 210.589707ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:21:39.707937Z","caller":"traceutil/trace.go:171","msg":"trace[1494504028] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1286; }","duration":"125.564304ms","start":"2026-07-16T00:21:39.58218Z","end":"2026-07-16T00:21:39.707744Z","steps":["trace[1494504028] 'read index received' (duration: 125.34502ms)","trace[1494504028] 'applied index is now lower than readState.Index' (duration: 218.004µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:21:39.708129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.929721ms","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-07-16T00:21:39.708216Z","caller":"traceutil/trace.go:171","msg":"trace[166297671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"126.070884ms","start":"2026-07-16T00:21:39.582125Z","end":"2026-07-16T00:21:39.708196Z","steps":["trace[166297671] 'agreement among raft nodes before linearized reading' (duration: 125.796968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:39.708316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.734699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:21:39.708436Z","caller":"traceutil/trace.go:171","msg":"trace[539841263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"103.934794ms","start":"2026-07-16T00:21:39.604492Z","end":"2026-07-16T00:21:39.708427Z","steps":["trace[539841263] 'agreement among raft nodes before linearized reading' (duration: 103.561316ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:21:41.134054Z","caller":"traceutil/trace.go:171","msg":"trace[596563831] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"154.113429ms","start":"2026-07-16T00:21:40.979913Z","end":"2026-07-16T00:21:41.134026Z","steps":["trace[596563831] 'process raft request' (duration: 153.952206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:42.564499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.671126ms","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-07-16T00:21:42.564567Z","caller":"traceutil/trace.go:171","msg":"trace[986527144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1243; }","duration":"401.799449ms","start":"2026-07-16T00:21:42.162754Z","end":"2026-07-16T00:21:42.564553Z","steps":["trace[986527144] 'range keys from in-memory index tree' (duration: 401.514212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:42.564574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.756904ms","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-07-16T00:21:42.564603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:42.162742Z","time spent":"401.852399ms","remote":"127.0.0.1:50122","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":"info","ts":"2026-07-16T00:21:42.564611Z","caller":"traceutil/trace.go:171","msg":"trace[218192989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1243; }","duration":"500.821575ms","start":"2026-07-16T00:21:42.063775Z","end":"2026-07-16T00:21:42.564596Z","steps":["trace[218192989] 'range keys from in-memory index tree' (duration: 500.611211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:42.564639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:42.063724Z","time spent":"500.909156ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T00:21:42.564507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.218489ms","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-07-16T00:21:42.564757Z","caller":"traceutil/trace.go:171","msg":"trace[546788120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1243; }","duration":"470.503075ms","start":"2026-07-16T00:21:42.094237Z","end":"2026-07-16T00:21:42.56474Z","steps":["trace[546788120] 'range keys from in-memory index tree' (duration: 470.109237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:42.564776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:42.09422Z","time spent":"470.550507ms","remote":"127.0.0.1:50122","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":"warn","ts":"2026-07-16T00:21:42.564808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.403743ms","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-07-16T00:21:42.564853Z","caller":"traceutil/trace.go:171","msg":"trace[464411828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1243; }","duration":"470.487515ms","start":"2026-07-16T00:21:42.094353Z","end":"2026-07-16T00:21:42.56484Z","steps":["trace[464411828] 'range keys from in-memory index tree' (duration: 470.266141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:42.564885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:42.09433Z","time spent":"470.547517ms","remote":"127.0.0.1:50122","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-07-16T00:21:43.107715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870774183884769,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T00:21:43.254753Z","caller":"traceutil/trace.go:171","msg":"trace[1940573029] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"686.424227ms","start":"2026-07-16T00:21:42.56831Z","end":"2026-07-16T00:21:43.254735Z","steps":["trace[1940573029] 'process raft request' (duration: 686.297825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:43.254866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:42.568289Z","time spent":"686.518599ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:21:43.49958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.334884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:21:43.499978Z","caller":"traceutil/trace.go:171","msg":"trace[1332111711] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"930.240222ms","start":"2026-07-16T00:21:42.569697Z","end":"2026-07-16T00:21:43.499937Z","steps":["trace[1332111711] 'process raft request' (duration: 796.406288ms)","trace[1332111711] 'compare' (duration: 133.10443ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:21:43.50012Z","caller":"traceutil/trace.go:171","msg":"trace[116867425] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1297; }","duration":"892.60547ms","start":"2026-07-16T00:21:42.607408Z","end":"2026-07-16T00:21:43.500014Z","steps":["trace[116867425] 'read index received' (duration: 647.315636ms)","trace[116867425] 'applied index is now lower than readState.Index' (duration: 245.287324ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:21:43.500174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:42.569672Z","time spent":"930.389785ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:21:43.500329Z","caller":"traceutil/trace.go:171","msg":"trace[1820914022] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"930.525448ms","start":"2026-07-16T00:21:42.569793Z","end":"2026-07-16T00:21:43.500318Z","steps":["trace[1820914022] 'process raft request' (duration: 930.068478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:43.500386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:42.569785Z","time spent":"930.572809ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:21:43.500422Z","caller":"traceutil/trace.go:171","msg":"trace[1065055081] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"930.544118ms","start":"2026-07-16T00:21:42.56984Z","end":"2026-07-16T00:21:43.500384Z","steps":["trace[1065055081] 'process raft request' (duration: 930.097939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:43.50076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:42.569834Z","time spent":"930.6677ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:21:43.50034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.914196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:21:43.5011Z","caller":"traceutil/trace.go:171","msg":"trace[872452438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"893.721721ms","start":"2026-07-16T00:21:42.60736Z","end":"2026-07-16T00:21:43.501082Z","steps":["trace[872452438] 'agreement among raft nodes before linearized reading' (duration: 892.833765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:43.501201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:42.607339Z","time spent":"893.843093ms","remote":"127.0.0.1:49876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T00:21:43.899507Z","caller":"traceutil/trace.go:171","msg":"trace[1266732700] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"173.787031ms","start":"2026-07-16T00:21:43.725692Z","end":"2026-07-16T00:21:43.899479Z","steps":["trace[1266732700] 'process raft request' (duration: 149.343155ms)","trace[1266732700] 'compare' (duration: 24.12563ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:21:44.478345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.562797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T00:21:44.478439Z","caller":"traceutil/trace.go:171","msg":"trace[528366954] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1249; }","duration":"284.664969ms","start":"2026-07-16T00:21:44.193746Z","end":"2026-07-16T00:21:44.478411Z","steps":["trace[528366954] 'count revisions from in-memory index tree' (duration: 284.396495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:45.106908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.923874ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-16T00:21:45.217944Z","caller":"traceutil/trace.go:171","msg":"trace[357477571] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1302; }","duration":"615.265563ms","start":"2026-07-16T00:21:44.602636Z","end":"2026-07-16T00:21:45.217902Z","steps":["trace[357477571] 'read index received' (duration: 125.35168ms)","trace[357477571] 'applied index is now lower than readState.Index' (duration: 489.912513ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:21:45.218132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.454287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:21:45.218078Z","caller":"traceutil/trace.go:171","msg":"trace[1260517928] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"623.056844ms","start":"2026-07-16T00:21:44.594983Z","end":"2026-07-16T00:21:45.21804Z","steps":["trace[1260517928] 'process raft request' (duration: 622.679186ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:21:45.218417Z","caller":"traceutil/trace.go:171","msg":"trace[1203524090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1250; }","duration":"615.668131ms","start":"2026-07-16T00:21:44.602593Z","end":"2026-07-16T00:21:45.218261Z","steps":["trace[1203524090] 'agreement among raft nodes before linearized reading' (duration: 615.453887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:45.21848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:44.602573Z","time spent":"615.893615ms","remote":"127.0.0.1:49876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T00:21:45.218606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:44.594948Z","time spent":"623.397431ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:21:51.611809Z","caller":"traceutil/trace.go:171","msg":"trace[1921521513] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"126.713025ms","start":"2026-07-16T00:21:51.485075Z","end":"2026-07-16T00:21:51.611788Z","steps":["trace[1921521513] 'process raft request' (duration: 125.708715ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:21:52.620449Z","caller":"traceutil/trace.go:171","msg":"trace[668472457] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"202.717124ms","start":"2026-07-16T00:21:52.417688Z","end":"2026-07-16T00:21:52.620405Z","steps":["trace[668472457] 'process raft request' (duration: 202.613082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:54.554878Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.314170522s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-16T00:21:54.602793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000785914s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-16T00:21:54.602878Z","caller":"traceutil/trace.go:171","msg":"trace[1316365467] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000920336s","start":"2026-07-16T00:21:52.60194Z","end":"2026-07-16T00:21:54.60286Z","steps":["trace[1316365467] 'agreement among raft nodes before linearized reading' (duration: 2.000800774s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:54.602929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:52.601924Z","time spent":"2.000995568s","remote":"127.0.0.1:49872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/16 00:21:54 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-16T00:21:54.781712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.540907344s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:21:54.781894Z","caller":"traceutil/trace.go:171","msg":"trace[674996296] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"2.156963233s","start":"2026-07-16T00:21:52.624914Z","end":"2026-07-16T00:21:54.781878Z","steps":["trace[674996296] 'process raft request' (duration: 2.156893502s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:54.781952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:52.624891Z","time spent":"2.157035194s","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":856,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:21:54.782205Z","caller":"traceutil/trace.go:171","msg":"trace[2134541489] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"2.323210238s","start":"2026-07-16T00:21:52.458982Z","end":"2026-07-16T00:21:54.782192Z","steps":["trace[2134541489] 'process raft request' (duration: 781.758353ms)","trace[2134541489] 'compare' (duration: 1.540719751s)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:21:54.782251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:52.458958Z","time spent":"2.323273139s","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:21:54.78238Z","caller":"traceutil/trace.go:171","msg":"trace[1644589050] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"2.254263387s","start":"2026-07-16T00:21:52.528092Z","end":"2026-07-16T00:21:54.782355Z","steps":["trace[1644589050] 'read index received' (duration: 92.348838ms)","trace[1644589050] 'applied index is now lower than readState.Index' (duration: 2.161912069s)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:21:54.782573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.25446337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T00:21:54.782607Z","caller":"traceutil/trace.go:171","msg":"trace[1950909926] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1327; }","duration":"2.254549171s","start":"2026-07-16T00:21:52.528048Z","end":"2026-07-16T00:21:54.782597Z","steps":["trace[1950909926] 'agreement among raft nodes before linearized reading' (duration: 2.254423739s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:54.782674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:52.52803Z","time spent":"2.254601673s","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-07-16T00:21:54.78306Z","caller":"traceutil/trace.go:171","msg":"trace[323740649] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"777.021111ms","start":"2026-07-16T00:21:54.006024Z","end":"2026-07-16T00:21:54.783045Z","steps":["trace[323740649] 'process raft request' (duration: 776.930359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:54.78315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:54.006001Z","time spent":"777.097532ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:21:54.798223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178103976s","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-07-16T00:21:54.798269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.58858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:21:54.798282Z","caller":"traceutil/trace.go:171","msg":"trace[172342474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1330; }","duration":"1.178205537s","start":"2026-07-16T00:21:53.620064Z","end":"2026-07-16T00:21:54.798269Z","steps":["trace[172342474] 'agreement among raft nodes before linearized reading' (duration: 1.178038784s)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:21:54.798292Z","caller":"traceutil/trace.go:171","msg":"trace[1799188714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1330; }","duration":"188.631151ms","start":"2026-07-16T00:21:54.609652Z","end":"2026-07-16T00:21:54.798284Z","steps":["trace[1799188714] 'agreement among raft nodes before linearized reading' (duration: 188.605081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:54.798317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:53.620049Z","time spent":"1.178261609s","remote":"127.0.0.1:50122","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":"warn","ts":"2026-07-16T00:21:54.798326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379060396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:21:54.798373Z","caller":"traceutil/trace.go:171","msg":"trace[1599206869] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1330; }","duration":"1.379153747s","start":"2026-07-16T00:21:53.419208Z","end":"2026-07-16T00:21:54.798362Z","steps":["trace[1599206869] 'agreement among raft nodes before linearized reading' (duration: 1.379078525s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:54.798399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:53.419187Z","time spent":"1.379203818s","remote":"127.0.0.1:45930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-07-16T00:21:54.798237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.925862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-16T00:21:54.79844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.475714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-16T00:21:54.798462Z","caller":"traceutil/trace.go:171","msg":"trace[1228595139] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1330; }","duration":"565.526025ms","start":"2026-07-16T00:21:54.23293Z","end":"2026-07-16T00:21:54.798456Z","steps":["trace[1228595139] 'agreement among raft nodes before linearized reading' (duration: 565.452693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:54.798484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:54.232913Z","time spent":"565.566046ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-16T00:21:54.798478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147072761s","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-07-16T00:21:54.79852Z","caller":"traceutil/trace.go:171","msg":"trace[1723873253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1330; }","duration":"1.147157363s","start":"2026-07-16T00:21:53.651351Z","end":"2026-07-16T00:21:54.798508Z","steps":["trace[1723873253] 'agreement among raft nodes before linearized reading' (duration: 1.147045231s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:54.798563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449738181s","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-07-16T00:21:54.798442Z","caller":"traceutil/trace.go:171","msg":"trace[1778761933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1330; }","duration":"769.158637ms","start":"2026-07-16T00:21:54.029276Z","end":"2026-07-16T00:21:54.798435Z","steps":["trace[1778761933] 'agreement among raft nodes before linearized reading' (duration: 768.897872ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:21:54.798585Z","caller":"traceutil/trace.go:171","msg":"trace[329341583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1330; }","duration":"1.449760831s","start":"2026-07-16T00:21:53.348818Z","end":"2026-07-16T00:21:54.798579Z","steps":["trace[329341583] 'agreement among raft nodes before linearized reading' (duration: 1.449674379s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:54.798594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:54.029235Z","time spent":"769.35421ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T00:21:54.798604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:53.348751Z","time spent":"1.449848282s","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T00:21:54.798657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.07666245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-16T00:21:54.798678Z","caller":"traceutil/trace.go:171","msg":"trace[929727145] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1330; }","duration":"2.076736972s","start":"2026-07-16T00:21:52.721937Z","end":"2026-07-16T00:21:54.798674Z","steps":["trace[929727145] 'agreement among raft nodes before linearized reading' (duration: 2.076672331s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:54.798696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:52.721918Z","time spent":"2.076774302s","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-16T00:21:54.798675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145499351s","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-07-16T00:21:54.798557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:53.651329Z","time spent":"1.147220163s","remote":"127.0.0.1:50122","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-07-16T00:21:54.798743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.209858522s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T00:21:54.798734Z","caller":"traceutil/trace.go:171","msg":"trace[2092237102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1330; }","duration":"1.145556992s","start":"2026-07-16T00:21:53.653166Z","end":"2026-07-16T00:21:54.798723Z","steps":["trace[2092237102] 'agreement among raft nodes before linearized reading' (duration: 1.145413179s)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:21:54.798758Z","caller":"traceutil/trace.go:171","msg":"trace[828947843] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1330; }","duration":"2.209911953s","start":"2026-07-16T00:21:52.58884Z","end":"2026-07-16T00:21:54.798752Z","steps":["trace[828947843] 'agreement among raft nodes before linearized reading' (duration: 2.209884623s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:21:54.798773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:52.588812Z","time spent":"2.209956594s","remote":"127.0.0.1:50350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-07-16T00:21:54.79877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:21:53.653134Z","time spent":"1.145626833s","remote":"127.0.0.1:50122","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":"info","ts":"2026-07-16T00:24:51.978183Z","caller":"traceutil/trace.go:171","msg":"trace[320586320] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"181.34505ms","start":"2026-07-16T00:24:51.796806Z","end":"2026-07-16T00:24:51.978151Z","steps":["trace[320586320] 'process raft request' (duration: 128.240402ms)","trace[320586320] 'compare' (duration: 52.911074ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:24:51.97861Z","caller":"traceutil/trace.go:171","msg":"trace[1062920823] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"180.781598ms","start":"2026-07-16T00:24:51.797816Z","end":"2026-07-16T00:24:51.978598Z","steps":["trace[1062920823] 'process raft request' (duration: 180.282739ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:24:51.97874Z","caller":"traceutil/trace.go:171","msg":"trace[620898707] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"180.452943ms","start":"2026-07-16T00:24:51.798278Z","end":"2026-07-16T00:24:51.978731Z","steps":["trace[620898707] 'process raft request' (duration: 180.096726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:24:54.475355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.465403ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-07-16T00:25:00.376803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.441368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:25:00.376877Z","caller":"traceutil/trace.go:171","msg":"trace[1246936078] linearizableReadLoop","detail":"{readStateIndex:2282; appliedIndex:2281; }","duration":"405.003087ms","start":"2026-07-16T00:24:59.971863Z","end":"2026-07-16T00:25:00.376866Z","steps":["trace[1246936078] 'read index received' (duration: 1.327976ms)","trace[1246936078] 'applied index is now lower than readState.Index' (duration: 403.674071ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:25:00.377054Z","caller":"traceutil/trace.go:171","msg":"trace[874653274] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"438.574248ms","start":"2026-07-16T00:24:59.93847Z","end":"2026-07-16T00:25:00.377044Z","steps":["trace[874653274] 'process raft request' (duration: 34.823584ms)","trace[874653274] 'compare' (duration: 403.237423ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:25:00.377092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:24:59.938456Z","time spent":"438.619429ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:25:00.377222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.360225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T00:25:00.377242Z","caller":"traceutil/trace.go:171","msg":"trace[1297863188] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2189; }","duration":"405.419426ms","start":"2026-07-16T00:24:59.971818Z","end":"2026-07-16T00:25:00.377238Z","steps":["trace[1297863188] 'agreement among raft nodes before linearized reading' (duration: 405.369135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:25:00.377257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:24:59.971798Z","time spent":"405.456156ms","remote":"127.0.0.1:50224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-16T00:25:00.377495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.27209ms","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:531"} {"level":"info","ts":"2026-07-16T00:25:00.377514Z","caller":"traceutil/trace.go:171","msg":"trace[287754631] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2189; }","duration":"349.33015ms","start":"2026-07-16T00:25:00.028179Z","end":"2026-07-16T00:25:00.377509Z","steps":["trace[287754631] 'agreement among raft nodes before linearized reading' (duration: 349.255689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:25:00.377528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:25:00.028161Z","time spent":"349.364161ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-16T00:25:00.37761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.360958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-16T00:25:00.377627Z","caller":"traceutil/trace.go:171","msg":"trace[882581292] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2189; }","duration":"138.403898ms","start":"2026-07-16T00:25:00.239219Z","end":"2026-07-16T00:25:00.377623Z","steps":["trace[882581292] 'agreement among raft nodes before linearized reading' (duration: 138.363458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:25:00.37771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.94446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-16T00:25:00.377727Z","caller":"traceutil/trace.go:171","msg":"trace[61868875] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2189; }","duration":"152.98392ms","start":"2026-07-16T00:25:00.224739Z","end":"2026-07-16T00:25:00.377723Z","steps":["trace[61868875] 'agreement among raft nodes before linearized reading' (duration: 152.941059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:25:00.377935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.478502ms","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-07-16T00:25:00.377953Z","caller":"traceutil/trace.go:171","msg":"trace[53180470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2189; }","duration":"273.516672ms","start":"2026-07-16T00:25:00.104433Z","end":"2026-07-16T00:25:00.377949Z","steps":["trace[53180470] 'agreement among raft nodes before linearized reading' (duration: 273.470672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:25:00.377914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.567473ms","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-07-16T00:25:00.378077Z","caller":"traceutil/trace.go:171","msg":"trace[1148218261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2189; }","duration":"275.739196ms","start":"2026-07-16T00:25:00.102296Z","end":"2026-07-16T00:25:00.378035Z","steps":["trace[1148218261] 'agreement among raft nodes before linearized reading' (duration: 275.389889ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:25:00.517465Z","caller":"traceutil/trace.go:171","msg":"trace[508550527] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"134.605845ms","start":"2026-07-16T00:25:00.382838Z","end":"2026-07-16T00:25:00.517444Z","steps":["trace[508550527] 'process raft request' (duration: 129.814152ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:25:00.52218Z","caller":"traceutil/trace.go:171","msg":"trace[157621125] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"138.636593ms","start":"2026-07-16T00:25:00.383526Z","end":"2026-07-16T00:25:00.522162Z","steps":["trace[157621125] 'process raft request' (duration: 138.563202ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:25:00.522298Z","caller":"traceutil/trace.go:171","msg":"trace[116232970] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"137.47065ms","start":"2026-07-16T00:25:00.384809Z","end":"2026-07-16T00:25:00.52228Z","steps":["trace[116232970] 'process raft request' (duration: 137.408139ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:25:00.522197Z","caller":"traceutil/trace.go:171","msg":"trace[1075549824] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"139.245914ms","start":"2026-07-16T00:25:00.382937Z","end":"2026-07-16T00:25:00.522183Z","steps":["trace[1075549824] 'process raft request' (duration: 139.044581ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:25:00.522425Z","caller":"traceutil/trace.go:171","msg":"trace[1228913085] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"138.746445ms","start":"2026-07-16T00:25:00.383672Z","end":"2026-07-16T00:25:00.522418Z","steps":["trace[1228913085] 'process raft request' (duration: 138.457599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:25:19.490722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.611065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-07-16T00:25:19.490853Z","caller":"traceutil/trace.go:171","msg":"trace[1437449625] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2276; }","duration":"119.8326ms","start":"2026-07-16T00:25:19.37099Z","end":"2026-07-16T00:25:19.490823Z","steps":["trace[1437449625] 'range keys from in-memory index tree' (duration: 119.285879ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:25:22.764898Z","caller":"traceutil/trace.go:171","msg":"trace[2064697890] linearizableReadLoop","detail":"{readStateIndex:2389; appliedIndex:2388; }","duration":"118.120016ms","start":"2026-07-16T00:25:22.646747Z","end":"2026-07-16T00:25:22.764867Z","steps":["trace[2064697890] 'read index received' (duration: 117.752729ms)","trace[2064697890] 'applied index is now lower than readState.Index' (duration: 365.877µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:25:22.764984Z","caller":"traceutil/trace.go:171","msg":"trace[543407774] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"127.240822ms","start":"2026-07-16T00:25:22.637696Z","end":"2026-07-16T00:25:22.764937Z","steps":["trace[543407774] 'process raft request' (duration: 126.975697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:25:22.765253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.478603ms","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-07-16T00:25:22.765341Z","caller":"traceutil/trace.go:171","msg":"trace[224027734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2292; }","duration":"118.582955ms","start":"2026-07-16T00:25:22.646733Z","end":"2026-07-16T00:25:22.765316Z","steps":["trace[224027734] 'agreement among raft nodes before linearized reading' (duration: 118.303889ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:25:53.124762Z","caller":"traceutil/trace.go:171","msg":"trace[1216183391] linearizableReadLoop","detail":"{readStateIndex:2535; appliedIndex:2534; }","duration":"132.193237ms","start":"2026-07-16T00:25:52.992535Z","end":"2026-07-16T00:25:53.124728Z","steps":["trace[1216183391] 'read index received' (duration: 47.228944ms)","trace[1216183391] 'applied index is now lower than readState.Index' (duration: 84.962813ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:25:53.124976Z","caller":"traceutil/trace.go:171","msg":"trace[1767302756] transaction","detail":"{read_only:false; response_revision:2432; number_of_response:1; }","duration":"133.627022ms","start":"2026-07-16T00:25:52.991253Z","end":"2026-07-16T00:25:53.124881Z","steps":["trace[1767302756] 'process raft request' (duration: 48.559908ms)","trace[1767302756] 'compare' (duration: 84.745098ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:25:53.125032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.451981ms","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-07-16T00:25:53.125266Z","caller":"traceutil/trace.go:171","msg":"trace[786536914] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2432; }","duration":"132.735776ms","start":"2026-07-16T00:25:52.992499Z","end":"2026-07-16T00:25:53.125235Z","steps":["trace[786536914] 'agreement among raft nodes before linearized reading' (duration: 132.360169ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:25:53.242616Z","caller":"traceutil/trace.go:171","msg":"trace[1296048049] transaction","detail":"{read_only:false; response_revision:2433; number_of_response:1; }","duration":"112.599186ms","start":"2026-07-16T00:25:53.129975Z","end":"2026-07-16T00:25:53.242574Z","steps":["trace[1296048049] 'process raft request' (duration: 105.977438ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:06.422888Z","caller":"traceutil/trace.go:171","msg":"trace[169380349] transaction","detail":"{read_only:false; response_revision:2516; number_of_response:1; }","duration":"145.202877ms","start":"2026-07-16T00:26:06.277652Z","end":"2026-07-16T00:26:06.422855Z","steps":["trace[169380349] 'process raft request' (duration: 48.212722ms)","trace[169380349] 'compare' (duration: 96.75485ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:26:06.422959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.059655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-16T00:26:06.423058Z","caller":"traceutil/trace.go:171","msg":"trace[1718403756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2516; }","duration":"100.212168ms","start":"2026-07-16T00:26:06.32282Z","end":"2026-07-16T00:26:06.423032Z","steps":["trace[1718403756] 'agreement among raft nodes before linearized reading' (duration: 99.969053ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:06.600259Z","caller":"traceutil/trace.go:171","msg":"trace[265625681] transaction","detail":"{read_only:false; response_revision:2518; number_of_response:1; }","duration":"107.512658ms","start":"2026-07-16T00:26:06.492715Z","end":"2026-07-16T00:26:06.600228Z","steps":["trace[265625681] 'process raft request' (duration: 30.645382ms)","trace[265625681] 'compare' (duration: 76.696373ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:26:10.947652Z","caller":"traceutil/trace.go:171","msg":"trace[1676391002] linearizableReadLoop","detail":"{readStateIndex:2644; appliedIndex:2643; }","duration":"166.962048ms","start":"2026-07-16T00:26:10.78065Z","end":"2026-07-16T00:26:10.947612Z","steps":["trace[1676391002] 'read index received' (duration: 197.094µs)","trace[1676391002] 'applied index is now lower than readState.Index' (duration: 166.763374ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:26:10.947944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.806363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-16T00:26:10.948052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.726053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2569"} {"level":"info","ts":"2026-07-16T00:26:10.9481Z","caller":"traceutil/trace.go:171","msg":"trace[1271688001] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2536; }","duration":"168.015678ms","start":"2026-07-16T00:26:10.780033Z","end":"2026-07-16T00:26:10.948048Z","steps":["trace[1271688001] 'agreement among raft nodes before linearized reading' (duration: 167.63148ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:10.948114Z","caller":"traceutil/trace.go:171","msg":"trace[592263765] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2536; }","duration":"123.825665ms","start":"2026-07-16T00:26:10.82427Z","end":"2026-07-16T00:26:10.948096Z","steps":["trace[592263765] 'agreement among raft nodes before linearized reading' (duration: 123.579859ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:10.948337Z","caller":"traceutil/trace.go:171","msg":"trace[269564447] transaction","detail":"{read_only:false; response_revision:2536; number_of_response:1; }","duration":"168.253412ms","start":"2026-07-16T00:26:10.780036Z","end":"2026-07-16T00:26:10.94829Z","steps":["trace[269564447] 'process raft request' (duration: 167.239892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:11.609751Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870774183887898,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T00:26:11.649776Z","caller":"traceutil/trace.go:171","msg":"trace[1848056786] linearizableReadLoop","detail":"{readStateIndex:2647; appliedIndex:2646; }","duration":"540.693483ms","start":"2026-07-16T00:26:11.109047Z","end":"2026-07-16T00:26:11.64974Z","steps":["trace[1848056786] 'read index received' (duration: 514.971975ms)","trace[1848056786] 'applied index is now lower than readState.Index' (duration: 25.718138ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:26:11.649868Z","caller":"traceutil/trace.go:171","msg":"trace[279229654] transaction","detail":"{read_only:false; response_revision:2539; number_of_response:1; }","duration":"557.323655ms","start":"2026-07-16T00:26:11.092452Z","end":"2026-07-16T00:26:11.649775Z","steps":["trace[279229654] 'process raft request' (duration: 531.648328ms)","trace[279229654] 'compare' (duration: 25.160926ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:26:11.650089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:26:11.092436Z","time spent":"557.547228ms","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:26:11.650148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.588109ms","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-07-16T00:26:11.650215Z","caller":"traceutil/trace.go:171","msg":"trace[769136595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2539; }","duration":"423.747142ms","start":"2026-07-16T00:26:11.226451Z","end":"2026-07-16T00:26:11.650198Z","steps":["trace[769136595] 'agreement among raft nodes before linearized reading' (duration: 423.475647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:11.650175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.0837ms","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-07-16T00:26:11.650291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:26:11.226419Z","time spent":"423.862874ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-16T00:26:11.650342Z","caller":"traceutil/trace.go:171","msg":"trace[2028478423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2539; }","duration":"541.313304ms","start":"2026-07-16T00:26:11.109003Z","end":"2026-07-16T00:26:11.650316Z","steps":["trace[2028478423] 'agreement among raft nodes before linearized reading' (duration: 540.842245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:11.650442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:26:11.108986Z","time spent":"541.423336ms","remote":"127.0.0.1:50122","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-07-16T00:26:11.651966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.668852ms","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:533"} {"level":"info","ts":"2026-07-16T00:26:11.652034Z","caller":"traceutil/trace.go:171","msg":"trace[1270665469] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2539; }","duration":"331.1089ms","start":"2026-07-16T00:26:11.320902Z","end":"2026-07-16T00:26:11.652011Z","steps":["trace[1270665469] 'agreement among raft nodes before linearized reading' (duration: 329.651062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:11.652067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:26:11.320873Z","time spent":"331.185382ms","remote":"127.0.0.1:50122","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-07-16T00:26:11.865413Z","caller":"traceutil/trace.go:171","msg":"trace[1637322877] transaction","detail":"{read_only:false; response_revision:2542; number_of_response:1; }","duration":"208.197735ms","start":"2026-07-16T00:26:11.657193Z","end":"2026-07-16T00:26:11.865391Z","steps":["trace[1637322877] 'process raft request' (duration: 208.126314ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:11.86558Z","caller":"traceutil/trace.go:171","msg":"trace[1291692182] transaction","detail":"{read_only:false; response_revision:2543; number_of_response:1; }","duration":"208.293767ms","start":"2026-07-16T00:26:11.657259Z","end":"2026-07-16T00:26:11.865553Z","steps":["trace[1291692182] 'process raft request' (duration: 208.097323ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:11.865813Z","caller":"traceutil/trace.go:171","msg":"trace[1713043621] transaction","detail":"{read_only:false; response_revision:2541; number_of_response:1; }","duration":"210.300356ms","start":"2026-07-16T00:26:11.655502Z","end":"2026-07-16T00:26:11.865803Z","steps":["trace[1713043621] 'process raft request' (duration: 171.491525ms)","trace[1713043621] 'compare' (duration: 38.23271ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:26:11.939307Z","caller":"traceutil/trace.go:171","msg":"trace[1833831859] transaction","detail":"{read_only:false; response_revision:2545; number_of_response:1; }","duration":"112.366563ms","start":"2026-07-16T00:26:11.826918Z","end":"2026-07-16T00:26:11.939285Z","steps":["trace[1833831859] 'process raft request' (duration: 112.244449ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:12.669865Z","caller":"traceutil/trace.go:171","msg":"trace[1980843903] linearizableReadLoop","detail":"{readStateIndex:2654; appliedIndex:2653; }","duration":"350.427654ms","start":"2026-07-16T00:26:12.319413Z","end":"2026-07-16T00:26:12.669841Z","steps":["trace[1980843903] 'read index received' (duration: 350.22815ms)","trace[1980843903] 'applied index is now lower than readState.Index' (duration: 198.454µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:26:12.670009Z","caller":"traceutil/trace.go:171","msg":"trace[34534755] transaction","detail":"{read_only:false; response_revision:2546; number_of_response:1; }","duration":"396.021785ms","start":"2026-07-16T00:26:12.273972Z","end":"2026-07-16T00:26:12.669994Z","steps":["trace[34534755] 'process raft request' (duration: 395.719279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:12.670142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:26:12.27395Z","time spent":"396.101397ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:26:12.670265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.508163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-16T00:26:12.670339Z","caller":"traceutil/trace.go:171","msg":"trace[631100173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2546; }","duration":"206.613745ms","start":"2026-07-16T00:26:12.463709Z","end":"2026-07-16T00:26:12.670323Z","steps":["trace[631100173] 'agreement among raft nodes before linearized reading' (duration: 206.438772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:12.670374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.956795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:26:12.670397Z","caller":"traceutil/trace.go:171","msg":"trace[948307960] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2546; }","duration":"351.012706ms","start":"2026-07-16T00:26:12.319377Z","end":"2026-07-16T00:26:12.67039Z","steps":["trace[948307960] 'agreement among raft nodes before linearized reading' (duration: 350.976205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:12.670435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:26:12.31936Z","time spent":"351.069347ms","remote":"127.0.0.1:45936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-16T00:26:12.670858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.019217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T00:26:12.67094Z","caller":"traceutil/trace.go:171","msg":"trace[1657025894] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2546; }","duration":"155.16283ms","start":"2026-07-16T00:26:12.515757Z","end":"2026-07-16T00:26:12.67092Z","steps":["trace[1657025894] 'agreement among raft nodes before linearized reading' (duration: 154.718982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:13.062903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.395217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:26:13.063128Z","caller":"traceutil/trace.go:171","msg":"trace[1373622256] transaction","detail":"{read_only:false; response_revision:2548; number_of_response:1; }","duration":"387.95925ms","start":"2026-07-16T00:26:12.675027Z","end":"2026-07-16T00:26:13.062987Z","steps":["trace[1373622256] 'process raft request' (duration: 71.38651ms)","trace[1373622256] 'compare' (duration: 316.288855ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:26:13.063374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:26:12.675021Z","time spent":"388.315457ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:26:30.061038Z","caller":"traceutil/trace.go:171","msg":"trace[208938572] transaction","detail":"{read_only:false; response_revision:2630; number_of_response:1; }","duration":"102.835358ms","start":"2026-07-16T00:26:29.958178Z","end":"2026-07-16T00:26:30.061013Z","steps":["trace[208938572] 'process raft request' (duration: 102.688275ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:30.190691Z","caller":"traceutil/trace.go:171","msg":"trace[1947095984] linearizableReadLoop","detail":"{readStateIndex:2744; appliedIndex:2742; }","duration":"193.890938ms","start":"2026-07-16T00:26:29.996735Z","end":"2026-07-16T00:26:30.190626Z","steps":["trace[1947095984] 'read index received' (duration: 64.225551ms)","trace[1947095984] 'applied index is now lower than readState.Index' (duration: 129.664327ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:26:30.19077Z","caller":"traceutil/trace.go:171","msg":"trace[1805688347] transaction","detail":"{read_only:false; response_revision:2631; number_of_response:1; }","duration":"195.818355ms","start":"2026-07-16T00:26:29.994892Z","end":"2026-07-16T00:26:30.19071Z","steps":["trace[1805688347] 'process raft request' (duration: 124.270803ms)","trace[1805688347] 'compare' (duration: 71.308198ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:26:30.191042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.058014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-16T00:26:30.191094Z","caller":"traceutil/trace.go:171","msg":"trace[1837401094] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2631; }","duration":"189.186997ms","start":"2026-07-16T00:26:30.001892Z","end":"2026-07-16T00:26:30.191079Z","steps":["trace[1837401094] 'agreement among raft nodes before linearized reading' (duration: 188.983273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:30.191127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.145106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-bootstrapper\" ","response":"range_response_count:1 size:633"} {"level":"info","ts":"2026-07-16T00:26:30.19123Z","caller":"traceutil/trace.go:171","msg":"trace[36090968] range","detail":"{range_begin:/registry/clusterroles/system:node-bootstrapper; range_end:; response_count:1; response_revision:2631; }","duration":"189.273119ms","start":"2026-07-16T00:26:30.001939Z","end":"2026-07-16T00:26:30.191212Z","steps":["trace[36090968] 'agreement among raft nodes before linearized reading' (duration: 189.014144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:30.191323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.543231ms","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:533"} {"level":"info","ts":"2026-07-16T00:26:30.191398Z","caller":"traceutil/trace.go:171","msg":"trace[1199829624] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2631; }","duration":"194.677625ms","start":"2026-07-16T00:26:29.9967Z","end":"2026-07-16T00:26:30.191377Z","steps":["trace[1199829624] 'agreement among raft nodes before linearized reading' (duration: 194.144954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:30.191665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.920844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:replicaset-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-07-16T00:26:30.191809Z","caller":"traceutil/trace.go:171","msg":"trace[998171226] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:replicaset-controller; range_end:; response_count:1; response_revision:2631; }","duration":"171.053277ms","start":"2026-07-16T00:26:30.020705Z","end":"2026-07-16T00:26:30.191758Z","steps":["trace[998171226] 'agreement among raft nodes before linearized reading' (duration: 170.156659ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:30.310485Z","caller":"traceutil/trace.go:171","msg":"trace[518359954] transaction","detail":"{read_only:false; response_revision:2633; number_of_response:1; }","duration":"113.150388ms","start":"2026-07-16T00:26:30.197312Z","end":"2026-07-16T00:26:30.310462Z","steps":["trace[518359954] 'process raft request' (duration: 95.975915ms)","trace[518359954] 'compare' (duration: 16.792085ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:26:31.123761Z","caller":"traceutil/trace.go:171","msg":"trace[1760773207] linearizableReadLoop","detail":"{readStateIndex:2748; appliedIndex:2747; }","duration":"280.827599ms","start":"2026-07-16T00:26:30.842912Z","end":"2026-07-16T00:26:31.123739Z","steps":["trace[1760773207] 'read index received' (duration: 237.90772ms)","trace[1760773207] 'applied index is now lower than readState.Index' (duration: 42.918729ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:26:31.1239Z","caller":"traceutil/trace.go:171","msg":"trace[515874926] transaction","detail":"{read_only:false; response_revision:2635; number_of_response:1; }","duration":"280.974962ms","start":"2026-07-16T00:26:30.842892Z","end":"2026-07-16T00:26:31.123867Z","steps":["trace[515874926] 'process raft request' (duration: 237.96351ms)","trace[515874926] 'compare' (duration: 42.781357ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:26:31.123949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.057971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:1 size:613"} {"level":"warn","ts":"2026-07-16T00:26:31.123953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.020123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-07-16T00:26:31.123986Z","caller":"traceutil/trace.go:171","msg":"trace[520243460] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:1; response_revision:2635; }","duration":"227.140462ms","start":"2026-07-16T00:26:30.896836Z","end":"2026-07-16T00:26:31.123977Z","steps":["trace[520243460] 'agreement among raft nodes before linearized reading' (duration: 227.062981ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:31.124006Z","caller":"traceutil/trace.go:171","msg":"trace[473421305] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:2635; }","duration":"281.108275ms","start":"2026-07-16T00:26:30.84288Z","end":"2026-07-16T00:26:31.123989Z","steps":["trace[473421305] 'agreement among raft nodes before linearized reading' (duration: 281.005153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:31.124249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.934471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-16T00:26:31.124333Z","caller":"traceutil/trace.go:171","msg":"trace[1428519201] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2635; }","duration":"145.051654ms","start":"2026-07-16T00:26:30.979259Z","end":"2026-07-16T00:26:31.12431Z","steps":["trace[1428519201] 'agreement among raft nodes before linearized reading' (duration: 144.82387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:42.139574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.478696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kube-proxy\" ","response":"range_response_count:1 size:317"} {"level":"info","ts":"2026-07-16T00:26:42.139656Z","caller":"traceutil/trace.go:171","msg":"trace[1546225783] range","detail":"{range_begin:/registry/roles/kube-system/kube-proxy; range_end:; response_count:1; response_revision:2686; }","duration":"127.602998ms","start":"2026-07-16T00:26:42.012033Z","end":"2026-07-16T00:26:42.139636Z","steps":["trace[1546225783] 'range keys from in-memory index tree' (duration: 127.391164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:42.139698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.498777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:kubeadm-certs\" ","response":"range_response_count:1 size:339"} {"level":"info","ts":"2026-07-16T00:26:42.139786Z","caller":"traceutil/trace.go:171","msg":"trace[893018065] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:kubeadm-certs; range_end:; response_count:1; response_revision:2686; }","duration":"125.627379ms","start":"2026-07-16T00:26:42.014137Z","end":"2026-07-16T00:26:42.139764Z","steps":["trace[893018065] 'range keys from in-memory index tree' (duration: 125.306183ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:45.017307Z","caller":"traceutil/trace.go:171","msg":"trace[583142279] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"110.840213ms","start":"2026-07-16T00:26:44.906437Z","end":"2026-07-16T00:26:45.017277Z","steps":["trace[583142279] 'process raft request' (duration: 110.6577ms)"],"step_count":1}