{"level":"warn","ts":"2026-04-28T07:32:13.579793Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T07:32:13.579901Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78: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.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-28T07:32:13.580067Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T07:32:13.58008Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-04-28T07:32:13.580101Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T07:32:13.581133Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-04-28T07:32:13.581219Z","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.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-28T07:32:13.584619Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.192743ms"} {"level":"info","ts":"2026-04-28T07:32:13.596029Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-04-28T07:32:13.596127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T07:32:13.596151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-04-28T07:32:13.596165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T07:32:13.596179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-04-28T07:32:13.596218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-04-28T07:32:13.613454Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T07:32:13.617213Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T07:32:13.620062Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-28T07:32:13.623531Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T07:32:13.623644Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T07:32:13.6239Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T07:32:13.624326Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T07:32:13.624585Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T07:32:13.625572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-04-28T07:32:13.625735Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-04-28T07:32:13.628188Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T07:32:13.628321Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-04-28T07:32:13.628399Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-04-28T07:32:13.628466Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T07:32:13.628512Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T07:32:14.596669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T07:32:14.596746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T07:32:14.596771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-04-28T07:32:14.596801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-04-28T07:32:14.596814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-04-28T07:32:14.596831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-04-28T07:32:14.596854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-04-28T07:32:14.598013Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:32:14.598935Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T07:32:14.599127Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T07:32:14.599146Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T07:32:14.599231Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T07:32:14.599258Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T07:32:14.601526Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:32:14.601684Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:32:14.601719Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:32:14.603034Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"info","ts":"2026-04-28T07:32:14.60435Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T07:32:17.025676Z","caller":"traceutil/trace.go:171","msg":"trace[1797085662] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"137.487033ms","start":"2026-04-28T07:32:16.888147Z","end":"2026-04-28T07:32:17.025634Z","steps":["trace[1797085662] 'process raft request' (duration: 105.803925ms)","trace[1797085662] 'compare' (duration: 31.413093ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:32:23.29458Z","caller":"traceutil/trace.go:171","msg":"trace[1288790842] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:310; }","duration":"147.151253ms","start":"2026-04-28T07:32:23.147406Z","end":"2026-04-28T07:32:23.294557Z","steps":["trace[1288790842] 'read index received' (duration: 146.802097ms)","trace[1288790842] 'applied index is now lower than readState.Index' (duration: 347.856µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:32:23.294655Z","caller":"traceutil/trace.go:171","msg":"trace[568908031] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"160.094625ms","start":"2026-04-28T07:32:23.134527Z","end":"2026-04-28T07:32:23.294621Z","steps":["trace[568908031] 'process raft request' (duration: 159.899802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:23.295062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.545093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-04-28T07:32:23.295142Z","caller":"traceutil/trace.go:171","msg":"trace[814709462] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:300; }","duration":"142.726017ms","start":"2026-04-28T07:32:23.152399Z","end":"2026-04-28T07:32:23.295125Z","steps":["trace[814709462] 'agreement among raft nodes before linearized reading' (duration: 142.305918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:23.295368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.939919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-28T07:32:23.295431Z","caller":"traceutil/trace.go:171","msg":"trace[1614715997] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:300; }","duration":"148.039711ms","start":"2026-04-28T07:32:23.147374Z","end":"2026-04-28T07:32:23.295414Z","steps":["trace[1614715997] 'agreement among raft nodes before linearized reading' (duration: 147.320407ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:23.428224Z","caller":"traceutil/trace.go:171","msg":"trace[2029126234] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"128.325806ms","start":"2026-04-28T07:32:23.299845Z","end":"2026-04-28T07:32:23.428171Z","steps":["trace[2029126234] 'process raft request' (duration: 125.804927ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:23.428324Z","caller":"traceutil/trace.go:171","msg":"trace[412165025] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"127.656242ms","start":"2026-04-28T07:32:23.300636Z","end":"2026-04-28T07:32:23.428293Z","steps":["trace[412165025] 'process raft request' (duration: 127.388837ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:23.428798Z","caller":"traceutil/trace.go:171","msg":"trace[1242860868] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"125.181603ms","start":"2026-04-28T07:32:23.303604Z","end":"2026-04-28T07:32:23.428786Z","steps":["trace[1242860868] 'process raft request' (duration: 124.745635ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:23.428946Z","caller":"traceutil/trace.go:171","msg":"trace[936962318] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"126.201333ms","start":"2026-04-28T07:32:23.302736Z","end":"2026-04-28T07:32:23.428937Z","steps":["trace[936962318] 'process raft request' (duration: 125.49158ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:23.429105Z","caller":"traceutil/trace.go:171","msg":"trace[259995595] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"125.726723ms","start":"2026-04-28T07:32:23.303366Z","end":"2026-04-28T07:32:23.429093Z","steps":["trace[259995595] 'process raft request' (duration: 124.922108ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:26.954284Z","caller":"traceutil/trace.go:171","msg":"trace[935765278] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"323.094329ms","start":"2026-04-28T07:32:26.631151Z","end":"2026-04-28T07:32:26.954246Z","steps":["trace[935765278] 'process raft request' (duration: 322.829954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:26.955172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:26.631129Z","time spent":"323.292642ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:32:32.413586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.9938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:32:32.413679Z","caller":"traceutil/trace.go:171","msg":"trace[1464064120] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:413; }","duration":"149.275254ms","start":"2026-04-28T07:32:32.264392Z","end":"2026-04-28T07:32:32.413667Z","steps":["trace[1464064120] 'read index received' (duration: 23.992999ms)","trace[1464064120] 'applied index is now lower than readState.Index' (duration: 125.281545ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:32:32.413747Z","caller":"traceutil/trace.go:171","msg":"trace[1556880912] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"230.282665ms","start":"2026-04-28T07:32:32.183457Z","end":"2026-04-28T07:32:32.41374Z","steps":["trace[1556880912] 'process raft request' (duration: 104.948259ms)","trace[1556880912] 'compare' (duration: 124.830357ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:32:32.41387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.483319ms","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-04-28T07:32:32.413888Z","caller":"traceutil/trace.go:171","msg":"trace[1361448678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:401; }","duration":"149.529589ms","start":"2026-04-28T07:32:32.264352Z","end":"2026-04-28T07:32:32.413882Z","steps":["trace[1361448678] 'agreement among raft nodes before linearized reading' (duration: 149.488347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:32.942053Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604220347719554,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T07:32:33.16138Z","caller":"traceutil/trace.go:171","msg":"trace[1971243900] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"742.354883ms","start":"2026-04-28T07:32:32.419002Z","end":"2026-04-28T07:32:33.161357Z","steps":["trace[1971243900] 'process raft request' (duration: 742.19833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:33.161537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:32.418977Z","time spent":"742.481035ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:32:33.16681Z","caller":"traceutil/trace.go:171","msg":"trace[1151730917] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"719.882975ms","start":"2026-04-28T07:32:32.446859Z","end":"2026-04-28T07:32:33.166741Z","steps":["trace[1151730917] 'process raft request' (duration: 719.61176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:33.167181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:32.446844Z","time spent":"720.17826ms","remote":"127.0.0.1:39986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:32:33.170487Z","caller":"traceutil/trace.go:171","msg":"trace[504557471] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:415; }","duration":"728.658337ms","start":"2026-04-28T07:32:32.4418Z","end":"2026-04-28T07:32:33.170459Z","steps":["trace[504557471] 'read index received' (duration: 719.436446ms)","trace[504557471] 'applied index is now lower than readState.Index' (duration: 9.220671ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:32:33.170663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.83136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:33.170696Z","caller":"traceutil/trace.go:171","msg":"trace[835751560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:404; }","duration":"728.913861ms","start":"2026-04-28T07:32:32.441772Z","end":"2026-04-28T07:32:33.170686Z","steps":["trace[835751560] 'agreement among raft nodes before linearized reading' (duration: 728.8305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:33.170733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:32.441759Z","time spent":"728.964872ms","remote":"127.0.0.1:39816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:32:33.170826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.5643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T07:32:33.170961Z","caller":"traceutil/trace.go:171","msg":"trace[1468116606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:404; }","duration":"192.712952ms","start":"2026-04-28T07:32:32.978208Z","end":"2026-04-28T07:32:33.170921Z","steps":["trace[1468116606] 'agreement among raft nodes before linearized reading' (duration: 192.550969ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:34.556987Z","caller":"traceutil/trace.go:171","msg":"trace[1620646634] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"108.896986ms","start":"2026-04-28T07:32:34.448057Z","end":"2026-04-28T07:32:34.556954Z","steps":["trace[1620646634] 'process raft request' (duration: 108.687062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:38.863001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.731727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-28T07:32:38.863058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.47649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:38.863131Z","caller":"traceutil/trace.go:171","msg":"trace[485934478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"291.9594ms","start":"2026-04-28T07:32:38.571138Z","end":"2026-04-28T07:32:38.863097Z","steps":["trace[485934478] 'range keys from in-memory index tree' (duration: 291.522512ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:38.863171Z","caller":"traceutil/trace.go:171","msg":"trace[602393906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"420.705264ms","start":"2026-04-28T07:32:38.44244Z","end":"2026-04-28T07:32:38.863145Z","steps":["trace[602393906] 'range keys from in-memory index tree' (duration: 420.326876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:38.863246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:38.442405Z","time spent":"420.830416ms","remote":"127.0.0.1:39812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T07:32:43.493128Z","caller":"traceutil/trace.go:171","msg":"trace[555459155] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"183.310848ms","start":"2026-04-28T07:32:43.309779Z","end":"2026-04-28T07:32:43.49309Z","steps":["trace[555459155] 'process raft request' (duration: 155.112458ms)","trace[555459155] 'compare' (duration: 27.886744ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:32:44.371531Z","caller":"traceutil/trace.go:171","msg":"trace[263141786] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"141.581571ms","start":"2026-04-28T07:32:44.229912Z","end":"2026-04-28T07:32:44.371493Z","steps":["trace[263141786] 'read index received' (duration: 140.856797ms)","trace[263141786] 'applied index is now lower than readState.Index' (duration: 723.394µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:32:44.371546Z","caller":"traceutil/trace.go:171","msg":"trace[1705428913] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"390.242695ms","start":"2026-04-28T07:32:43.98127Z","end":"2026-04-28T07:32:44.371512Z","steps":["trace[1705428913] 'process raft request' (duration: 389.771776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:44.371728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:43.981231Z","time spent":"390.422708ms","remote":"127.0.0.1:39904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317296,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:32:44.371763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.837476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T07:32:44.371862Z","caller":"traceutil/trace.go:171","msg":"trace[593967125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:429; }","duration":"141.99624ms","start":"2026-04-28T07:32:44.229843Z","end":"2026-04-28T07:32:44.37184Z","steps":["trace[593967125] 'agreement among raft nodes before linearized reading' (duration: 141.750435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:47.991362Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604220347719804,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T07:32:48.122456Z","caller":"traceutil/trace.go:171","msg":"trace[660395761] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"698.279571ms","start":"2026-04-28T07:32:47.424148Z","end":"2026-04-28T07:32:48.122427Z","steps":["trace[660395761] 'process raft request' (duration: 694.766003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:48.122906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:47.424118Z","time spent":"698.563656ms","remote":"127.0.0.1:39872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:32:48.128491Z","caller":"traceutil/trace.go:171","msg":"trace[1066038958] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:577; }","duration":"637.197233ms","start":"2026-04-28T07:32:47.491273Z","end":"2026-04-28T07:32:48.128471Z","steps":["trace[1066038958] 'read index received' (duration: 626.942466ms)","trace[1066038958] 'applied index is now lower than readState.Index' (duration: 10.253787ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:32:48.128708Z","caller":"traceutil/trace.go:171","msg":"trace[1155815245] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"686.647766ms","start":"2026-04-28T07:32:47.442051Z","end":"2026-04-28T07:32:48.128699Z","steps":["trace[1155815245] 'process raft request' (duration: 686.181047ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:48.128991Z","caller":"traceutil/trace.go:171","msg":"trace[950051812] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"650.279435ms","start":"2026-04-28T07:32:47.478694Z","end":"2026-04-28T07:32:48.128974Z","steps":["trace[950051812] 'process raft request' (duration: 649.645502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:48.129044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:47.478679Z","time spent":"650.340947ms","remote":"127.0.0.1:39872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:32:48.129192Z","caller":"traceutil/trace.go:171","msg":"trace[463832189] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"490.192697ms","start":"2026-04-28T07:32:47.638988Z","end":"2026-04-28T07:32:48.129181Z","steps":["trace[463832189] 'process raft request' (duration: 489.411282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:48.129239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:47.638969Z","time spent":"490.249538ms","remote":"127.0.0.1:39872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:32:48.129375Z","caller":"traceutil/trace.go:171","msg":"trace[425077839] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"289.467394ms","start":"2026-04-28T07:32:47.839886Z","end":"2026-04-28T07:32:48.129353Z","steps":["trace[425077839] 'process raft request' (duration: 288.537626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:48.129376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:47.442028Z","time spent":"686.709337ms","remote":"127.0.0.1:39872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:32:48.129718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.480099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T07:32:48.129753Z","caller":"traceutil/trace.go:171","msg":"trace[390096997] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:566; }","duration":"622.541661ms","start":"2026-04-28T07:32:47.507203Z","end":"2026-04-28T07:32:48.129745Z","steps":["trace[390096997] 'agreement among raft nodes before linearized reading' (duration: 622.426268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:48.12978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:47.507192Z","time spent":"622.582901ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T07:32:48.129903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.63814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:48.129929Z","caller":"traceutil/trace.go:171","msg":"trace[892445732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"638.697582ms","start":"2026-04-28T07:32:47.491225Z","end":"2026-04-28T07:32:48.129923Z","steps":["trace[892445732] 'agreement among raft nodes before linearized reading' (duration: 638.659551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:48.129954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:47.491205Z","time spent":"638.745432ms","remote":"127.0.0.1:39812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:32:48.130068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.87369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-28T07:32:48.130095Z","caller":"traceutil/trace.go:171","msg":"trace[1071903627] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:566; }","duration":"389.944712ms","start":"2026-04-28T07:32:47.740142Z","end":"2026-04-28T07:32:48.130087Z","steps":["trace[1071903627] 'agreement among raft nodes before linearized reading' (duration: 389.902521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:48.130118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:47.740124Z","time spent":"389.989463ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"info","ts":"2026-04-28T07:32:49.239454Z","caller":"traceutil/trace.go:171","msg":"trace[1068426335] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"129.821335ms","start":"2026-04-28T07:32:49.109609Z","end":"2026-04-28T07:32:49.23943Z","steps":["trace[1068426335] 'process raft request' (duration: 129.415887ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:50.203354Z","caller":"traceutil/trace.go:171","msg":"trace[1647461540] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"448.599604ms","start":"2026-04-28T07:32:49.754729Z","end":"2026-04-28T07:32:50.203329Z","steps":["trace[1647461540] 'process raft request' (duration: 448.152706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:50.2035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:49.754703Z","time spent":"448.719646ms","remote":"127.0.0.1:39872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:32:50.203503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.237197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/envoy-gateway-system/\" range_end:\"/registry/resourcequotas/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:50.203592Z","caller":"traceutil/trace.go:171","msg":"trace[1084897339] range","detail":"{range_begin:/registry/resourcequotas/envoy-gateway-system/; range_end:/registry/resourcequotas/envoy-gateway-system0; response_count:0; response_revision:592; }","duration":"447.36897ms","start":"2026-04-28T07:32:49.756203Z","end":"2026-04-28T07:32:50.203572Z","steps":["trace[1084897339] 'agreement among raft nodes before linearized reading' (duration: 447.209477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:50.203644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:49.756149Z","time spent":"447.482233ms","remote":"127.0.0.1:39902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/envoy-gateway-system/\" range_end:\"/registry/resourcequotas/envoy-gateway-system0\" "} {"level":"info","ts":"2026-04-28T07:32:50.203342Z","caller":"traceutil/trace.go:171","msg":"trace[2055812138] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"447.006143ms","start":"2026-04-28T07:32:49.756231Z","end":"2026-04-28T07:32:50.203237Z","steps":["trace[2055812138] 'read index received' (duration: 446.602426ms)","trace[2055812138] 'applied index is now lower than readState.Index' (duration: 402.197µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:32:50.205984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.824138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-28T07:32:50.206053Z","caller":"traceutil/trace.go:171","msg":"trace[181145177] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:593; }","duration":"167.930379ms","start":"2026-04-28T07:32:50.038104Z","end":"2026-04-28T07:32:50.206035Z","steps":["trace[181145177] 'agreement among raft nodes before linearized reading' (duration: 167.733106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:50.206248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.979659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-28T07:32:50.206338Z","caller":"traceutil/trace.go:171","msg":"trace[1435631885] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:593; }","duration":"368.151893ms","start":"2026-04-28T07:32:49.838169Z","end":"2026-04-28T07:32:50.206321Z","steps":["trace[1435631885] 'agreement among raft nodes before linearized reading' (duration: 367.574952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:50.206388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:49.838151Z","time spent":"368.209343ms","remote":"127.0.0.1:39872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-04-28T07:32:51.994064Z","caller":"traceutil/trace.go:171","msg":"trace[1733185710] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"162.979744ms","start":"2026-04-28T07:32:51.831062Z","end":"2026-04-28T07:32:51.994042Z","steps":["trace[1733185710] 'read index received' (duration: 92.881972ms)","trace[1733185710] 'applied index is now lower than readState.Index' (duration: 70.096892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:32:51.994302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.213798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T07:32:51.994358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.385643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:51.994401Z","caller":"traceutil/trace.go:171","msg":"trace[758069137] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"176.408273ms","start":"2026-04-28T07:32:51.817985Z","end":"2026-04-28T07:32:51.994393Z","steps":["trace[758069137] 'process raft request' (duration: 106.046536ms)","trace[758069137] 'compare' (duration: 69.901908ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:32:51.994401Z","caller":"traceutil/trace.go:171","msg":"trace[575091571] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:612; }","duration":"162.453715ms","start":"2026-04-28T07:32:51.831935Z","end":"2026-04-28T07:32:51.994389Z","steps":["trace[575091571] 'agreement among raft nodes before linearized reading' (duration: 162.374203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:51.994539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.472843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:51.994559Z","caller":"traceutil/trace.go:171","msg":"trace[1645455702] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:612; }","duration":"163.516914ms","start":"2026-04-28T07:32:51.831038Z","end":"2026-04-28T07:32:51.994555Z","steps":["trace[1645455702] 'agreement among raft nodes before linearized reading' (duration: 163.485713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:51.994632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.847689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:51.994665Z","caller":"traceutil/trace.go:171","msg":"trace[934155701] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:612; }","duration":"108.88876ms","start":"2026-04-28T07:32:51.885765Z","end":"2026-04-28T07:32:51.994654Z","steps":["trace[934155701] 'agreement among raft nodes before linearized reading' (duration: 108.841459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:51.994758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.227374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:51.99478Z","caller":"traceutil/trace.go:171","msg":"trace[1696452109] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:612; }","duration":"142.269985ms","start":"2026-04-28T07:32:51.852501Z","end":"2026-04-28T07:32:51.994771Z","steps":["trace[1696452109] 'agreement among raft nodes before linearized reading' (duration: 142.242594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:51.994818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.290035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T07:32:51.994858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.300678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:51.994853Z","caller":"traceutil/trace.go:171","msg":"trace[1266008883] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:612; }","duration":"142.358746ms","start":"2026-04-28T07:32:51.852485Z","end":"2026-04-28T07:32:51.994844Z","steps":["trace[1266008883] 'agreement among raft nodes before linearized reading' (duration: 142.299405ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:51.994877Z","caller":"traceutil/trace.go:171","msg":"trace[804504616] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:612; }","duration":"109.34784ms","start":"2026-04-28T07:32:51.885525Z","end":"2026-04-28T07:32:51.994872Z","steps":["trace[804504616] 'agreement among raft nodes before linearized reading' (duration: 109.320929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:51.994947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.398681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:51.99496Z","caller":"traceutil/trace.go:171","msg":"trace[1153222302] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:612; }","duration":"109.430571ms","start":"2026-04-28T07:32:51.885525Z","end":"2026-04-28T07:32:51.994956Z","steps":["trace[1153222302] 'agreement among raft nodes before linearized reading' (duration: 109.410961ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:51.994371Z","caller":"traceutil/trace.go:171","msg":"trace[2033170124] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:612; }","duration":"163.32083ms","start":"2026-04-28T07:32:51.831032Z","end":"2026-04-28T07:32:51.994353Z","steps":["trace[2033170124] 'agreement among raft nodes before linearized reading' (duration: 163.198428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:51.995017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.435148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:51.995031Z","caller":"traceutil/trace.go:171","msg":"trace[1262341130] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:612; }","duration":"141.493149ms","start":"2026-04-28T07:32:51.853533Z","end":"2026-04-28T07:32:51.995027Z","steps":["trace[1262341130] 'agreement among raft nodes before linearized reading' (duration: 141.470499ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:52.934491Z","caller":"traceutil/trace.go:171","msg":"trace[1445019459] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"198.758905ms","start":"2026-04-28T07:32:52.735688Z","end":"2026-04-28T07:32:52.934447Z","steps":["trace[1445019459] 'process raft request' (duration: 185.895206ms)","trace[1445019459] 'compare' (duration: 11.776637ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:32:55.395711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.118723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T07:32:55.395834Z","caller":"traceutil/trace.go:171","msg":"trace[1472033167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:638; }","duration":"608.289175ms","start":"2026-04-28T07:32:54.787527Z","end":"2026-04-28T07:32:55.395817Z","steps":["trace[1472033167] 'range keys from in-memory index tree' (duration: 607.916038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:55.395873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:54.787506Z","time spent":"608.357527ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T07:32:55.942228Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604220347720058,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:32:56.442755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604220347720058,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:32:56.642799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.74615ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:56.643185Z","caller":"traceutil/trace.go:171","msg":"trace[387664783] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:639; }","duration":"374.143868ms","start":"2026-04-28T07:32:56.269013Z","end":"2026-04-28T07:32:56.643157Z","steps":["trace[387664783] 'range keys from in-memory index tree' (duration: 373.72639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:56.652318Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.213843277s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T07:32:56.652659Z","caller":"traceutil/trace.go:171","msg":"trace[2141167327] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"1.211348749s","start":"2026-04-28T07:32:55.441289Z","end":"2026-04-28T07:32:56.652638Z","steps":["trace[2141167327] 'read index received' (duration: 1.211131945s)","trace[2141167327] 'applied index is now lower than readState.Index' (duration: 216.224µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:32:56.652726Z","caller":"traceutil/trace.go:171","msg":"trace[1826719720] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.252098396s","start":"2026-04-28T07:32:55.400617Z","end":"2026-04-28T07:32:56.652715Z","steps":["trace[1826719720] 'process raft request' (duration: 1.251853071s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:56.652814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:55.400609Z","time spent":"1.252140226s","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:32:56.652994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.786612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T07:32:56.652981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211665985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:56.653108Z","caller":"traceutil/trace.go:171","msg":"trace[482650879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"315.874714ms","start":"2026-04-28T07:32:56.337192Z","end":"2026-04-28T07:32:56.653067Z","steps":["trace[482650879] 'agreement among raft nodes before linearized reading' (duration: 315.70826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:56.653156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:56.337136Z","time spent":"316.010307ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T07:32:56.653109Z","caller":"traceutil/trace.go:171","msg":"trace[842358113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"1.211840849s","start":"2026-04-28T07:32:55.441244Z","end":"2026-04-28T07:32:56.653085Z","steps":["trace[842358113] 'agreement among raft nodes before linearized reading' (duration: 1.211644515s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:56.653229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:55.441226Z","time spent":"1.211993862s","remote":"127.0.0.1:39816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:32:56.653494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205720331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T07:32:56.653538Z","caller":"traceutil/trace.go:171","msg":"trace[374645531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"1.205805143s","start":"2026-04-28T07:32:55.447721Z","end":"2026-04-28T07:32:56.653526Z","steps":["trace[374645531] 'agreement among raft nodes before linearized reading' (duration: 1.205106219s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:56.653571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:55.4477Z","time spent":"1.205862883s","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T07:32:56.784372Z","caller":"traceutil/trace.go:171","msg":"trace[182739520] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"124.136894ms","start":"2026-04-28T07:32:56.660213Z","end":"2026-04-28T07:32:56.784349Z","steps":["trace[182739520] 'read index received' (duration: 48.839692ms)","trace[182739520] 'applied index is now lower than readState.Index' (duration: 75.296082ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:32:56.784514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.277657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:56.784547Z","caller":"traceutil/trace.go:171","msg":"trace[521674207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"124.325838ms","start":"2026-04-28T07:32:56.660209Z","end":"2026-04-28T07:32:56.784535Z","steps":["trace[521674207] 'agreement among raft nodes before linearized reading' (duration: 124.255806ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:56.784613Z","caller":"traceutil/trace.go:171","msg":"trace[239663270] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"127.721055ms","start":"2026-04-28T07:32:56.656877Z","end":"2026-04-28T07:32:56.784598Z","steps":["trace[239663270] 'process raft request' (duration: 127.390248ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:56.784373Z","caller":"traceutil/trace.go:171","msg":"trace[457716722] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"127.540511ms","start":"2026-04-28T07:32:56.656797Z","end":"2026-04-28T07:32:56.784338Z","steps":["trace[457716722] 'process raft request' (duration: 87.771944ms)","trace[457716722] 'compare' (duration: 39.548973ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:32:58.241464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604220347720069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:32:58.58445Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.137886792s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T07:32:58.584967Z","caller":"traceutil/trace.go:171","msg":"trace[2100851067] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"844.450931ms","start":"2026-04-28T07:32:57.740491Z","end":"2026-04-28T07:32:58.584942Z","steps":["trace[2100851067] 'read index received' (duration: 844.216527ms)","trace[2100851067] 'applied index is now lower than readState.Index' (duration: 233.104µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:32:58.585078Z","caller":"traceutil/trace.go:171","msg":"trace[1739281408] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.138634366s","start":"2026-04-28T07:32:57.446414Z","end":"2026-04-28T07:32:58.585048Z","steps":["trace[1739281408] 'process raft request' (duration: 1.138275029s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:58.585145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.992977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:58.585181Z","caller":"traceutil/trace.go:171","msg":"trace[1544952266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"144.07922ms","start":"2026-04-28T07:32:58.44109Z","end":"2026-04-28T07:32:58.585169Z","steps":["trace[1544952266] 'agreement among raft nodes before linearized reading' (duration: 144.003908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:58.585345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.780398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-28T07:32:58.585301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:57.446391Z","time spent":"1.13877697s","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:32:58.585418Z","caller":"traceutil/trace.go:171","msg":"trace[983015721] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:645; }","duration":"844.938991ms","start":"2026-04-28T07:32:57.74045Z","end":"2026-04-28T07:32:58.585389Z","steps":["trace[983015721] 'agreement among raft nodes before linearized reading' (duration: 844.619524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:58.585484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:57.74043Z","time spent":"845.043553ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"warn","ts":"2026-04-28T07:32:58.894901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.676194ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-28T07:32:58.895017Z","caller":"traceutil/trace.go:171","msg":"trace[1469992171] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"238.036332ms","start":"2026-04-28T07:32:58.656961Z","end":"2026-04-28T07:32:58.894997Z","steps":["trace[1469992171] 'read index received' (duration: 83.114863ms)","trace[1469992171] 'applied index is now lower than readState.Index' (duration: 154.919809ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:32:58.895119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:58.587364Z","time spent":"307.752007ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-28T07:32:58.895394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.430289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T07:32:58.895427Z","caller":"traceutil/trace.go:171","msg":"trace[892408531] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:645; }","duration":"238.50488ms","start":"2026-04-28T07:32:58.656912Z","end":"2026-04-28T07:32:58.895417Z","steps":["trace[892408531] 'agreement among raft nodes before linearized reading' (duration: 238.374668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:58.895652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.451429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T07:32:58.895687Z","caller":"traceutil/trace.go:171","msg":"trace[860707069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"182.52035ms","start":"2026-04-28T07:32:58.713159Z","end":"2026-04-28T07:32:58.895679Z","steps":["trace[860707069] 'agreement among raft nodes before linearized reading' (duration: 182.435209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:58.89578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.350831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T07:32:58.895805Z","caller":"traceutil/trace.go:171","msg":"trace[822327065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"106.376102ms","start":"2026-04-28T07:32:58.789423Z","end":"2026-04-28T07:32:58.895799Z","steps":["trace[822327065] 'agreement among raft nodes before linearized reading' (duration: 106.31262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:58.895903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.181817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T07:32:58.895996Z","caller":"traceutil/trace.go:171","msg":"trace[390821974] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"106.321061ms","start":"2026-04-28T07:32:58.789654Z","end":"2026-04-28T07:32:58.895975Z","steps":["trace[390821974] 'agreement among raft nodes before linearized reading' (duration: 106.075455ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:01.186842Z","caller":"traceutil/trace.go:171","msg":"trace[301249274] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"238.666885ms","start":"2026-04-28T07:33:00.948142Z","end":"2026-04-28T07:33:01.186809Z","steps":["trace[301249274] 'process raft request' (duration: 185.865416ms)","trace[301249274] 'compare' (duration: 52.635026ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:01.187027Z","caller":"traceutil/trace.go:171","msg":"trace[36485027] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"237.37241ms","start":"2026-04-28T07:33:00.949631Z","end":"2026-04-28T07:33:01.187003Z","steps":["trace[36485027] 'process raft request' (duration: 237.283149ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:01.187054Z","caller":"traceutil/trace.go:171","msg":"trace[1705327850] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"238.354699ms","start":"2026-04-28T07:33:00.948665Z","end":"2026-04-28T07:33:01.187019Z","steps":["trace[1705327850] 'process raft request' (duration: 238.106324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:01.965768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.245036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4glth\" ","response":"range_response_count:1 size:17520"} {"level":"info","ts":"2026-04-28T07:33:01.96586Z","caller":"traceutil/trace.go:171","msg":"trace[141588380] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4glth; range_end:; response_count:1; response_revision:664; }","duration":"252.348288ms","start":"2026-04-28T07:33:01.71349Z","end":"2026-04-28T07:33:01.965839Z","steps":["trace[141588380] 'range keys from in-memory index tree' (duration: 252.029372ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:02.108654Z","caller":"traceutil/trace.go:171","msg":"trace[1087626094] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"127.630132ms","start":"2026-04-28T07:33:01.981003Z","end":"2026-04-28T07:33:02.108633Z","steps":["trace[1087626094] 'process raft request' (duration: 127.49564ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:11.272408Z","caller":"traceutil/trace.go:171","msg":"trace[997510936] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"135.539195ms","start":"2026-04-28T07:33:11.136846Z","end":"2026-04-28T07:33:11.272385Z","steps":["trace[997510936] 'process raft request' (duration: 135.332031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:17.296Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604220347720305,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:33:17.796728Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604220347720305,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:33:17.848051Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.08443701s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T07:33:17.848444Z","caller":"traceutil/trace.go:171","msg":"trace[138436542] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"1.084959511s","start":"2026-04-28T07:33:16.763463Z","end":"2026-04-28T07:33:17.848423Z","steps":["trace[138436542] 'process raft request' (duration: 1.084832449s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:17.84857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:16.763435Z","time spent":"1.085057603s","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:17.855485Z","caller":"traceutil/trace.go:171","msg":"trace[1352863626] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:788; }","duration":"1.060387977s","start":"2026-04-28T07:33:16.795071Z","end":"2026-04-28T07:33:17.855459Z","steps":["trace[1352863626] 'read index received' (duration: 1.053313011s)","trace[1352863626] 'applied index is now lower than readState.Index' (duration: 7.073806ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:33:17.855906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060818926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q2ns8\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-28T07:33:17.855965Z","caller":"traceutil/trace.go:171","msg":"trace[1897835144] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q2ns8; range_end:; response_count:1; response_revision:766; }","duration":"1.060930137s","start":"2026-04-28T07:33:16.79502Z","end":"2026-04-28T07:33:17.85595Z","steps":["trace[1897835144] 'agreement among raft nodes before linearized reading' (duration: 1.060746674s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:17.856009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:16.794997Z","time spent":"1.061003809s","remote":"127.0.0.1:39986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q2ns8\" "} {"level":"info","ts":"2026-04-28T07:33:17.855787Z","caller":"traceutil/trace.go:171","msg":"trace[1470657368] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"629.787531ms","start":"2026-04-28T07:33:17.225968Z","end":"2026-04-28T07:33:17.855756Z","steps":["trace[1470657368] 'process raft request' (duration: 629.379283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:17.856255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:17.225943Z","time spent":"630.230708ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:33:17.856433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.2351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T07:33:17.856478Z","caller":"traceutil/trace.go:171","msg":"trace[1361079706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:766; }","duration":"726.316392ms","start":"2026-04-28T07:33:17.130151Z","end":"2026-04-28T07:33:17.856468Z","steps":["trace[1361079706] 'agreement among raft nodes before linearized reading' (duration: 726.2068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:17.856509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:17.130121Z","time spent":"726.381153ms","remote":"127.0.0.1:40070","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-04-28T07:33:17.856696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.837577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-28T07:33:17.85673Z","caller":"traceutil/trace.go:171","msg":"trace[1515115211] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:766; }","duration":"113.871008ms","start":"2026-04-28T07:33:17.74285Z","end":"2026-04-28T07:33:17.856721Z","steps":["trace[1515115211] 'agreement among raft nodes before linearized reading' (duration: 113.783986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:17.856877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.1992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:33:17.85691Z","caller":"traceutil/trace.go:171","msg":"trace[821649295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:766; }","duration":"416.272912ms","start":"2026-04-28T07:33:17.440628Z","end":"2026-04-28T07:33:17.856901Z","steps":["trace[821649295] 'agreement among raft nodes before linearized reading' (duration: 416.225791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:17.856937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:17.440607Z","time spent":"416.323813ms","remote":"127.0.0.1:39812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:33:17.857126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.67356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T07:33:17.857162Z","caller":"traceutil/trace.go:171","msg":"trace[1590502128] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:766; }","duration":"458.752411ms","start":"2026-04-28T07:33:17.398401Z","end":"2026-04-28T07:33:17.857153Z","steps":["trace[1590502128] 'agreement among raft nodes before linearized reading' (duration: 458.667809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:17.857192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:17.398382Z","time spent":"458.801672ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-28T07:33:17.857289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.080908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T07:33:17.857436Z","caller":"traceutil/trace.go:171","msg":"trace[783327855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:766; }","duration":"543.271711ms","start":"2026-04-28T07:33:17.31415Z","end":"2026-04-28T07:33:17.857422Z","steps":["trace[783327855] 'agreement among raft nodes before linearized reading' (duration: 542.920874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:17.859568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:17.31413Z","time spent":"545.425423ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T07:33:17.857803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.789753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T07:33:17.859699Z","caller":"traceutil/trace.go:171","msg":"trace[916035202] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"543.70159ms","start":"2026-04-28T07:33:17.315987Z","end":"2026-04-28T07:33:17.859689Z","steps":["trace[916035202] 'agreement among raft nodes before linearized reading' (duration: 541.746771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:17.859723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:17.31598Z","time spent":"543.73632ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T07:33:17.857929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.061647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T07:33:17.859823Z","caller":"traceutil/trace.go:171","msg":"trace[1482661940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"543.995025ms","start":"2026-04-28T07:33:17.315821Z","end":"2026-04-28T07:33:17.859816Z","steps":["trace[1482661940] 'agreement among raft nodes before linearized reading' (duration: 542.024237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:17.859844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:17.315794Z","time spent":"544.043736ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T07:33:32.518119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.424666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:33:32.518305Z","caller":"traceutil/trace.go:171","msg":"trace[1348942685] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"504.266088ms","start":"2026-04-28T07:33:32.014018Z","end":"2026-04-28T07:33:32.518284Z","steps":["trace[1348942685] 'process raft request' (duration: 346.581457ms)","trace[1348942685] 'compare' (duration: 157.259543ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:33:32.518361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:32.013992Z","time spent":"504.34231ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:32.520831Z","caller":"traceutil/trace.go:171","msg":"trace[1899548612] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:925; }","duration":"274.400794ms","start":"2026-04-28T07:33:32.246417Z","end":"2026-04-28T07:33:32.520817Z","steps":["trace[1899548612] 'read index received' (duration: 114.306766ms)","trace[1899548612] 'applied index is now lower than readState.Index' (duration: 160.093008ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:32.520839Z","caller":"traceutil/trace.go:171","msg":"trace[415984495] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"506.009021ms","start":"2026-04-28T07:33:32.014814Z","end":"2026-04-28T07:33:32.520823Z","steps":["trace[415984495] 'process raft request' (duration: 505.877499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:32.520909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:32.014796Z","time spent":"506.075743ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:33:32.520959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.537737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-hgk8c\" ","response":"range_response_count:1 size:5673"} {"level":"info","ts":"2026-04-28T07:33:32.520986Z","caller":"traceutil/trace.go:171","msg":"trace[1754526236] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-hgk8c; range_end:; response_count:1; response_revision:902; }","duration":"274.601767ms","start":"2026-04-28T07:33:32.246375Z","end":"2026-04-28T07:33:32.520976Z","steps":["trace[1754526236] 'agreement among raft nodes before linearized reading' (duration: 274.487155ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:32.520946Z","caller":"traceutil/trace.go:171","msg":"trace[1515151410] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"504.299459ms","start":"2026-04-28T07:33:32.016611Z","end":"2026-04-28T07:33:32.52091Z","steps":["trace[1515151410] 'process raft request' (duration: 504.164127ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:32.521028Z","caller":"traceutil/trace.go:171","msg":"trace[1141416530] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"504.388411ms","start":"2026-04-28T07:33:32.016618Z","end":"2026-04-28T07:33:32.521007Z","steps":["trace[1141416530] 'process raft request' (duration: 504.176557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:32.521102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:32.016594Z","time spent":"504.433041ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:33:32.521128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:32.016603Z","time spent":"504.475072ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:32.521276Z","caller":"traceutil/trace.go:171","msg":"trace[1960099091] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"505.779808ms","start":"2026-04-28T07:33:32.015487Z","end":"2026-04-28T07:33:32.521266Z","steps":["trace[1960099091] 'process raft request' (duration: 505.263768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:32.521384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:32.015465Z","time spent":"505.841578ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:32.862443Z","caller":"traceutil/trace.go:171","msg":"trace[1947971423] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"285.933126ms","start":"2026-04-28T07:33:32.576465Z","end":"2026-04-28T07:33:32.862398Z","steps":["trace[1947971423] 'process raft request' (duration: 285.727902ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:33.071009Z","caller":"traceutil/trace.go:171","msg":"trace[862270528] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"494.275976ms","start":"2026-04-28T07:33:32.576703Z","end":"2026-04-28T07:33:33.070979Z","steps":["trace[862270528] 'process raft request' (duration: 494.012711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:33.071168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:32.576682Z","time spent":"494.411568ms","remote":"127.0.0.1:40082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:33.071195Z","caller":"traceutil/trace.go:171","msg":"trace[1636313968] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:932; }","duration":"152.726376ms","start":"2026-04-28T07:33:32.918441Z","end":"2026-04-28T07:33:33.071167Z","steps":["trace[1636313968] 'read index received' (duration: 152.252757ms)","trace[1636313968] 'applied index is now lower than readState.Index' (duration: 472.059µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:33.071212Z","caller":"traceutil/trace.go:171","msg":"trace[38652886] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"494.303626ms","start":"2026-04-28T07:33:32.576878Z","end":"2026-04-28T07:33:33.071188Z","steps":["trace[38652886] 'process raft request' (duration: 494.027031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:33.071366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:32.576859Z","time spent":"494.431799ms","remote":"127.0.0.1:40306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5192,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:33:33.071426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.966091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-vnr6d\" ","response":"range_response_count:1 size:2275"} {"level":"info","ts":"2026-04-28T07:33:33.071477Z","caller":"traceutil/trace.go:171","msg":"trace[1591208552] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-vnr6d; range_end:; response_count:1; response_revision:906; }","duration":"153.091783ms","start":"2026-04-28T07:33:32.918372Z","end":"2026-04-28T07:33:33.071463Z","steps":["trace[1591208552] 'agreement among raft nodes before linearized reading' (duration: 152.911589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:43.395533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.039465ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-28T07:33:53.258052Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604220347720743,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:33:53.759122Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604220347720743,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:33:54.259937Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604220347720743,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:33:54.265443Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.580205129s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T07:33:54.265864Z","caller":"traceutil/trace.go:171","msg":"trace[1359342981] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.580697109s","start":"2026-04-28T07:33:52.68514Z","end":"2026-04-28T07:33:54.265837Z","steps":["trace[1359342981] 'process raft request' (duration: 1.580472274s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:54.266037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:52.685119Z","time spent":"1.580827661s","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:54.319997Z","caller":"traceutil/trace.go:171","msg":"trace[1413920922] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"1.102364978s","start":"2026-04-28T07:33:53.217609Z","end":"2026-04-28T07:33:54.319974Z","steps":["trace[1413920922] 'process raft request' (duration: 1.102146314s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:54.32018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:53.21758Z","time spent":"1.102504911s","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:33:54.450718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.812037ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T07:33:54.450801Z","caller":"traceutil/trace.go:171","msg":"trace[1042088494] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1010; }","duration":"1.692859311s","start":"2026-04-28T07:33:52.757928Z","end":"2026-04-28T07:33:54.450787Z","steps":["trace[1042088494] 'read index received' (duration: 1.507718361s)","trace[1042088494] 'applied index is now lower than readState.Index' (duration: 185.139869ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:33:54.450983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.693054334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T07:33:54.451013Z","caller":"traceutil/trace.go:171","msg":"trace[444781229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"1.693121095s","start":"2026-04-28T07:33:52.757882Z","end":"2026-04-28T07:33:54.451003Z","steps":["trace[444781229] 'agreement among raft nodes before linearized reading' (duration: 1.692982683s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:54.451045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:52.757865Z","time spent":"1.693168466s","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T07:33:54.451024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.879722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T07:33:54.451081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.654980441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-28T07:33:54.45106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:33:54.451119Z","caller":"traceutil/trace.go:171","msg":"trace[1673174110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:979; }","duration":"1.655045641s","start":"2026-04-28T07:33:52.796063Z","end":"2026-04-28T07:33:54.451109Z","steps":["trace[1673174110] 'agreement among raft nodes before linearized reading' (duration: 1.654884058s)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:54.451119Z","caller":"traceutil/trace.go:171","msg":"trace[102224682] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:979; }","duration":"698.038775ms","start":"2026-04-28T07:33:53.753061Z","end":"2026-04-28T07:33:54.4511Z","steps":["trace[102224682] 'agreement among raft nodes before linearized reading' (duration: 697.889362ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:54.451137Z","caller":"traceutil/trace.go:171","msg":"trace[1730520888] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:979; }","duration":"934.692743ms","start":"2026-04-28T07:33:53.516427Z","end":"2026-04-28T07:33:54.45112Z","steps":["trace[1730520888] 'agreement among raft nodes before linearized reading' (duration: 934.56009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:54.451149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:52.796049Z","time spent":"1.655094012s","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T07:33:54.45117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:53.753039Z","time spent":"698.118687ms","remote":"127.0.0.1:40318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-28T07:33:54.451174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:53.516413Z","time spent":"934.751294ms","remote":"127.0.0.1:39930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-28T07:33:54.451186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.589835852s","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-04-28T07:33:54.451027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00928391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:33:54.451225Z","caller":"traceutil/trace.go:171","msg":"trace[1874664803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"1.589947125s","start":"2026-04-28T07:33:52.861261Z","end":"2026-04-28T07:33:54.451208Z","steps":["trace[1874664803] 'agreement among raft nodes before linearized reading' (duration: 1.589836343s)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:54.451241Z","caller":"traceutil/trace.go:171","msg":"trace[1378982693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"1.009563645s","start":"2026-04-28T07:33:53.44167Z","end":"2026-04-28T07:33:54.451234Z","steps":["trace[1378982693] 'agreement among raft nodes before linearized reading' (duration: 1.009276189s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:54.451255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:52.861238Z","time spent":"1.590012616s","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T07:33:54.451264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:53.441648Z","time spent":"1.009610316s","remote":"127.0.0.1:39812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:33:54.451362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593267388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T07:33:54.451386Z","caller":"traceutil/trace.go:171","msg":"trace[1162733997] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:979; }","duration":"1.593324269s","start":"2026-04-28T07:33:52.858055Z","end":"2026-04-28T07:33:54.451379Z","steps":["trace[1162733997] 'agreement among raft nodes before linearized reading' (duration: 1.593246858s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:54.451405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:52.85803Z","time spent":"1.59337001s","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T07:34:00.586135Z","caller":"traceutil/trace.go:171","msg":"trace[1453341504] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"101.922587ms","start":"2026-04-28T07:34:00.484187Z","end":"2026-04-28T07:34:00.586109Z","steps":["trace[1453341504] 'process raft request' (duration: 101.721853ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:34:04.720966Z","caller":"traceutil/trace.go:171","msg":"trace[1169123217] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"106.416583ms","start":"2026-04-28T07:34:04.614516Z","end":"2026-04-28T07:34:04.720932Z","steps":["trace[1169123217] 'process raft request' (duration: 65.990424ms)","trace[1169123217] 'compare' (duration: 40.208855ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:34:04.721425Z","caller":"traceutil/trace.go:171","msg":"trace[903043620] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"106.782679ms","start":"2026-04-28T07:34:04.614584Z","end":"2026-04-28T07:34:04.721366Z","steps":["trace[903043620] 'process raft request' (duration: 106.543004ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:34:04.721285Z","caller":"traceutil/trace.go:171","msg":"trace[911907015] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"106.685988ms","start":"2026-04-28T07:34:04.614572Z","end":"2026-04-28T07:34:04.721258Z","steps":["trace[911907015] 'process raft request' (duration: 106.306901ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:34:12.767541Z","caller":"traceutil/trace.go:171","msg":"trace[1925626880] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"107.943591ms","start":"2026-04-28T07:34:12.659578Z","end":"2026-04-28T07:34:12.767521Z","steps":["trace[1925626880] 'process raft request' (duration: 107.833239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:18.182018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.728299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:34:18.182312Z","caller":"traceutil/trace.go:171","msg":"trace[1862135796] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"417.256019ms","start":"2026-04-28T07:34:17.765001Z","end":"2026-04-28T07:34:18.182257Z","steps":["trace[1862135796] 'process raft request' (duration: 280.782927ms)","trace[1862135796] 'compare' (duration: 135.32525ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:34:18.182414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:17.764972Z","time spent":"417.392401ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:34:19.215049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.750087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T07:34:19.215187Z","caller":"traceutil/trace.go:171","msg":"trace[820539176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1059; }","duration":"329.960111ms","start":"2026-04-28T07:34:18.88521Z","end":"2026-04-28T07:34:19.21517Z","steps":["trace[820539176] 'range keys from in-memory index tree' (duration: 329.521603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:19.215233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:18.885188Z","time spent":"330.032902ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T07:34:19.215229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.972389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T07:34:19.215323Z","caller":"traceutil/trace.go:171","msg":"trace[571324642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1059; }","duration":"332.117082ms","start":"2026-04-28T07:34:18.883184Z","end":"2026-04-28T07:34:19.215301Z","steps":["trace[571324642] 'range keys from in-memory index tree' (duration: 331.701933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:19.215373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:18.883165Z","time spent":"332.198533ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T07:34:19.215626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.02424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T07:34:19.215792Z","caller":"traceutil/trace.go:171","msg":"trace[906178885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1059; }","duration":"331.257544ms","start":"2026-04-28T07:34:18.884512Z","end":"2026-04-28T07:34:19.215769Z","steps":["trace[906178885] 'range keys from in-memory index tree' (duration: 330.413469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:19.215927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:18.884476Z","time spent":"331.435878ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T07:34:19.473974Z","caller":"traceutil/trace.go:171","msg":"trace[1645137527] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"254.226432ms","start":"2026-04-28T07:34:19.219724Z","end":"2026-04-28T07:34:19.473951Z","steps":["trace[1645137527] 'process raft request' (duration: 254.079729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:19.7624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.958989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:34:19.762618Z","caller":"traceutil/trace.go:171","msg":"trace[636117936] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1098; }","duration":"321.403078ms","start":"2026-04-28T07:34:19.441198Z","end":"2026-04-28T07:34:19.762601Z","steps":["trace[636117936] 'read index received' (duration: 32.56972ms)","trace[636117936] 'applied index is now lower than readState.Index' (duration: 288.832478ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:34:19.762763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.580672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:34:19.762792Z","caller":"traceutil/trace.go:171","msg":"trace[326081911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"321.649352ms","start":"2026-04-28T07:34:19.441133Z","end":"2026-04-28T07:34:19.762783Z","steps":["trace[326081911] 'agreement among raft nodes before linearized reading' (duration: 321.54309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:19.762824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:19.441084Z","time spent":"321.732244ms","remote":"127.0.0.1:39812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T07:34:19.762928Z","caller":"traceutil/trace.go:171","msg":"trace[1813742146] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"542.264685ms","start":"2026-04-28T07:34:19.220649Z","end":"2026-04-28T07:34:19.762914Z","steps":["trace[1813742146] 'process raft request' (duration: 541.863627ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:34:19.763001Z","caller":"traceutil/trace.go:171","msg":"trace[1269627815] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"425.512986ms","start":"2026-04-28T07:34:19.337479Z","end":"2026-04-28T07:34:19.762992Z","steps":["trace[1269627815] 'process raft request' (duration: 425.083228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:19.76305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:19.337447Z","time spent":"425.578968ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:34:19.763069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:19.220624Z","time spent":"542.359136ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:34:19.762836Z","caller":"traceutil/trace.go:171","msg":"trace[1078803137] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"542.574221ms","start":"2026-04-28T07:34:19.220224Z","end":"2026-04-28T07:34:19.762799Z","steps":["trace[1078803137] 'process raft request' (duration: 391.141333ms)","trace[1078803137] 'compare' (duration: 150.839597ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:34:19.763296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:19.220208Z","time spent":"543.05548ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:34:21.161564Z","caller":"traceutil/trace.go:171","msg":"trace[63598749] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"281.318956ms","start":"2026-04-28T07:34:20.880222Z","end":"2026-04-28T07:34:21.161541Z","steps":["trace[63598749] 'process raft request' (duration: 281.164963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:21.769844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.444055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:34:21.76992Z","caller":"traceutil/trace.go:171","msg":"trace[882874220] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1066; }","duration":"365.591759ms","start":"2026-04-28T07:34:21.404312Z","end":"2026-04-28T07:34:21.769904Z","steps":["trace[882874220] 'count revisions from in-memory index tree' (duration: 365.284691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:21.769956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:21.404275Z","time spent":"365.671331ms","remote":"127.0.0.1:49482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-28T07:34:21.769938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.672534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T07:34:21.770003Z","caller":"traceutil/trace.go:171","msg":"trace[1349264709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1066; }","duration":"291.794796ms","start":"2026-04-28T07:34:21.478197Z","end":"2026-04-28T07:34:21.769991Z","steps":["trace[1349264709] 'range keys from in-memory index tree' (duration: 291.50246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:21.770258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.29629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:34:21.770287Z","caller":"traceutil/trace.go:171","msg":"trace[1067734223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"328.352561ms","start":"2026-04-28T07:34:21.441928Z","end":"2026-04-28T07:34:21.77028Z","steps":["trace[1067734223] 'range keys from in-memory index tree' (duration: 328.230048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:21.770317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:21.441907Z","time spent":"328.395262ms","remote":"127.0.0.1:39812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T07:34:22.77247Z","caller":"traceutil/trace.go:171","msg":"trace[641485723] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"375.031607ms","start":"2026-04-28T07:34:22.397414Z","end":"2026-04-28T07:34:22.772446Z","steps":["trace[641485723] 'process raft request' (duration: 374.878854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:22.772629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:22.397388Z","time spent":"375.162169ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:34:22.832421Z","caller":"traceutil/trace.go:171","msg":"trace[135147248] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"333.013628ms","start":"2026-04-28T07:34:22.499382Z","end":"2026-04-28T07:34:22.832396Z","steps":["trace[135147248] 'process raft request' (duration: 332.851945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:22.832666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:22.499352Z","time spent":"333.232342ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:34:33.382862Z","caller":"traceutil/trace.go:171","msg":"trace[113248611] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"121.346016ms","start":"2026-04-28T07:34:33.261481Z","end":"2026-04-28T07:34:33.382827Z","steps":["trace[113248611] 'process raft request' (duration: 121.293905ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:34:33.382921Z","caller":"traceutil/trace.go:171","msg":"trace[841700133] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"122.480688ms","start":"2026-04-28T07:34:33.260395Z","end":"2026-04-28T07:34:33.382876Z","steps":["trace[841700133] 'process raft request' (duration: 122.195033ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:34:41.079482Z","caller":"traceutil/trace.go:171","msg":"trace[846656111] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"124.763281ms","start":"2026-04-28T07:34:40.954702Z","end":"2026-04-28T07:34:41.079465Z","steps":["trace[846656111] 'read index received' (duration: 124.649409ms)","trace[846656111] 'applied index is now lower than readState.Index' (duration: 113.332µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:34:41.079604Z","caller":"traceutil/trace.go:171","msg":"trace[2107059793] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"162.408956ms","start":"2026-04-28T07:34:40.917168Z","end":"2026-04-28T07:34:41.079577Z","steps":["trace[2107059793] 'process raft request' (duration: 162.193772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:41.079632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.925454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T07:34:41.079653Z","caller":"traceutil/trace.go:171","msg":"trace[2129969717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1192; }","duration":"125.003405ms","start":"2026-04-28T07:34:40.954645Z","end":"2026-04-28T07:34:41.079648Z","steps":["trace[2129969717] 'agreement among raft nodes before linearized reading' (duration: 124.905664ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:34:54.297689Z","caller":"traceutil/trace.go:171","msg":"trace[1361107602] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1283; }","duration":"182.695402ms","start":"2026-04-28T07:34:54.114967Z","end":"2026-04-28T07:34:54.297662Z","steps":["trace[1361107602] 'read index received' (duration: 182.518189ms)","trace[1361107602] 'applied index is now lower than readState.Index' (duration: 176.203µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:34:54.297813Z","caller":"traceutil/trace.go:171","msg":"trace[717475227] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"240.787432ms","start":"2026-04-28T07:34:54.057015Z","end":"2026-04-28T07:34:54.297803Z","steps":["trace[717475227] 'process raft request' (duration: 240.466986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:54.298229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.257273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T07:34:54.298271Z","caller":"traceutil/trace.go:171","msg":"trace[1385910344] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1236; }","duration":"183.362715ms","start":"2026-04-28T07:34:54.114899Z","end":"2026-04-28T07:34:54.298262Z","steps":["trace[1385910344] 'agreement among raft nodes before linearized reading' (duration: 183.226792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:54.298831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.870305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T07:34:54.298909Z","caller":"traceutil/trace.go:171","msg":"trace[986492024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1236; }","duration":"183.002817ms","start":"2026-04-28T07:34:54.115886Z","end":"2026-04-28T07:34:54.298889Z","steps":["trace[986492024] 'agreement among raft nodes before linearized reading' (duration: 182.05169ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:34:55.363178Z","caller":"traceutil/trace.go:171","msg":"trace[1385306042] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"165.2262ms","start":"2026-04-28T07:34:55.197894Z","end":"2026-04-28T07:34:55.36312Z","steps":["trace[1385306042] 'read index received' (duration: 101.88245ms)","trace[1385306042] 'applied index is now lower than readState.Index' (duration: 63.34211ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:34:55.363298Z","caller":"traceutil/trace.go:171","msg":"trace[1518524517] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"182.543148ms","start":"2026-04-28T07:34:55.180682Z","end":"2026-04-28T07:34:55.363225Z","steps":["trace[1518524517] 'process raft request' (duration: 119.117867ms)","trace[1518524517] 'compare' (duration: 63.186537ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:34:55.36364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.727251ms","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-04-28T07:34:55.36372Z","caller":"traceutil/trace.go:171","msg":"trace[392162730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1249; }","duration":"165.874384ms","start":"2026-04-28T07:34:55.197828Z","end":"2026-04-28T07:34:55.363703Z","steps":["trace[392162730] 'agreement among raft nodes before linearized reading' (duration: 165.425835ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:34:57.584649Z","caller":"traceutil/trace.go:171","msg":"trace[931424062] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"188.648485ms","start":"2026-04-28T07:34:57.395965Z","end":"2026-04-28T07:34:57.584614Z","steps":["trace[931424062] 'read index received' (duration: 188.418651ms)","trace[931424062] 'applied index is now lower than readState.Index' (duration: 228.854µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:34:57.58504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.050912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-28T07:34:57.585108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.775645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:34:57.58513Z","caller":"traceutil/trace.go:171","msg":"trace[302149266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1261; }","duration":"189.194555ms","start":"2026-04-28T07:34:57.395914Z","end":"2026-04-28T07:34:57.585109Z","steps":["trace[302149266] 'agreement among raft nodes before linearized reading' (duration: 188.92004ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:34:57.585175Z","caller":"traceutil/trace.go:171","msg":"trace[1172532206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1261; }","duration":"142.892008ms","start":"2026-04-28T07:34:57.442265Z","end":"2026-04-28T07:34:57.585157Z","steps":["trace[1172532206] 'agreement among raft nodes before linearized reading' (duration: 142.777886ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:34:57.585418Z","caller":"traceutil/trace.go:171","msg":"trace[2103912572] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"435.046394ms","start":"2026-04-28T07:34:57.150357Z","end":"2026-04-28T07:34:57.585403Z","steps":["trace[2103912572] 'process raft request' (duration: 434.070136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:57.585527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:57.150314Z","time spent":"435.139726ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:34:58.09814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.698077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:34:58.098255Z","caller":"traceutil/trace.go:171","msg":"trace[668038153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1261; }","duration":"511.86993ms","start":"2026-04-28T07:34:57.586361Z","end":"2026-04-28T07:34:58.098231Z","steps":["trace[668038153] 'range keys from in-memory index tree' (duration: 511.625625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:58.098302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:57.586353Z","time spent":"511.939291ms","remote":"127.0.0.1:39816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:34:58.098381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.054837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:34:58.098518Z","caller":"traceutil/trace.go:171","msg":"trace[1298402831] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1309; }","duration":"348.912762ms","start":"2026-04-28T07:34:57.749584Z","end":"2026-04-28T07:34:58.098497Z","steps":["trace[1298402831] 'read index received' (duration: 135.556029ms)","trace[1298402831] 'applied index is now lower than readState.Index' (duration: 213.355133ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:34:58.098618Z","caller":"traceutil/trace.go:171","msg":"trace[1074206003] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"508.565046ms","start":"2026-04-28T07:34:57.589966Z","end":"2026-04-28T07:34:58.098531Z","steps":["trace[1074206003] 'process raft request' (duration: 295.233104ms)","trace[1074206003] 'compare' (duration: 212.881494ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:34:58.098712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.126445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-28T07:34:58.098758Z","caller":"traceutil/trace.go:171","msg":"trace[808453539] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:1262; }","duration":"349.212607ms","start":"2026-04-28T07:34:57.74953Z","end":"2026-04-28T07:34:58.098743Z","steps":["trace[808453539] 'agreement among raft nodes before linearized reading' (duration: 349.033474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:58.098814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:57.749466Z","time spent":"349.334739ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"warn","ts":"2026-04-28T07:34:58.099324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.470861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T07:34:58.099386Z","caller":"traceutil/trace.go:171","msg":"trace[1930259074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1261; }","duration":"513.554562ms","start":"2026-04-28T07:34:57.585816Z","end":"2026-04-28T07:34:58.099371Z","steps":["trace[1930259074] 'range keys from in-memory index tree' (duration: 512.078713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:58.099433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:57.585798Z","time spent":"513.620133ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T07:34:58.098876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:57.589951Z","time spent":"508.788361ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:34:59.533582Z","caller":"traceutil/trace.go:171","msg":"trace[2000155563] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1317; }","duration":"187.411552ms","start":"2026-04-28T07:34:59.346146Z","end":"2026-04-28T07:34:59.533558Z","steps":["trace[2000155563] 'read index received' (duration: 187.195558ms)","trace[2000155563] 'applied index is now lower than readState.Index' (duration: 214.394µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:34:59.533665Z","caller":"traceutil/trace.go:171","msg":"trace[843147757] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"779.317268ms","start":"2026-04-28T07:34:58.754285Z","end":"2026-04-28T07:34:59.533602Z","steps":["trace[843147757] 'process raft request' (duration: 778.992762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:34:59.533867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:34:58.754261Z","time spent":"779.521511ms","remote":"127.0.0.1:39890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":791,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:34:59.533856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.685267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:34:59.533965Z","caller":"traceutil/trace.go:171","msg":"trace[281943485] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1269; }","duration":"187.845349ms","start":"2026-04-28T07:34:59.346101Z","end":"2026-04-28T07:34:59.533946Z","steps":["trace[281943485] 'agreement among raft nodes before linearized reading' (duration: 187.562384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:35:00.679846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.842335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T07:35:00.679929Z","caller":"traceutil/trace.go:171","msg":"trace[82303644] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1278; }","duration":"144.991588ms","start":"2026-04-28T07:35:00.534916Z","end":"2026-04-28T07:35:00.679908Z","steps":["trace[82303644] 'count revisions from in-memory index tree' (duration: 144.736414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:35:08.349862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.719774ms","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-04-28T07:35:08.34996Z","caller":"traceutil/trace.go:171","msg":"trace[1338788088] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1339; }","duration":"171.866177ms","start":"2026-04-28T07:35:08.178073Z","end":"2026-04-28T07:35:08.349939Z","steps":["trace[1338788088] 'range keys from in-memory index tree' (duration: 171.4847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:35:08.351079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.493043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-28T07:35:08.351152Z","caller":"traceutil/trace.go:171","msg":"trace[364442858] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1339; }","duration":"244.615376ms","start":"2026-04-28T07:35:08.106513Z","end":"2026-04-28T07:35:08.351128Z","steps":["trace[364442858] 'range keys from in-memory index tree' (duration: 243.541595ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:35:08.540195Z","caller":"traceutil/trace.go:171","msg":"trace[462980945] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"185.442865ms","start":"2026-04-28T07:35:08.35471Z","end":"2026-04-28T07:35:08.540153Z","steps":["trace[462980945] 'process raft request' (duration: 185.264251ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:35:08.741585Z","caller":"traceutil/trace.go:171","msg":"trace[1601397409] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1390; }","duration":"299.24633ms","start":"2026-04-28T07:35:08.442314Z","end":"2026-04-28T07:35:08.741561Z","steps":["trace[1601397409] 'read index received' (duration: 97.702391ms)","trace[1601397409] 'applied index is now lower than readState.Index' (duration: 201.542439ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:35:08.741764Z","caller":"traceutil/trace.go:171","msg":"trace[1881208486] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"382.254903ms","start":"2026-04-28T07:35:08.359485Z","end":"2026-04-28T07:35:08.74174Z","steps":["trace[1881208486] 'process raft request' (duration: 382.028918ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:35:08.741833Z","caller":"traceutil/trace.go:171","msg":"trace[382671311] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"386.77713ms","start":"2026-04-28T07:35:08.355025Z","end":"2026-04-28T07:35:08.741802Z","steps":["trace[382671311] 'process raft request' (duration: 386.448273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:35:08.741879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:35:08.359473Z","time spent":"382.344785ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:35:08.741989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:35:08.355007Z","time spent":"386.886332ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:35:08.74173Z","caller":"traceutil/trace.go:171","msg":"trace[148882581] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"386.970873ms","start":"2026-04-28T07:35:08.354747Z","end":"2026-04-28T07:35:08.741718Z","steps":["trace[148882581] 'process raft request' (duration: 296.657231ms)","trace[148882581] 'compare' (duration: 89.900074ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:35:08.742101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.073325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T07:35:08.742127Z","caller":"traceutil/trace.go:171","msg":"trace[299938739] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1343; }","duration":"225.134146ms","start":"2026-04-28T07:35:08.516986Z","end":"2026-04-28T07:35:08.74212Z","steps":["trace[299938739] 'agreement among raft nodes before linearized reading' (duration: 225.066905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:35:08.742107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.417455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T07:35:08.742176Z","caller":"traceutil/trace.go:171","msg":"trace[166673942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1343; }","duration":"260.515276ms","start":"2026-04-28T07:35:08.481639Z","end":"2026-04-28T07:35:08.742154Z","steps":["trace[166673942] 'agreement among raft nodes before linearized reading' (duration: 260.346563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:35:08.74236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.331715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T07:35:08.742401Z","caller":"traceutil/trace.go:171","msg":"trace[1026842316] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1343; }","duration":"239.408496ms","start":"2026-04-28T07:35:08.502981Z","end":"2026-04-28T07:35:08.742389Z","steps":["trace[1026842316] 'agreement among raft nodes before linearized reading' (duration: 239.260964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:35:08.742192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:35:08.354724Z","time spent":"387.383531ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:35:08.742216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.911553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:35:08.742775Z","caller":"traceutil/trace.go:171","msg":"trace[874825147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1343; }","duration":"300.520684ms","start":"2026-04-28T07:35:08.442242Z","end":"2026-04-28T07:35:08.742763Z","steps":["trace[874825147] 'agreement among raft nodes before linearized reading' (duration: 299.938164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:35:08.742817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:35:08.442202Z","time spent":"300.602825ms","remote":"127.0.0.1:39812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T07:35:12.848855Z","caller":"traceutil/trace.go:171","msg":"trace[1831873150] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"134.58596ms","start":"2026-04-28T07:35:12.714246Z","end":"2026-04-28T07:35:12.848832Z","steps":["trace[1831873150] 'process raft request' (duration: 134.208253ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:35:53.727219Z","caller":"traceutil/trace.go:171","msg":"trace[1654584842] transaction","detail":"{read_only:false; response_revision:1572; number_of_response:1; }","duration":"100.562386ms","start":"2026-04-28T07:35:53.626625Z","end":"2026-04-28T07:35:53.727187Z","steps":["trace[1654584842] 'process raft request' (duration: 47.848317ms)","trace[1654584842] 'compare' (duration: 52.587587ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:35:54.09791Z","caller":"traceutil/trace.go:171","msg":"trace[598998031] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"170.353819ms","start":"2026-04-28T07:35:53.927532Z","end":"2026-04-28T07:35:54.097886Z","steps":["trace[598998031] 'process raft request' (duration: 170.212616ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:36:38.463485Z","caller":"traceutil/trace.go:171","msg":"trace[1857455431] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"183.838478ms","start":"2026-04-28T07:36:38.279624Z","end":"2026-04-28T07:36:38.463462Z","steps":["trace[1857455431] 'process raft request' (duration: 178.675856ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:36:38.471196Z","caller":"traceutil/trace.go:171","msg":"trace[2079319695] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"176.398208ms","start":"2026-04-28T07:36:38.294761Z","end":"2026-04-28T07:36:38.471159Z","steps":["trace[2079319695] 'process raft request' (duration: 176.153014ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:37:38.099623Z","caller":"traceutil/trace.go:171","msg":"trace[812698591] linearizableReadLoop","detail":"{readStateIndex:2178; appliedIndex:2177; }","duration":"312.784228ms","start":"2026-04-28T07:37:37.786803Z","end":"2026-04-28T07:37:38.099587Z","steps":["trace[812698591] 'read index received' (duration: 283.560348ms)","trace[812698591] 'applied index is now lower than readState.Index' (duration: 29.22237ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:37:38.099713Z","caller":"traceutil/trace.go:171","msg":"trace[1305107897] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"329.954012ms","start":"2026-04-28T07:37:37.769698Z","end":"2026-04-28T07:37:38.099652Z","steps":["trace[1305107897] 'process raft request' (duration: 300.61508ms)","trace[1305107897] 'compare' (duration: 28.999025ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:37:38.099915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:37:37.769648Z","time spent":"330.174787ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:37:38.099931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.101615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T07:37:38.1Z","caller":"traceutil/trace.go:171","msg":"trace[1928444251] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2097; }","duration":"313.250738ms","start":"2026-04-28T07:37:37.786734Z","end":"2026-04-28T07:37:38.099985Z","steps":["trace[1928444251] 'agreement among raft nodes before linearized reading' (duration: 312.968862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:37:38.100008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.268972ms","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":"warn","ts":"2026-04-28T07:37:38.100051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:37:37.786705Z","time spent":"313.334309ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-28T07:37:38.100054Z","caller":"traceutil/trace.go:171","msg":"trace[455076815] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2097; }","duration":"224.396185ms","start":"2026-04-28T07:37:37.875646Z","end":"2026-04-28T07:37:38.100042Z","steps":["trace[455076815] 'agreement among raft nodes before linearized reading' (duration: 224.177141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:37:38.100901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.078485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050268"} {"level":"info","ts":"2026-04-28T07:37:38.100962Z","caller":"traceutil/trace.go:171","msg":"trace[1196196585] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:2097; }","duration":"113.169796ms","start":"2026-04-28T07:37:37.987764Z","end":"2026-04-28T07:37:38.100934Z","steps":["trace[1196196585] 'agreement among raft nodes before linearized reading' (duration: 112.292329ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:37:42.408182Z","caller":"traceutil/trace.go:171","msg":"trace[1353802996] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"210.950553ms","start":"2026-04-28T07:37:42.197141Z","end":"2026-04-28T07:37:42.408091Z","steps":["trace[1353802996] 'process raft request' (duration: 193.244727ms)","trace[1353802996] 'compare' (duration: 17.40078ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:37:42.408609Z","caller":"traceutil/trace.go:171","msg":"trace[541648449] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"211.526764ms","start":"2026-04-28T07:37:42.197045Z","end":"2026-04-28T07:37:42.408572Z","steps":["trace[541648449] 'process raft request' (duration: 210.991814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:38:11.838244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.48288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:38:11.838407Z","caller":"traceutil/trace.go:171","msg":"trace[877664193] linearizableReadLoop","detail":"{readStateIndex:2355; appliedIndex:2354; }","duration":"154.54817ms","start":"2026-04-28T07:38:11.683834Z","end":"2026-04-28T07:38:11.838383Z","steps":["trace[877664193] 'read index received' (duration: 3.696503ms)","trace[877664193] 'applied index is now lower than readState.Index' (duration: 150.849877ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:38:11.838525Z","caller":"traceutil/trace.go:171","msg":"trace[1467233647] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"208.686159ms","start":"2026-04-28T07:38:11.629826Z","end":"2026-04-28T07:38:11.838512Z","steps":["trace[1467233647] 'process raft request' (duration: 57.811991ms)","trace[1467233647] 'compare' (duration: 150.309436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:38:11.838732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.911388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:38:11.838848Z","caller":"traceutil/trace.go:171","msg":"trace[12654534] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:0; response_revision:2268; }","duration":"155.06458ms","start":"2026-04-28T07:38:11.683757Z","end":"2026-04-28T07:38:11.838821Z","steps":["trace[12654534] 'agreement among raft nodes before linearized reading' (duration: 154.832536ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:38:12.044535Z","caller":"traceutil/trace.go:171","msg":"trace[1922055153] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"147.922301ms","start":"2026-04-28T07:38:11.896565Z","end":"2026-04-28T07:38:12.044488Z","steps":["trace[1922055153] 'process raft request' (duration: 102.36126ms)","trace[1922055153] 'compare' (duration: 45.322525ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:38:20.678767Z","caller":"traceutil/trace.go:171","msg":"trace[845342567] linearizableReadLoop","detail":"{readStateIndex:2428; appliedIndex:2427; }","duration":"227.732829ms","start":"2026-04-28T07:38:20.451Z","end":"2026-04-28T07:38:20.678733Z","steps":["trace[845342567] 'read index received' (duration: 227.361202ms)","trace[845342567] 'applied index is now lower than readState.Index' (duration: 370.617µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:38:20.67917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.145028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T07:38:20.679273Z","caller":"traceutil/trace.go:171","msg":"trace[1549871077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2338; }","duration":"228.260901ms","start":"2026-04-28T07:38:20.450983Z","end":"2026-04-28T07:38:20.679244Z","steps":["trace[1549871077] 'agreement among raft nodes before linearized reading' (duration: 227.922723ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:38:20.679874Z","caller":"traceutil/trace.go:171","msg":"trace[385323422] transaction","detail":"{read_only:false; response_revision:2338; number_of_response:1; }","duration":"301.782476ms","start":"2026-04-28T07:38:20.377029Z","end":"2026-04-28T07:38:20.678812Z","steps":["trace[385323422] 'process raft request' (duration: 301.44023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:38:20.680066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:38:20.376991Z","time spent":"302.96248ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:38:24.17843Z","caller":"traceutil/trace.go:171","msg":"trace[1266396707] transaction","detail":"{read_only:false; response_revision:2358; number_of_response:1; }","duration":"112.520029ms","start":"2026-04-28T07:38:24.065799Z","end":"2026-04-28T07:38:24.178319Z","steps":["trace[1266396707] 'process raft request' (duration: 57.916312ms)","trace[1266396707] 'compare' (duration: 54.226ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:38:24.377016Z","caller":"traceutil/trace.go:171","msg":"trace[1976778258] linearizableReadLoop","detail":"{readStateIndex:2451; appliedIndex:2450; }","duration":"120.810122ms","start":"2026-04-28T07:38:24.256137Z","end":"2026-04-28T07:38:24.376947Z","steps":["trace[1976778258] 'read index received' (duration: 30.17189ms)","trace[1976778258] 'applied index is now lower than readState.Index' (duration: 90.636132ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:38:24.377274Z","caller":"traceutil/trace.go:171","msg":"trace[1141849751] transaction","detail":"{read_only:false; response_revision:2360; number_of_response:1; }","duration":"190.111265ms","start":"2026-04-28T07:38:24.187116Z","end":"2026-04-28T07:38:24.377227Z","steps":["trace[1141849751] 'process raft request' (duration: 99.2927ms)","trace[1141849751] 'compare' (duration: 90.017929ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:38:24.377642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.479874ms","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:528"} {"level":"info","ts":"2026-04-28T07:38:24.377741Z","caller":"traceutil/trace.go:171","msg":"trace[1503090339] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2360; }","duration":"121.641387ms","start":"2026-04-28T07:38:24.256077Z","end":"2026-04-28T07:38:24.377718Z","steps":["trace[1503090339] 'agreement among raft nodes before linearized reading' (duration: 121.372652ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:38:29.701631Z","caller":"traceutil/trace.go:171","msg":"trace[929398555] transaction","detail":"{read_only:false; response_revision:2391; number_of_response:1; }","duration":"223.370025ms","start":"2026-04-28T07:38:29.478229Z","end":"2026-04-28T07:38:29.701599Z","steps":["trace[929398555] 'process raft request' (duration: 223.186342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:39:00.742704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.8973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-04-28T07:39:00.742804Z","caller":"traceutil/trace.go:171","msg":"trace[1373530901] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:2553; }","duration":"126.038123ms","start":"2026-04-28T07:39:00.616747Z","end":"2026-04-28T07:39:00.742785Z","steps":["trace[1373530901] 'range keys from in-memory index tree' (duration: 125.766457ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:39:01.135581Z","caller":"traceutil/trace.go:171","msg":"trace[1577831541] linearizableReadLoop","detail":"{readStateIndex:2654; appliedIndex:2653; }","duration":"198.558939ms","start":"2026-04-28T07:39:00.936998Z","end":"2026-04-28T07:39:01.135557Z","steps":["trace[1577831541] 'read index received' (duration: 198.424247ms)","trace[1577831541] 'applied index is now lower than readState.Index' (duration: 133.302µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:39:01.135659Z","caller":"traceutil/trace.go:171","msg":"trace[1979824182] transaction","detail":"{read_only:false; response_revision:2555; number_of_response:1; }","duration":"213.490971ms","start":"2026-04-28T07:39:00.922117Z","end":"2026-04-28T07:39:01.135608Z","steps":["trace[1979824182] 'process raft request' (duration: 213.295007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:39:01.135757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.726482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/default\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-04-28T07:39:01.135798Z","caller":"traceutil/trace.go:171","msg":"trace[1960608324] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/default; range_end:; response_count:1; response_revision:2555; }","duration":"198.833206ms","start":"2026-04-28T07:39:00.936955Z","end":"2026-04-28T07:39:01.135789Z","steps":["trace[1960608324] 'agreement among raft nodes before linearized reading' (duration: 198.719403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:39:01.137417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.851659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-28T07:39:01.137465Z","caller":"traceutil/trace.go:171","msg":"trace[2105029618] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:2556; }","duration":"191.91628ms","start":"2026-04-28T07:39:00.945536Z","end":"2026-04-28T07:39:01.137452Z","steps":["trace[2105029618] 'agreement among raft nodes before linearized reading' (duration: 191.768547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:39:01.137655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.692489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T07:39:01.137751Z","caller":"traceutil/trace.go:171","msg":"trace[340489355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2556; }","duration":"165.823391ms","start":"2026-04-28T07:39:00.971905Z","end":"2026-04-28T07:39:01.137729Z","steps":["trace[340489355] 'agreement among raft nodes before linearized reading' (duration: 165.563056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:39:01.137753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.406532ms","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-04-28T07:39:01.138035Z","caller":"traceutil/trace.go:171","msg":"trace[837307262] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2556; }","duration":"166.610906ms","start":"2026-04-28T07:39:00.971291Z","end":"2026-04-28T07:39:01.137902Z","steps":["trace[837307262] 'agreement among raft nodes before linearized reading' (duration: 166.168028ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:39:01.137846Z","caller":"traceutil/trace.go:171","msg":"trace[490901493] transaction","detail":"{read_only:false; response_revision:2556; number_of_response:1; }","duration":"109.859337ms","start":"2026-04-28T07:39:01.027975Z","end":"2026-04-28T07:39:01.137834Z","steps":["trace[490901493] 'process raft request' (duration: 109.250036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:39:01.13817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.439334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T07:39:01.138218Z","caller":"traceutil/trace.go:171","msg":"trace[1531523018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2556; }","duration":"162.487975ms","start":"2026-04-28T07:39:00.975718Z","end":"2026-04-28T07:39:01.138206Z","steps":["trace[1531523018] 'agreement among raft nodes before linearized reading' (duration: 162.339722ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:39:11.60303Z","caller":"traceutil/trace.go:171","msg":"trace[747776541] transaction","detail":"{read_only:false; response_revision:2611; number_of_response:1; }","duration":"168.974581ms","start":"2026-04-28T07:39:11.434019Z","end":"2026-04-28T07:39:11.602993Z","steps":["trace[747776541] 'process raft request' (duration: 168.752757ms)"],"step_count":1}